1506860 - Intermittent transform-3d/animate-preserve3d-parent.html == transform-3d/animate-preserve3d-ref.html | load failed: timed out waiting for reftest-wait to be removed

Reporter

Description

•

6 years ago

treeherder

Filed by: shindli [at] mozilla.com

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

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

https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://queue.taskcluster.net/v1/task/QRZqvBRsRDGA_RbuQT4xLA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

14:11:39     INFO - [Parent 6120, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/modules/libjar/nsJARChannel.cpp, line 1142
14:11:40     INFO - --DOMWINDOW == 13 (0B094C00) [pid = 4104] [serial = 163] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
14:11:40     INFO - --DOMWINDOW == 12 (0C42B800) [pid = 4104] [serial = 165] [outer = 00000000] [url = file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-cube-degrees-zoom-ref.html]
14:11:40     INFO - --DOMWINDOW == 11 (0C9B6400) [pid = 4104] [serial = 166] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
14:11:40     INFO - --DOMWINDOW == 10 (0BF64000) [pid = 4104] [serial = 167] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
14:11:40     INFO - --DOMWINDOW == 9 (0BF67C00) [pid = 4104] [serial = 162] [outer = 00000000] [url = file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-cube-degrees-ref.html]
14:11:40     INFO - --DOMWINDOW == 8 (0C427800) [pid = 4104] [serial = 164] [outer = 00000000] [url = file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-cube-degrees-zoom.html]
14:11:40     INFO - --DOMWINDOW == 7 (0C428000) [pid = 4104] [serial = 158] [outer = 00000000] [url = file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-cube-radians-zoom-ref.html]
14:11:40     INFO - --DOMWINDOW == 6 (0B094400) [pid = 4104] [serial = 160] [outer = 00000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
14:11:40     INFO - --DOMWINDOW == 5 (0C427400) [pid = 4104] [serial = 161] [outer = 00000000] [url = file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-cube-degrees.html]
14:14:19     INFO - [Parent 6120, Main Thread] WARNING: Suboptimal indexes for the SQL statement 0x142f7f10 (http://mzl.la/1FuID0j).: file z:/build/build/src/storage/mozStoragePrivateHelpers.cpp, line 114
14:16:31     INFO - REFTEST TEST-UNEXPECTED-FAIL | file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html == file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-ref.html | load failed: timed out waiting for reftest-wait to be removed
14:16:31     INFO - REFTEST INFO | Saved log: START file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] OnDocumentLoad triggering WaitForTestEnd
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] WaitForTestEnd: Adding listeners
14:16:31     INFO - REFTEST INFO | Saved log: Initializing canvas snapshot
14:16:31     INFO - REFTEST INFO | Saved log: DoDrawWindow 0,0,800,1000
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_TO_FIRE_INVALIDATE_EVENT
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: dispatching MozReftestInvalidate
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 58 19 265 260
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:31     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:31     INFO - REFTEST INFO | Saved log: DoDrawWindow 58,19,207,241
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 73 19 265 250
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:31     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:31     INFO - REFTEST INFO | Saved log: DoDrawWindow 73,19,192,231
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 0 rects
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:31     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 77 23 230 246
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:31     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:31     INFO - REFTEST INFO | Saved log: DoDrawWindow 77,23,153,223
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 77 23 230 246
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:31     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:31     INFO - REFTEST INFO | Saved log: DoDrawWindow 77,23,153,223
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 77 23 231 246
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:31     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:31     INFO - REFTEST INFO | Saved log: DoDrawWindow 77,23,154,223
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 78 23 231 246
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:31     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:31     INFO - REFTEST INFO | Saved log: DoDrawWindow 78,23,153,223
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 78 23 231 246
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:31     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:31     INFO - REFTEST INFO | Saved log: DoDrawWindow 78,23,153,223
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 78 23 231 246
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:31     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:31     INFO - REFTEST INFO | Saved log: DoDrawWindow 78,23,153,223
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 78 23 231 246
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:31     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:31     INFO - REFTEST INFO | Saved log: DoDrawWindow 78,23,153,223
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 78 23 231 246
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:31     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:31     INFO - REFTEST INFO | Saved log: DoDrawWindow 78,23,153,223
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 78 23 231 246
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:31     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:31     INFO - REFTEST INFO | Saved log: DoDrawWindow 78,23,153,223
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 78 23 232 246
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:31     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:31     INFO - REFTEST INFO | Saved log: DoDrawWindow 78,23,154,223
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 79 23 232 246
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:31     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:31     INFO - REFTEST INFO | Saved log: DoDrawWindow 79,23,153,223
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 79 23 232 246
14:16:31     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:31     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 79,23,153,223
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 79 23 232 246
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 79,23,153,223
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 79 23 232 246
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 79,23,153,223
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 79 23 232 246
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 79,23,153,223
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 79 23 232 245
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 79,23,153,222
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 79 23 233 245
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 79,23,154,222
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 80 23 233 245
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 80,23,153,222
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 80 23 233 245
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 80,23,153,222
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 80 23 233 245
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 80,23,153,222
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 80 23 233 245
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 80,23,153,222
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 80 23 233 245
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 80,23,153,222
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 80 23 234 245
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 80,23,154,222
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 81 23 234 245
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 81,23,153,222
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 81 23 234 245
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 81,23,153,222
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 81 23 234 245
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 81,23,153,222
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 81 23 234 245
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 81,23,153,222
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 81 23 234 245
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 81,23,153,222
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 81 23 234 245
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 81,23,153,222
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 81 23 235 245
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 81,23,154,222
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 82 23 235 244
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 82,23,153,221
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 82 23 235 244
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 82,23,153,221
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 82 23 235 244
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 82,23,153,221
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 82 23 235 244
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 82,23,153,221
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 82 23 235 244
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 82,23,153,221
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 82 23 235 244
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 82,23,153,221
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 83 23 236 244
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 83,23,153,221
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 83 23 236 244
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 83,23,153,221
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 83 22 236 244
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 83,22,153,222
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 83 22 236 244
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 83,22,153,222
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 83 22 236 244
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 83,22,153,222
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 83 22 236 244
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 83,22,153,222
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 83 22 236 244
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 83,22,153,222
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 84 22 237 244
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 84,22,153,222
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Z:/task_1542115196/build/tests/reftest/tests/layout/reftests/transform-3d/animate-preserve3d-parent.html
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 84 22 237 244
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] Test file chose to skip SynchronizeForSnapshot
14:16:32     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
14:16:32     INFO - REFTEST INFO | Saved log: DoDrawWindow 84,22,153,222
14:16:32     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL

