Closed Bug 1411144 Opened 7 years ago Closed 4 years ago

Intermittent gfx/layers/apz/test/mochitest/test_bug1151667.html | Test timed out.

Categories

(Core :: Panning and Zooming, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE
mozilla61
Tracking Status
firefox61 --- fixed

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, Whiteboard: [gfx-noted])

Whiteboard: [gfx-noted]
Component: Graphics: Layers → Panning and Zooming
https://wiki.mozilla.org/Bugmasters#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=155587037&repo=mozilla-inbound&lineNumber=23418

10:58:54     INFO -  1793 INFO TEST-START | gfx/layers/apz/test/mochitest/test_bug1151663.html
10:58:54     INFO -  GECKO(5100) | ++DOMWINDOW == 7 (05E62800) [pid = 1464] [serial = 7] [outer = 0E270820]
10:58:55     INFO -  GECKO(5100) | ++DOMWINDOW == 8 (0E2B6C00) [pid = 1464] [serial = 8] [outer = 0E270820]
10:58:55     INFO -  GECKO(5100) | ++DOCSHELL 0E2BCC00 == 3 [pid = 1464] [id = {36b18dca-5a6a-47ef-bfb5-f73ff86825eb}]
10:58:55     INFO -  GECKO(5100) | ++DOMWINDOW == 9 (0E278DC0) [pid = 1464] [serial = 9] [outer = 00000000]
10:58:56     INFO -  GECKO(5100) | ++DOMWINDOW == 10 (0E2BD400) [pid = 1464] [serial = 10] [outer = 0E278DC0]
10:58:56     INFO -  GECKO(5100) | ++DOMWINDOW == 11 (0E2BDC00) [pid = 1464] [serial = 11] [outer = 0E278DC0]
10:58:56     INFO -  GECKO(5100) | ++DOCSHELL 0E2BFC00 == 4 [pid = 1464] [id = {03c6d056-09cd-4d01-b98c-c8445126ed62}]
10:58:56     INFO -  GECKO(5100) | ++DOMWINDOW == 12 (0D2B8160) [pid = 1464] [serial = 12] [outer = 00000000]
10:58:56     INFO -  GECKO(5100) | ++DOMWINDOW == 13 (0E2C0C00) [pid = 1464] [serial = 13] [outer = 0D2B8160]
10:58:56     INFO -  GECKO(5100) | ++DOMWINDOW == 14 (0E2C2C00) [pid = 1464] [serial = 14] [outer = 0D2B8160]
10:58:56     INFO -  GECKO(5100) | MEMORY STAT | vsize 521MB | vsizeMaxContiguous 918MB | residentFast 103MB | heapAllocated 23MB
10:58:56     INFO -  1794 INFO TEST-OK | gfx/layers/apz/test/mochitest/test_bug1151663.html | took 2218ms
10:58:56     INFO -  GECKO(5100) | ++DOMWINDOW == 15 (0F48D800) [pid = 1464] [serial = 15] [outer = 0E270820]
10:58:57     INFO -  1795 INFO None1796 INFO TEST-START | gfx/layers/apz/test/mochitest/test_bug1151667.html
10:58:57     INFO -  GECKO(5100) | ++DOMWINDOW == 16 (0E2BA400) [pid = 1464] [serial = 16] [outer = 0E270820]
10:58:57     INFO -  GECKO(5100) | [Child 1464, Main Thread] WARNING: Unable to find interface object on global: file z:/build/build/src/dom/base/nsDOMClassInfo.cpp, line 1745
10:58:57     INFO -  GECKO(5100) | Flushed APZ repaints, waiting for callback...
10:58:58     INFO -  GECKO(5100) | --DOCSHELL 10F28400 == 4 [pid = 5100] [id = {fe49b38d-591d-4013-9ac8-fe3e902718d9}]
10:58:59     INFO -  GECKO(5100) | --DOCSHELL 16C4B400 == 3 [pid = 5100] [id = {335ae4ea-6e50-44fa-84a6-a8c9d8f97806}]
10:59:01     INFO -  GECKO(5100) | --DOCSHELL 0615FC00 == 0 [pid = 920] [id = {e41fe130-17a6-4ce3-801e-a486ff0e0075}]
10:59:01     INFO -  GECKO(5100) | --DOMWINDOW == 15 (0D2EC800) [pid = 1464] [serial = 6] [outer = 00000000] [url = about:blank]
10:59:01     INFO -  GECKO(5100) | --DOMWINDOW == 14 (05E6D800) [pid = 1464] [serial = 2] [outer = 00000000] [url = about:blank]
10:59:01     INFO -  GECKO(5100) | --DOCSHELL 0E2BCC00 == 3 [pid = 1464] [id = {36b18dca-5a6a-47ef-bfb5-f73ff86825eb}]
10:59:04     INFO -  GECKO(5100) | --DOMWINDOW == 13 (0E278DC0) [pid = 1464] [serial = 9] [outer = 00000000] [url = http://mochi.test:8888/tests/gfx/layers/apz/test/mochitest/helper_bug1151663.html]
10:59:05     INFO -  GECKO(5100) | --DOMWINDOW == 10 (114F1800) [pid = 5100] [serial = 4] [outer = 00000000] [url = about:blank]
10:59:07     INFO -  GECKO(5100) | --DOMWINDOW == 9 (16B49CA0) [pid = 5100] [serial = 9] [outer = 00000000] [url = about:blank]
10:59:08     INFO -  GECKO(5100) | --DOMWINDOW == 12 (0E2BD400) [pid = 1464] [serial = 10] [outer = 00000000] [url = about:blank]
10:59:08     INFO -  GECKO(5100) | --DOMWINDOW == 11 (0F48D800) [pid = 1464] [serial = 15] [outer = 00000000] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html]
10:59:08     INFO -  GECKO(5100) | --DOMWINDOW == 10 (0E2B6C00) [pid = 1464] [serial = 8] [outer = 00000000] [url = http://mochi.test:8888/tests/gfx/layers/apz/test/mochitest/test_bug1151663.html]
10:59:08     INFO -  GECKO(5100) | --DOMWINDOW == 9 (05E62800) [pid = 1464] [serial = 7] [outer = 00000000] [url = about:blank]
10:59:08     INFO -  GECKO(5100) | --DOMWINDOW == 8 (0E2BDC00) [pid = 1464] [serial = 11] [outer = 00000000] [url = about:blank]
10:59:08     INFO -  GECKO(5100) | --DOMWINDOW == 7 (0E2C0C00) [pid = 1464] [serial = 13] [outer = 00000000] [url = about:blank]
10:59:08     INFO -  GECKO(5100) | --DOMWINDOW == 6 (05E62C00) [pid = 1464] [serial = 3] [outer = 00000000] [url = about:blank]
10:59:08     INFO -  GECKO(5100) | --DOMWINDOW == 2 (06161400) [pid = 920] [serial = 2] [outer = 00000000] [url = about:blank]
10:59:09     INFO -  GECKO(5100) | [DEBUG SHUTDOWN] Shutdown: decoder=0603ECC0 state machine=16294800
10:59:09     INFO -  GECKO(5100) | --DOMWINDOW == 1 (060913A0) [pid = 920] [serial = 1] [outer = 00000000] [url = chrome://gfxsanity/content/sanitytest.html]
10:59:09     INFO -  GECKO(5100) | [DEBUG SHUTDOWN] Enter: state machine=01306F90 reader=013F3F20
10:59:09     INFO -  GECKO(5100) | [DEBUG SHUTDOWN] Shutdown: reader=1620F000 shutdown demuxer=1624BF98
10:59:09     INFO -  GECKO(5100) | [DEBUG SHUTDOWN] Shutdown: pool=162EEC10 count=1
10:59:09     INFO -  GECKO(5100) | [DEBUG SHUTDOWN] operator (): pool=162EEC10 shutdown=true count=0
10:59:09     INFO -  GECKO(5100) | [DEBUG SHUTDOWN] TearDownDecoders: reader=1620F000 shut down audio task queue
10:59:09     INFO -  GECKO(5100) | [DEBUG SHUTDOWN] TearDownDecoders: reader=1620F000 shut down video task queue
10:59:09     INFO -  GECKO(5100) | [DEBUG SHUTDOWN] FinishShutdown: state machine=16294800
10:59:09     INFO -  GECKO(5100) | [DEBUG SHUTDOWN] Unregister: decoder=0603ECC0, count=0
10:59:10     INFO -  GECKO(5100) | [Parent 5100, StreamTrans #11] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/modules/libjar/nsJARChannel.cpp, line 433
10:59:11     INFO -  GECKO(5100) | --DOMWINDOW == 0 (16236400) [pid = 920] [serial = 3] [outer = 00000000] [url = chrome://gfxsanity/content/sanitytest.html]
10:59:13     INFO -  GECKO(5100) | --DOMWINDOW == 8 (16C4E000) [pid = 5100] [serial = 10] [outer = 00000000] [url = about:blank]
11:00:53     INFO -  GECKO(5100) | [Parent 5100, Lazy Idle] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/widget/windows/WinUtils.cpp, line 1483
11:02:53     INFO -  GECKO(5100) | [Parent 5100, Lazy Idle] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/widget/windows/WinUtils.cpp, line 1483
11:04:24     INFO -  TEST-INFO | started process screenshot
11:04:24     INFO -  TEST-INFO | screenshot: exit 0
11:04:24     INFO -  Buffered messages logged at 10:58:57
11:04:24     INFO -  1797 INFO must wait for load
11:04:24     INFO -  Buffered messages finished
11:04:24    ERROR -  1798 INFO TEST-UNEXPECTED-FAIL | gfx/layers/apz/test/mochitest/test_bug1151667.html | Test timed out.
11:04:24     INFO -      reportError@SimpleTest/TestRunner.js:121:7
11:04:24     INFO -      TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
11:04:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:04:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:04:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:04:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:04:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:04:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:04:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:04:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:04:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:04:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:04:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:04:24     INFO -      TestRunner.runTests@SimpleTest/TestRunner.js:380:5
11:04:24     INFO -      RunSet.runtests@SimpleTest/setup.js:194:3
11:04:24     INFO -      RunSet.runall@SimpleTest/setup.js:173:5
11:04:24     INFO -      hookupTests@SimpleTest/setup.js:266:5
11:04:24     INFO -  parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
11:04:24     INFO -  getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
11:04:24     INFO -  EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
11:04:24     INFO -      hookup@SimpleTest/setup.js:246:5
11:04:24     INFO -  EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Cgenericworker%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
11:04:25     INFO -  GECKO(5100) | MEMORY STAT | vsize 519MB | vsizeMaxContiguous 918MB | residentFast 33MB | heapAllocated 15MB
11:04:25     INFO -  1799 INFO TEST-OK | gfx/layers/apz/test/mochitest/test_bug1151667.html | took 328748ms
11:04:25     INFO -  GECKO(5100) | ++DOMWINDOW == 7 (05E62C00) [pid = 1464] [serial = 17] [outer = 0E270820]
11:04:25     INFO -  1800 INFO None1801 INFO TEST-START | gfx/layers/apz/test/mochitest/test_bug1253683.html
11:04:25     INFO -  GECKO(5100) | ++DOMWINDOW == 8 (05E6D800) [pid = 1464] [serial = 18] [outer = 0E270820]
11:04:26     INFO -  GECKO(5100) | [Child 1464, Main Thread] WARNING: Unable to find interface object on global: file z:/build/build/src/dom/base/nsDOMClassInfo.cpp, line 1745
11:04:26     INFO -  GECKO(5100) | Flushed APZ repaints, waiting for callback...
11:04:33     INFO -  GECKO(5100) | --DOMWINDOW == 7 (0E2BA400) [pid = 1464] [serial = 16] [outer = 00000000] [url = http://mochi.test:8888/tests/gfx/layers/apz/test/mochitest/test_bug1151667.html]
11:04:38     INFO -  GECKO(5100) | --DOMWINDOW == 6 (05E62C00) [pid = 1464] [serial = 17] [outer = 00000000] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html]
11:06:26     INFO -  GECKO(5100) | [Parent 5100, Lazy Idle] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/widget/windows/WinUtils.cpp, line 1483
11:07:26     INFO -  [6104, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file z:/build/build/src/toolkit/components/places/Database.cpp, line 751
11:07:26     INFO -  [6104, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file z:/build/build/src/toolkit/components/places/Database.cpp, line 585
11:07:26     INFO -  [6104, Main Thread] WARNING: Unable to get a connection to vacuum database: file z:/build/build/src/storage/VacuumManager.cpp, line 139
11:07:26     INFO -  [6104, Main Thread] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/dom/quota/ActorsParent.cpp, line 2664
11:07:26     INFO -  [6104, Main Thread] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/dom/quota/ActorsParent.cpp, line 2827
11:07:26     INFO -  [6104, IPDL Background] WARNING: '!quotaManager', file z:/build/build/src/dom/quota/ActorsParent.cpp, line 6462
11:08:26     INFO -  GECKO(5100) | [Parent 5100, Lazy Idle] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/widget/windows/WinUtils.cpp, line 1483
11:09:20     INFO -  GECKO(5100) | [Parent 5100, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80070057: file z:/build/build/src/netwerk/base/nsChannelClassifier.cpp, line 344
11:09:20     INFO -  GECKO(5100) | [Parent 5100, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80070057: file z:/build/build/src/netwerk/base/nsChannelClassifier.cpp, line 344
11:09:54     INFO -  Not taking screenshot here: see the one that was previously logged
11:09:54     INFO -  Buffered messages logged at 11:04:26
11:09:54     INFO -  1802 INFO TEST-PASS | gfx/layers/apz/test/mochitest/test_bug1253683.html | Initial scrollY should be 0
11:09:54     INFO -  1803 INFO TEST-PASS | gfx/layers/apz/test/mochitest/test_bug1253683.html | expected at least one paint
11:09:54     INFO -  1804 INFO TEST-PASS | gfx/layers/apz/test/mochitest/test_bug1253683.html | initially 'no_layer' should not be layerized
11:09:54     INFO -  Buffered messages finished
11:09:54    ERROR -  1805 INFO TEST-UNEXPECTED-FAIL | gfx/layers/apz/test/mochitest/test_bug1253683.html | Test timed out.
11:09:54     INFO -      reportError@SimpleTest/TestRunner.js:121:7
11:09:54     INFO -      TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
11:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:09:54     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:09:54     INFO -      TestRunner.runTests@SimpleTest/TestRunner.js:380:5
11:09:54     INFO -      RunSet.runtests@SimpleTest/setup.js:194:3
11:09:54     INFO -      RunSet.runall@SimpleTest/setup.js:173:5
11:09:54     INFO -      hookupTests@SimpleTest/setup.js:266:5
11:09:54     INFO -  parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
11:09:54     INFO -  getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
11:09:54     INFO -  EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
11:09:54     INFO -      hookup@SimpleTest/setup.js:246:5
11:09:54     INFO -  EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Cgenericworker%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
11:09:55     INFO -  GECKO(5100) | MEMORY STAT | vsize 519MB | vsizeMaxContiguous 918MB | residentFast 22MB | heapAllocated 16MB
11:09:55     INFO -  1806 INFO TEST-OK | gfx/layers/apz/test/mochitest/test_bug1253683.html | took 329959ms
11:09:55     INFO -  GECKO(5100) | ++DOMWINDOW == 7 (05E65000) [pid = 1464] [serial = 19] [outer = 0E270820]
11:09:55     INFO -  1807 INFO None1808 INFO TEST-START | gfx/layers/apz/test/mochitest/test_bug1277814.html
11:09:55     INFO -  GECKO(5100) | ++DOMWINDOW == 8 (05E62C00) [pid = 1464] [serial = 20] [outer = 0E270820]
11:09:56     INFO -  GECKO(5100) | [Child 1464, Main Thread] WARNING: Unable to find interface object on global: file z:/build/build/src/dom/base/nsDOMClassInfo.cpp, line 1745
11:09:56     INFO -  GECKO(5100) | Flushed APZ repaints, waiting for callback...
11:09:56     INFO -  GECKO(5100) | Flushed APZ repaints, waiting for callback...
11:10:01     INFO -  GECKO(5100) | --DOMWINDOW == 7 (05E6D800) [pid = 1464] [serial = 18] [outer = 00000000] [url = http://mochi.test:8888/tests/gfx/layers/apz/test/mochitest/test_bug1253683.html]
11:10:08     INFO -  GECKO(5100) | --DOMWINDOW == 6 (05E65000) [pid = 1464] [serial = 19] [outer = 00000000] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html]
11:11:56     INFO -  GECKO(5100) | [Parent 5100, Lazy Idle] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/widget/windows/WinUtils.cpp, line 1483
11:13:56     INFO -  GECKO(5100) | [Parent 5100, Lazy Idle] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/widget/windows/WinUtils.cpp, line 1483
11:15:24     INFO -  Not taking screenshot here: see the one that was previously logged
11:15:24    ERROR -  1809 INFO TEST-UNEXPECTED-FAIL | gfx/layers/apz/test/mochitest/test_bug1277814.html | Test timed out.
11:15:24     INFO -      reportError@SimpleTest/TestRunner.js:121:7
11:15:24     INFO -      TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
11:15:24     INFO -      TestRunner.runTests@SimpleTest/TestRunner.js:380:5
11:15:24     INFO -      RunSet.runtests@SimpleTest/setup.js:194:3
11:15:24     INFO -      RunSet.runall@SimpleTest/setup.js:173:5
11:15:24     INFO -      hookupTests@SimpleTest/setup.js:266:5
11:15:24     INFO -  parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
11:15:24     INFO -  getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
11:15:24     INFO -  EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
11:15:24     INFO -      hookup@SimpleTest/setup.js:246:5
11:15:24     INFO -  EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Cgenericworker%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
11:15:25     INFO -  GECKO(5100) | MEMORY STAT | vsize 519MB | vsizeMaxContiguous 918MB | residentFast 24MB | heapAllocated 16MB
11:15:25     INFO -  1810 INFO TEST-OK | gfx/layers/apz/test/mochitest/test_bug1277814.html | took 330145ms
11:15:26     INFO -  GECKO(5100) | ++DOMWINDOW == 7 (05E6D800) [pid = 1464] [serial = 21] [outer = 0E270820]
11:15:26     INFO -  1811 INFO None1812 INFO TEST-START | gfx/layers/apz/test/mochitest/test_bug1304689-2.html
11:15:26     INFO -  GECKO(5100) | ++DOMWINDOW == 8 (05E6C800) [pid = 1464] [serial = 22] [outer = 0E270820]
11:15:26     INFO -  GECKO(5100) | [Child 1464, Main Thread] WARNING: Unable to find interface object on global: file z:/build/build/src/dom/base/nsDOMClassInfo.cpp, line 1745
11:15:26     INFO -  GECKO(5100) | Flushed APZ repaints, waiting for callback...
11:15:26     INFO -  GECKO(5100) | Flushed APZ repaints, waiting for callback...
11:15:26     INFO -  GECKO(5100) | Flushed APZ repaints, waiting for callback...
11:15:26     INFO -  GECKO(5100) | MEMORY STAT | vsize 519MB | vsizeMaxContiguous 918MB | residentFast 54MB | heapAllocated 16MB
11:15:26     INFO -  1813 INFO TEST-OK | gfx/layers/apz/test/mochitest/test_bug1304689-2.html | took 452ms
11:15:26     INFO -  GECKO(5100) | ++DOMWINDOW == 9 (0E2B9C00) [pid = 1464] [serial = 23] [outer = 0E270820]
11:15:26     INFO -  1814 INFO None1815 INFO TEST-START | gfx/layers/apz/test/mochitest/test_bug1304689.html
11:15:26     INFO -  GECKO(5100) | ++DOMWINDOW == 10 (05E6DC00) [pid = 1464] [serial = 24] [outer = 0E270820]
11:15:26     INFO -  GECKO(5100) | [Child 1464, Main Thread] WARNING: Unable to find interface object on global: file z:/build/build/src/dom/base/nsDOMClassInfo.cpp, line 1745
11:15:26     INFO -  GECKO(5100) | Flushed APZ repaints, waiting for callback...
11:15:26     INFO -  GECKO(5100) | Flushed APZ repaints, waiting for callback...
11:15:26     INFO -  GECKO(5100) | Flushed APZ repaints, waiting for callback...
11:15:26     INFO -  GECKO(5100) | MEMORY STAT | vsize 519MB | vsizeMaxContiguous 918MB | residentFast 57MB | heapAllocated 17MB
11:15:26     INFO -  1816 INFO TEST-OK | gfx/layers/apz/test/mochitest/test_bug1304689.html | took 330ms
11:15:26     INFO -  GECKO(5100) | ++DOMWINDOW == 11 (05E65000) [pid = 1464] [serial = 25] [outer = 0E270820]
11:15:26     INFO -  1817 INFO None1818 INFO TEST-START | gfx/layers/apz/test/mochitest/test_bug982141.html
11:15:26     INFO -  GECKO(5100) | ++DOMWINDOW == 12 (05E67400) [pid = 1464] [serial = 26] [outer = 0E270820]
11:15:27     INFO -  GECKO(5100) | ++DOCSHELL 0F491800 == 4 [pid = 1464] [id = {acece301-f0bd-4806-aa26-4d2c0544652f}]
11:15:27     INFO -  GECKO(5100) | ++DOMWINDOW == 13 (00F73B80) [pid = 1464] [serial = 27] [outer = 00000000]
11:15:27     INFO -  GECKO(5100) | ++DOMWINDOW == 14 (0F493800) [pid = 1464] [serial = 28] [outer = 00F73B80]
11:15:27     INFO -  GECKO(5100) | ++DOMWINDOW == 15 (0FB3A000) [pid = 1464] [serial = 29] [outer = 00F73B80]
11:15:27     INFO -  GECKO(5100) | --DOMWINDOW == 14 (05E62C00) [pid = 1464] [serial = 20] [outer = 00000000] [url = http://mochi.test:8888/tests/gfx/layers/apz/test/mochitest/test_bug1277814.html]
11:15:27     INFO -  GECKO(5100) | MEMORY STAT | vsize 529MB | vsizeMaxContiguous 918MB | residentFast 71MB | heapAllocated 19MB
11:15:27     INFO -  1819 INFO TEST-OK | gfx/layers/apz/test/mochitest/test_bug982141.html | took 742ms
11:15:27     INFO -  GECKO(5100) | ++DOMWINDOW == 15 (05E62C00) [pid = 1464] [serial = 30] [outer = 0E270820]
11:15:27     INFO -  1820 INFO None1821 INFO TEST-START | gfx/layers/apz/test/mochitest/test_frame_reconstruction.html
11:15:27     INFO -  GECKO(5100) | ++DOMWINDOW == 16 (0E2B9800) [pid = 1464] [serial = 31] [outer = 0E270820]
11:15:27     INFO -  GECKO(5100) | [Child 1464, Main Thread] WARNING: Unable to find interface object on global: file z:/build/build/src/dom/base/nsDOMClassInfo.cpp, line 1745
11:15:28     INFO -  GECKO(5100) | Flushed APZ repaints, waiting for callback...
11:15:28     INFO -  GECKO(5100) | Flushed APZ repaints, waiting for callback...
11:15:28     INFO -  GECKO(5100) | Flushed APZ repaints, waiting for callback...
11:15:28     INFO -  Not taking screenshot here: see the one that was previously logged
11:15:28     INFO -  Buffered messages logged at 11:15:28
11:15:28     INFO -  1822 INFO must wait for load
11:15:28     INFO -  Buffered messages finished
11:15:28    ERROR -  1823 INFO TEST-UNEXPECTED-FAIL | gfx/layers/apz/test/mochitest/test_frame_reconstruction.html | APZ animation in progress - scrollTop is now 0
11:15:28     INFO -      test@gfx/layers/apz/test/mochitest/test_frame_reconstruction.html:174:3
11:15:28     INFO -      driveTest@gfx/layers/apz/test/mochitest/apz_test_utils.js:305:19
11:15:28     INFO -      setTimeout handler*SimpleTest_setTimeoutShim@SimpleTest/SimpleTest.js:676:12
11:15:28     INFO -      repaintDone@gfx/layers/apz/test/mochitest/apz_test_utils.js:124:5
11:15:28     INFO -  1824 INFO TEST-PASS | gfx/layers/apz/test/mochitest/test_frame_reconstruction.html | APZ animation not yet completed - scrollTop is now 0
11:15:28     INFO -  GECKO(5100) | Flushed APZ repaints, waiting for callback...
11:15:28     INFO -  1825 INFO TEST-PASS | gfx/layers/apz/test/mochitest/test_frame_reconstruction.html | APZ animation not yet completed - scrollTop is now 0
11:15:28     INFO -  Not taking screenshot here: see the one that was previously logged
11:15:28    ERROR -  1826 INFO TEST-UNEXPECTED-FAIL | gfx/layers/apz/test/mochitest/test_frame_reconstruction.html | Frame reconstruction triggered - reconstruction triggered 0 times
11:15:28     INFO -      test@gfx/layers/apz/test/mochitest/test_frame_reconstruction.html:195:3
11:15:28     INFO -      driveTest@gfx/layers/apz/test/mochitest/apz_test_utils.js:305:19
11:15:28     INFO -      setTimeout handler*SimpleTest_setTimeoutShim@SimpleTest/SimpleTest.js:676:12
11:15:28     INFO -      repaintDone@gfx/layers/apz/test/mochitest/apz_test_utils.js:124:5
11:15:28     INFO -  GECKO(5100) | Flushed APZ repaints, waiting for callback...
11:15:28     INFO -  Not taking screenshot here: see the one that was previously logged
11:15:28    ERROR -  1827 INFO TEST-UNEXPECTED-FAIL | gfx/layers/apz/test/mochitest/test_frame_reconstruction.html | Element should have scrolled by 200px - got +0, expected 200
11:15:28     INFO -      SimpleTest.is@SimpleTest/SimpleTest.js:312:5
11:15:28     INFO -      test@gfx/layers/apz/test/mochitest/test_frame_reconstruction.html:205:3
11:15:28     INFO -      driveTest@gfx/layers/apz/test/mochitest/apz_test_utils.js:305:19
11:15:28     INFO -      setTimeout handler*SimpleTest_setTimeoutShim@SimpleTest/SimpleTest.js:676:12
11:15:28     INFO -      repaintDone@gfx/layers/apz/test/mochitest/apz_test_utils.js:124:5
11:15:28     INFO -  GECKO(5100) | MEMORY STAT | vsize 520MB | vsizeMaxContiguous 918MB | residentFast 63MB | heapAllocated 18MB
11:15:28     INFO -  1828 INFO TEST-OK | gfx/layers/apz/test/mochitest/test_frame_reconstruction.html | took 495ms
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
There are 30 failures on this bug over the last 7 days, these happen on linux64-qr and windows10-64

Here is the most recent log example: https://treeherder.mozilla.org/logviewer.html#?repo=autoland&job_id=174550817&lineNumber=6509

Here is a relevant part of that log: 

[task 2018-04-19T14:38:23.287Z] 14:38:23     INFO - TEST-START | gfx/layers/apz/test/mochitest/test_bug1151667.html
[task 2018-04-19T14:38:23.594Z] 14:38:23     INFO - GECKO(5555) | Parent process: flushed APZ repaints, waiting for callback...
[task 2018-04-19T14:38:23.700Z] 14:38:23     INFO - GECKO(5555) | Flushed APZ repaints, waiting for callback...
[task 2018-04-19T14:43:51.508Z] 14:43:51     INFO - TEST-INFO | started process screentopng
[task 2018-04-19T14:43:51.792Z] 14:43:51     INFO - TEST-INFO | screentopng: exit 0
[task 2018-04-19T14:43:51.792Z] 14:43:51     INFO - TEST-UNEXPECTED-FAIL | gfx/layers/apz/test/mochitest/test_bug1151667.html | Test timed out. 
[task 2018-04-19T14:43:51.793Z] 14:43:51     INFO -     reportError@SimpleTest/TestRunner.js:121:7
[task 2018-04-19T14:43:51.794Z] 14:43:51     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
[task 2018-04-19T14:43:51.795Z] 14:43:51     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-04-19T14:43:51.795Z] 14:43:51     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-04-19T14:43:51.796Z] 14:43:51     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-04-19T14:43:51.797Z] 14:43:51     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-04-19T14:43:51.798Z] 14:43:51     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-04-19T14:43:51.798Z] 14:43:51     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-04-19T14:43:51.799Z] 14:43:51     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-04-19T14:43:51.800Z] 14:43:51     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-04-19T14:43:51.800Z] 14:43:51     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-04-19T14:43:51.801Z] 14:43:51     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-04-19T14:43:51.802Z] 14:43:51     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-04-19T14:43:51.802Z] 14:43:51     INFO -     TestRunner.runTests@SimpleTest/TestRunner.js:380:5
Flags: needinfo?(bugmail)
Thanks, I'm investigating.
Assignee: nobody → bugmail
Flags: needinfo?(bugmail)
Based on the log at https://treeherder.mozilla.org/logviewer.html#?job_id=174620005&repo=try&lineNumber=56615 it looks like the wheel event that we dispatch from the test is hitting the wrong thing. It's picking up the APZC for the RCD rather than the APZC for the subframe. The coordinates of the wheel event seem ok because I see the main-thread content response is targeting the subframe as intended. So this is an issue with the WR hit-testing producing the wrong result. Also for the record the HitTestingTree for this test doesn't really look like what the test intends, I think. Probably because with QR we just slap together a fake scrolldata tree based on the display list.