Hiroyuki Ikezoe (:hiro)

Assignee

Updated

•

6 years ago

Depends on: 1506988

Andreea Pavel [:apavel]

Comment 1

•

6 years ago

Hi Emilio. The failure here was classified on the push for bug 1499170 can you please take a look?

Flags: needinfo?(emilio)

Comment hidden (Intermittent Failures Robot)

22 failures in 560 pushes (0.039 failures/push) were associated with this bug yesterday.

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

Platform breakdown:
* linux64: 2
* osx-10-10: 6
* windows7-32: 1
* linux32: 8
* linux64-ccov: 2
* android-em-4-3-armv7-api16: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1506860&startday=2018-11-14&endday=2018-11-14&tree=all

Emilio Cobos Álvarez (:emilio)

Comment 3

•

6 years ago

It's very weird that my patch caused this, since it was a performance optimization related to quirks mode, and this test isn't even using quirks-mode...

In any case looks like Hiro has a patch for bug 1506988.

Probably instead of my patch other changes related to compositor animations that landed about the same time caused this? Hiro, do you happen to know what caused this off-hand?

Flags: needinfo?(emilio) → needinfo?(hikezoe)

Hiroyuki Ikezoe (:hiro)

Assignee

Comment 4

•

6 years ago

Yeah.  I don't know which area your patch optimized, but these reftests start failing if some performance improvement happens unfortunately.  I actually made some performance improvement in bug 1504929, then animate-backface-hidden.html started failing permanently on MacOSX *opt* build (See bug 1504929 comment 15 for detail).  So my best guess is that bug 1504929 improved performance there to some extent, then your patch made improved more and more, then these tests fail more frequent.

Anyway I am pretty sure bug 1506988 should fix these failures completely, there has been an underlying issue for a long time (in the first place?).

Assigning to myself for now.

Assignee: nobody → hikezoe

Flags: needinfo?(hikezoe)

Comment hidden (Intermittent Failures Robot)

16 failures in 585 pushes (0.027 failures/push) were associated with this bug yesterday.

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

Platform breakdown:
* linux64: 2
* macosx64-qr: 1
* windows10-64-ccov: 1
* osx-10-10: 1
* linux32: 8
* android-em-4-3-armv7-api16: 1
* windows7-32: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1506860&startday=2018-11-16&endday=2018-11-16&tree=all

Andreea Pavel [:apavel]

Comment 6

•

6 years ago

Emilio, Hiro thanks for looking into this.

Comment hidden (Intermittent Failures Robot)

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

This is the #10 most frequent failure this week.

** This failure happened more than 75 times this week! Resolving this bug is a very high priority. ** 

** Try to resolve this bug as soon as possible. If unresolved for 1 week, the affected test(s) may be disabled. ** 

Repository breakdown:
* mozilla-central: 20
* autoland: 37
* mozilla-inbound: 17
* try: 5

Platform breakdown:
* windows7-32: 5
* linux64-ccov: 5
* macosx64-qr: 3
* linux64: 9
* windows10-64-ccov: 3
* osx-10-10: 18
* linux32: 24
* android-em-4-3-armv7-api16: 11
* linux64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1506860&startday=2018-11-12&endday=2018-11-18&tree=all

Hiroyuki Ikezoe (:hiro)

Assignee

Comment 8

•

6 years ago

Huh, bug 1506988 didn't fix this particular case.

Status: NEW → ASSIGNED

Flags: needinfo?(hikezoe)

Comment hidden (Intermittent Failures Robot)

24 failures in 386 pushes (0.062 failures/push) were associated with this bug yesterday.

Repository breakdown:
* mozilla-central: 3
* try: 8
* autoland: 8
* mozilla-inbound: 5

Platform breakdown:
* android-em-4-3-armv7-api16: 2
* windows10-64-qr: 1
* linux32: 5
* macosx64-qr: 3
* linux64-qr: 1
* windows10-64: 1
* linux64-ccov: 1
* linux64: 6
* osx-10-10: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1506860&startday=2018-11-22&endday=2018-11-22&tree=all

Hiroyuki Ikezoe (:hiro)

Assignee

Updated

•

6 years ago

Depends on: 1509746

Hiroyuki Ikezoe (:hiro)

Assignee

Comment 10

•

6 years ago

This is totally different from the issue fixed by bug 1506988. In this case the failure reason is bug 1509746 and I have confirmed that bug 1509746 also happens on Firefox 57.

What's going on in the failure case is that when a transform animation is initially painted it takes over 100ms for some reasons, thus an animationiteration event is NOT fired due to bug 1509746.

I think one of the reasons why this failure started happening recently is the change [1] for the reftest in question in bug 1504929.  Before the change the transform animation is initially created on an element with 'paused' state and after getting the animationstart event for the animation we change the playState to 'running', but after the change we do create the transform animation on running state in the first place.  I think the latter is bit heavier than the before one.  Though I don't still quite understand why animate-preserve3d-child.html has never failed, maybe the setup cost to painting the animations is cheaper?

Anyway, I am going to revert the workaround we did in bug 1504929.

https://treeherder.mozilla.org/#/jobs?repo=try&revision=0dfa04c66067ac1a0233b26eddf40548a40e56b7

[1] https://hg.mozilla.org/integration/autoland/rev/c84f0ad36ce9

No longer depends on: 1506988

Flags: needinfo?(hikezoe)

Hiroyuki Ikezoe (:hiro)

Assignee

Comment 11

•

6 years ago

Attached file Bug 1506860 - Revert the workaround that we did in bug 1504929. r=birtles — Details

That's because the underlying issue which had caused intermittent failure
has been fixed in 1506988.

Here is the link to the workaround commit;
https://hg.mozilla.org/mozilla-central/rev/c84f0ad36ce9

Phabricator Automation

Updated

•

6 years ago

Attachment #9027421 - Attachment description: Bug 1506860 - Revert the workaround that we did in Bug 1506860. r=birtles → Bug 1506860 - Revert the workaround that we did in Bug 1504929. r=birtles

Phabricator Automation

Updated

•

6 years ago

Attachment #9027421 - Attachment description: Bug 1506860 - Revert the workaround that we did in Bug 1504929. r=birtles → Bug 1506860 - Revert the workaround that we did in bug 1504929. r=birtles

Pulsebot

Comment 12

•

6 years ago

Pushed by hikezoe@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/995042d1cd8c
Revert the workaround that we did in bug 1504929. r=birtles

Comment hidden (Intermittent Failures Robot)

86 failures in 2387 pushes (0.036 failures/push) were associated with this bug in the last 7 days.

This is the #7 most frequent failure this week.

** This test has failed more than 150 times in the last 21 days. It should be disabled until it can be fixed. ** 

Repository breakdown:
* mozilla-inbound: 15
* autoland: 33
* try: 20
* mozilla-central: 18

Platform breakdown:
* android-em-4-3-armv7-api16: 18
* linux64: 14
* windows7-32: 6
* linux32: 13
* windows10-64-ccov: 1
* linux64-ccov: 7
* osx-10-10: 9
* macosx64-qr: 8
* windows10-64-qr: 2
* linux64-qr: 4
* windows10-64: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1506860&startday=2018-11-19&endday=2018-11-25&tree=all

Natalia Csoregi [:nataliaCs]

Comment 14

•

6 years ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/995042d1cd8c

Status: ASSIGNED → RESOLVED

Closed: 6 years ago

status-firefox65: --- → fixed

Resolution: --- → FIXED

Target Milestone: --- → mozilla65

Comment hidden (Intermittent Failures Robot)

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

Repository breakdown:
* try: 1

Platform breakdown:
* linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1506860&startday=2018-11-26&endday=2018-12-02&tree=all