Still thinking about if I should disable this test temporarily or if there's something I can do now to fix it. I expect the behaviour to change once async scene building is enabled in CI.
I'm going to disable this test on QR temporarily. For one thing I'm not really convinced it's testing the thing it's supposed to be testing, because with QR the "layer tree" is somewhat different. Also in this test the hit-test doesn't occur during an async transform so it's not clear to me why it wouldn't work - this will need more detailed investigation but I don't want to do that until after I have async scene building on by default in CI.
Pushed by kgupta@mozilla.com:
https://hg.mozilla.org/integration/mozilla-inbound/rev/05476b05d168
Temporarily disable a high-frequency intermittent failure for webrender. r=me
https://hg.mozilla.org/mozilla-central/rev/05476b05d168
Status: REOPENED → RESOLVED
Closed: 7 years ago6 years ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla61
This is still occurring: https://treeherder.mozilla.org/logviewer.html#?job_id=176630349&repo=mozilla-inbound&lineNumber=6634

20:53:01     INFO -  1185 INFO TEST-START | gfx/layers/apz/test/mochitest/test_bug1151663.html
20:53:02     INFO -  GECKO(5040) | Parent process: flushed APZ repaints, waiting for callback...
20:53:02     INFO -  GECKO(5040) | Flushed APZ repaints, waiting for callback...
20:53:02     INFO -  GECKO(5040) | Forcing a paint since none was pending already...
20:53:02     INFO -  GECKO(5040) | Flushed APZ repaints, waiting for callback...
20:53:02     INFO -  GECKO(5040) | MEMORY STAT | vsize 2098586MB | vsizeMaxContiguous 130112672MB | residentFast 100MB | heapAllocated 23MB
20:53:02     INFO -  1186 INFO TEST-OK | gfx/layers/apz/test/mochitest/test_bug1151663.html | took 1434ms
20:53:02     INFO -  1187 INFO TEST-START | gfx/layers/apz/test/mochitest/test_bug1151667.html
20:53:03     INFO -  GECKO(5040) | Parent process: flushed APZ repaints, waiting for callback...
20:53:03     INFO -  GECKO(5040) | Flushed APZ repaints, waiting for callback...
20:58:31     INFO -  TEST-INFO | started process screenshot
20:58:31     INFO -  TEST-INFO | screenshot: exit 0
20:58:31     INFO -  Buffered messages logged at 20:53:03
20:58:31     INFO -  1188 INFO must wait for load
20:58:31     INFO -  Buffered messages finished
20:58:31    ERROR -  1189 INFO TEST-UNEXPECTED-FAIL | gfx/layers/apz/test/mochitest/test_bug1151667.html | Test timed out.
20:58:31     INFO -      reportError@SimpleTest/TestRunner.js:121:7
20:58:31     INFO -      TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
20:58:31     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
20:58:31     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
20:58:31     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
20:58:31     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
20:58:31     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
20:58:31     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
20:58:31     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
20:58:31     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
20:58:31     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
20:58:31     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
20:58:31     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
20:58:31     INFO -      TestRunner.runTests@SimpleTest/TestRunner.js:380:5
20:58:31     INFO -      RunSet.runtests@SimpleTest/setup.js:194:3
20:58:31     INFO -      RunSet.runall@SimpleTest/setup.js:173:5
20:58:31     INFO -      hookupTests@SimpleTest/setup.js:266:5
20:58:31     INFO -  parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
20:58:31     INFO -  getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
20:58:31     INFO -  EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
20:58:31     INFO -      hookup@SimpleTest/setup.js:246:5
20:58:31     INFO -  EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1525293187%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
20:58:32     INFO -  GECKO(5040) | MEMORY STAT | vsize 2098579MB | vsizeMaxContiguous 130112672MB | residentFast 92MB | heapAllocated 16MB
20:58:32     INFO -  1190 INFO TEST-OK | gfx/layers/apz/test/mochitest/test_bug1151667.html | took 329586ms
20:58:32     INFO -  1191 INFO TEST-START | gfx/layers/apz/test/mochitest/test_bug1253683.html
20:58:32     INFO -  GECKO(5040) | Parent process: flushed APZ repaints, waiting for callback...
20:58:32     INFO -  GECKO(5040) | Flushed APZ repaints, waiting for callback...
21:04:01     INFO -  Not taking screenshot here: see the one that was previously logged
21:04:01     INFO -  Buffered messages logged at 20:58:32
21:04:01     INFO -  1192 INFO TEST-PASS | gfx/layers/apz/test/mochitest/test_bug1253683.html | Initial scrollY should be 0
21:04:01     INFO -  1193 INFO TEST-PASS | gfx/layers/apz/test/mochitest/test_bug1253683.html | expected at least one paint
21:04:01     INFO -  1194 INFO TEST-PASS | gfx/layers/apz/test/mochitest/test_bug1253683.html | initially 'no_layer' should not be layerized
21:04:01     INFO -  Buffered messages finished
21:04:01    ERROR -  1195 INFO TEST-UNEXPECTED-FAIL | gfx/layers/apz/test/mochitest/test_bug1253683.html | Test timed out.
21:04:01     INFO -      reportError@SimpleTest/TestRunner.js:121:7
21:04:01     INFO -      TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
21:04:01     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:04:01     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:04:01     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:04:01     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:04:01     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:04:01     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:04:01     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:04:01     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:04:01     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:04:01     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:04:01     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:04:01     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:04:01     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:04:01     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:04:01     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:04:01     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:04:01     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:04:01     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:04:01     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:04:01     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:04:01     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:04:01     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:04:01     INFO -      TestRunner.runTests@SimpleTest/TestRunner.js:380:5
21:04:01     INFO -      RunSet.runtests@SimpleTest/setup.js:194:3
21:04:01     INFO -      RunSet.runall@SimpleTest/setup.js:173:5
21:04:01     INFO -      hookupTests@SimpleTest/setup.js:266:5
21:04:01     INFO -  parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
21:04:01     INFO -  getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
21:04:01     INFO -  EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
21:04:01     INFO -      hookup@SimpleTest/setup.js:246:5
21:04:01     INFO -  EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1525293187%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
21:04:02     INFO -  GECKO(5040) | MEMORY STAT | vsize 2098577MB | vsizeMaxContiguous 130112672MB | residentFast 90MB | heapAllocated 16MB
21:04:02     INFO -  1196 INFO TEST-OK | gfx/layers/apz/test/mochitest/test_bug1253683.html | took 330044ms
21:04:02     INFO -  1197 INFO TEST-START | gfx/layers/apz/test/mochitest/test_bug1277814.html
21:04:02     INFO -  GECKO(5040) | Parent process: flushed APZ repaints, waiting for callback...
21:04:02     INFO -  GECKO(5040) | Flushed APZ repaints, waiting for callback...
21:04:02     INFO -  GECKO(5040) | Flushed APZ repaints, waiting for callback...
21:09:31     INFO -  Not taking screenshot here: see the one that was previously logged

:kats can you take a look?
21:09:31    ERROR -  1198 INFO TEST-UNEXPECTED-FAIL | gfx/layers/apz/test/mochitest/test_bug1277814.html | Test timed out.
I disabled it for webrender, but the test was failing intermittently even on non-webrender platforms. Really I should have landed the patch to disable it in a different bug that this one depends on. But we can just reopen this bug and keep using it to track the continuing failures.
Status: RESOLVED → REOPENED
Resolution: FIXED → ---
Assignee: bugmail → nobody
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: REOPENED → RESOLVED
Closed: 6 years ago6 years ago
Resolution: --- → INCOMPLETE
Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=204490527&repo=autoland&lineNumber=6040

INFO - TEST-START | gfx/layers/apz/test/mochitest/test_bug1151667.html
12:30:14     INFO - GECKO(6240) | Parent process: flushed APZ repaints, waiting for callback...
12:35:42     INFO - TEST-INFO | started process screenshot
12:35:43     INFO - TEST-INFO | screenshot: exit 0
12:35:43     INFO - TEST-UNEXPECTED-FAIL | gfx/layers/apz/test/mochitest/test_bug1151667.html | Test timed out. 
12:35:43     INFO -     reportError@SimpleTest/TestRunner.js:121:7
12:35:43     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
12:35:43     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:35:43     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:35:43     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:35:43     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:35:43     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:35:43     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:35:43     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:35:43     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:35:43     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:35:43     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:35:43     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
12:35:43     INFO -     TestRunner.runTests/<@SimpleTest/TestRunner.js:381:9
12:35:43     INFO -     promise callback*TestRunner.runTests@SimpleTest/TestRunner.js:368:50
12:35:43     INFO -     RunSet.runtests@SimpleTest/setup.js:194:3
12:35:43     INFO -     RunSet.runall@SimpleTest/setup.js:173:5
12:35:43     INFO -     hookupTests@SimpleTest/setup.js:266:5
12:35:43     INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
12:35:43     INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
12:35:43     INFO - EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
12:35:43     INFO -     hookup@SimpleTest/setup.js:246:5
12:35:43     INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1539173445%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
12:35:43     INFO - GECKO(6240) | MEMORY STAT | vsize 1494MB | vsizeMaxContiguous 132000766MB | residentFast 92MB | heapAllocated 21MB
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 6 years ago5 years ago
Resolution: --- → DUPLICATE
Status: RESOLVED → REOPENED
Resolution: DUPLICATE → ---
Status: REOPENED → RESOLVED
Closed: 5 years ago5 years ago
Resolution: --- → INCOMPLETE
Status: REOPENED → RESOLVED
Closed: 5 years ago5 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 5 years ago5 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 5 years ago4 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.