Closed Bug 1489078 Opened 6 years ago Closed 6 years ago

Intermittent bugs/613433-1.html == bugs/613433-1-ref.html | load failed: timed out waiting for pending paint count to reach zero (after reftest-wait removed and switch to print mode) (waiting for MozAfterPaint)

Categories

(Core :: Layout, defect, P5)

Product:
Core
Component:
Layout
Type:
defect

Tracking

()

Status:
RESOLVED DUPLICATE of bug 1490117

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure) 

Filed by: ccoroiu [at] mozilla.com

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

https://queue.taskcluster.net/v1/task/cqmxXHk8T0O9HlGH_VSQRg/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/cqmxXHk8T0O9HlGH_VSQRg/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1&only_show_unexpected=1

05:55:58     INFO - REFTEST TEST-START | file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/613433-1.html == file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/613433-1-ref.html
05:55:58     INFO - REFTEST TEST-LOAD | file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/613433-1.html | 1590 / 2044 (77%)
05:55:58     INFO - ++DOMWINDOW == 43 (000002332626D800) [pid = 7516] [serial = 4570] [outer = 000002332644C800]
05:55:58     INFO - [Child 7516, Main Thread] WARNING: HTMLEditRules::BeforeEdit() failed to handle something: 'NS_SUCCEEDED(rv)', file z:/build/build/src/editor/libeditor/HTMLEditor.cpp, line 3619
05:55:58     INFO - [Child 7516, Main Thread] WARNING: '!aSelection->RangeCount()', file z:/build/build/src/editor/libeditor/EditorBase.cpp, line 3912
05:55:58     INFO - [Child 7516, Main Thread] WARNING: '!selectionStartPoint.IsSet()', file z:/build/build/src/editor/libeditor/HTMLEditRules.cpp, line 10245
05:55:58     INFO - [Child 7516, Main Thread] WARNING: Failed to normalize Selection: 'NS_SUCCEEDED(rv)', file z:/build/build/src/editor/libeditor/HTMLEditRules.cpp, line 470
05:55:58     INFO - [Child 7516, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file z:/build/build/src/dom/base/nsContentUtils.cpp, line 4015
05:55:59     INFO - REFTEST TEST-LOAD | file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/613433-1-ref.html | 1590 / 2044 (77%)
05:55:59     INFO - ++DOMWINDOW == 44 (0000023326271C00) [pid = 7516] [serial = 4571] [outer = 000002332644C800]
05:55:59     INFO - [Child 7516, Main Thread] WARNING: HTMLEditRules::BeforeEdit() failed to handle something: 'NS_SUCCEEDED(rv)', file z:/build/build/src/editor/libeditor/HTMLEditor.cpp, line 3619
05:55:59     INFO - [Child 7516, Main Thread] WARNING: '!aSelection->RangeCount()', file z:/build/build/src/editor/libeditor/EditorBase.cpp, line 3912
05:55:59     INFO - [Child 7516, Main Thread] WARNING: '!selectionStartPoint.IsSet()', file z:/build/build/src/editor/libeditor/HTMLEditRules.cpp, line 10245
05:55:59     INFO - [Child 7516, Main Thread] WARNING: Failed to normalize Selection: 'NS_SUCCEEDED(rv)', file z:/build/build/src/editor/libeditor/HTMLEditRules.cpp, line 470
05:55:59     INFO - [Child 7516, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file z:/build/build/src/dom/base/nsContentUtils.cpp, line 4015
05:55:59     INFO - --DOMWINDOW == 43 (0000023328E46800) [pid = 7516] [serial = 4553] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/607267-1.html]
05:55:59     INFO - --DOMWINDOW == 42 (000002332782DC00) [pid = 7516] [serial = 4544] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/604737.html]
05:55:59     INFO - --DOMWINDOW == 41 (0000023326966C00) [pid = 7516] [serial = 4554] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/607267-1-ref.html]
05:55:59     INFO - --DOMWINDOW == 40 (0000023328E44000) [pid = 7516] [serial = 4552] [outer = 0000000000000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
05:55:59     INFO - --DOMWINDOW == 39 (0000023328A4BC00) [pid = 7516] [serial = 4546] [outer = 0000000000000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
05:55:59     INFO - --DOMWINDOW == 38 (0000023316879000) [pid = 7516] [serial = 4551] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/605157-1-ref.xhtml]
05:55:59     INFO - --DOMWINDOW == 37 (000002332626D400) [pid = 7516] [serial = 4545] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/604737-ref.html]
05:55:59     INFO - --DOMWINDOW == 36 (0000023328E3C000) [pid = 7516] [serial = 4549] [outer = 0000000000000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
05:55:59     INFO - --DOMWINDOW == 35 (000002331C0A6C00) [pid = 7516] [serial = 4550] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/605157-1.xhtml]
05:55:59     INFO - --DOMWINDOW == 34 (000002331C0A8000) [pid = 7516] [serial = 4548] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/605138-1-ref.html]
05:56:04     INFO - --DOMWINDOW == 33 (0000023328E3E400) [pid = 7516] [serial = 4560] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/608756-1-ref.html]
05:56:04     INFO - --DOMWINDOW == 32 (0000023328E3DC00) [pid = 7516] [serial = 4559] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/608756-1a.html]
05:56:04     INFO - --DOMWINDOW == 31 (0000023328E4AC00) [pid = 7516] [serial = 4563] [outer = 0000000000000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
05:56:04     INFO - --DOMWINDOW == 30 (0000023326479400) [pid = 7516] [serial = 4555] [outer = 0000000000000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
05:56:04     INFO - --DOMWINDOW == 29 (0000023328E43800) [pid = 7516] [serial = 4561] [outer = 0000000000000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
05:56:04     INFO - --DOMWINDOW == 28 (0000023328C46400) [pid = 7516] [serial = 4556] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/608636-1.html]
05:56:04     INFO - --DOMWINDOW == 27 (0000023328E47000) [pid = 7516] [serial = 4562] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/608756-1b.html]
05:56:04     INFO - --DOMWINDOW == 26 (000002332949F400) [pid = 7516] [serial = 4564] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/608756-2.html]
05:56:04     INFO - --DOMWINDOW == 25 (000002331C0A7400) [pid = 7516] [serial = 4557] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/608636-1-ref.html]
05:56:04     INFO - --DOMWINDOW == 24 (0000023328C4CC00) [pid = 7516] [serial = 4558] [outer = 0000000000000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
05:56:04     INFO - --DOMWINDOW == 23 (0000023327823800) [pid = 7516] [serial = 4565] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/608756-2-ref.html]
05:56:13     INFO - --DOMWINDOW == 22 (0000023328A51000) [pid = 7516] [serial = 4567] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/609272-1.html]
05:56:13     INFO - --DOMWINDOW == 21 (000002332647B400) [pid = 7516] [serial = 4566] [outer = 0000000000000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
05:56:26     INFO - [NPAPI 3656, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
05:56:26     INFO - [NPAPI 3656, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
05:56:26     INFO - [Parent 6768, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
05:56:26     INFO - [NPAPI 3656, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
05:56:26     INFO - [Parent 6768, Main Thread] WARNING: '!aObserver', file z:/build/build/src/xpcom/ds/nsObserverService.cpp, line 243
05:56:26     INFO - nsStringStats
05:56:26     INFO -  => mAllocCount:            248
05:56:26     INFO -  => mReallocCount:            0
05:56:26     INFO -  => mFreeCount:             248
05:56:26     INFO -  => mShareCount:            369
05:56:26     INFO -  => mAdoptCount:              0
05:56:26     INFO -  => mAdoptFreeCount:          0
05:56:26     INFO -  => Process ID: 3656, Thread ID: 1380
05:56:33     INFO - --DOCSHELL 000002332C335800 == 5 [pid = 7516] [id = {33c64724-b01b-41e4-9c9b-ce0e9cdd0057}]
05:56:33     INFO - --DOCSHELL 000002332A12D800 == 4 [pid = 7516] [id = {31ae55f0-6d79-42a9-bf03-85af19d24217}]
05:56:33     INFO - --DOMWINDOW == 20 (0000023326449400) [pid = 7516] [serial = 4142] [outer = 0000000000000000] [url = about:blank]
05:56:33     INFO - --DOMWINDOW == 19 (00000233299D1400) [pid = 7516] [serial = 4145] [outer = 0000000000000000] [url = about:blank]
05:56:37     INFO - --DOMWINDOW == 18 (000002331C0AA800) [pid = 7516] [serial = 4138] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/542116-3.html]
05:56:37     INFO - --DOMWINDOW == 17 (0000023326471400) [pid = 7516] [serial = 4146] [outer = 0000000000000000] [url = about:blank]
05:56:37     INFO - --DOMWINDOW == 16 (000002331C0A7800) [pid = 7516] [serial = 4144] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/542317-1-ref.html]
05:56:37     INFO - --DOMWINDOW == 15 (0000023326967000) [pid = 7516] [serial = 4547] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/605138-1.html]
05:56:37     INFO - --DOMWINDOW == 14 (000002332696B400) [pid = 7516] [serial = 4515] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/600803-1.html]
05:56:37     INFO - --DOMWINDOW == 13 (000002331C0A6400) [pid = 7516] [serial = 4194] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/557087-2.html]
05:56:37     INFO - --DOMWINDOW == 12 (000002331C07FC00) [pid = 7516] [serial = 4139] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/542116-3-ref.html]
05:56:37     INFO - --DOMWINDOW == 11 (000002331C0AD000) [pid = 7516] [serial = 4378] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/572598-1.html]
05:56:37     INFO - --DOMWINDOW == 10 (0000023326478C00) [pid = 7516] [serial = 4516] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/600803-1-ref.html]
05:56:37     INFO - --DOMWINDOW == 9 (0000023326965400) [pid = 7516] [serial = 4405] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/579655-1.html]
05:56:37     INFO - --DOMWINDOW == 8 (00000233168B2C00) [pid = 7516] [serial = 4192] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/557087-ref.html]
05:56:37     INFO - --DOMWINDOW == 7 (000002331C0AC400) [pid = 7516] [serial = 4191] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/557087-1.html]
05:56:37     INFO - --DOMWINDOW == 6 (000002331C0A8800) [pid = 7516] [serial = 4141] [outer = 0000000000000000] [url = file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/542317-1.html]
05:56:37     INFO - --DOMWINDOW == 5 (000002331C0B2000) [pid = 7516] [serial = 4143] [outer = 0000000000000000] [url = about:blank]
05:58:58     INFO - [Parent 6768, Main Thread] WARNING: Suboptimal indexes for the SQL statement 0x240b8fc6ed0 (http://mzl.la/1FuID0j).: file z:/build/build/src/storage/mozStoragePrivateHelpers.cpp, line 114
06:00:59     INFO - REFTEST TEST-UNEXPECTED-FAIL | file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/613433-1.html == file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/613433-1-ref.html | load failed: timed out waiting for pending paint count to reach zero (after reftest-wait removed and switch to print mode) (waiting for MozAfterPaint)
06:00:59     INFO - REFTEST INFO | Saved log: START file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/613433-1.html
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] OnDocumentLoad triggering WaitForTestEnd
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] WaitForTestEnd: Adding listeners
06:00:59     INFO - REFTEST INFO | Saved log: Initializing canvas snapshot
06:00:59     INFO - REFTEST INFO | Saved log: DoDrawWindow 0,0,800,1000
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_TO_FIRE_INVALIDATE_EVENT
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for MozAfterPaint
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/613433-1.html
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] Webrender enabled, sending update whole canvas for invalidation
06:00:59     INFO - REFTEST INFO | Saved log: Updating entire canvas for invalidation
06:00:59     INFO - REFTEST INFO | Saved log: DoDrawWindow 0,0,800,1000
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_TO_FIRE_INVALIDATE_EVENT
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: dispatching MozReftestInvalidate
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_SPELL_CHECKS
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for spell checks to end
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_SPELL_CHECKS
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_APZ_FLUSH
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: done requesting APZ flush
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: apz-repaints-flushed fired
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_TO_FINISH
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for MozAfterPaint
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/613433-1.html
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] Webrender enabled, sending update whole canvas for invalidation
06:00:59     INFO - REFTEST INFO | Saved log: Updating entire canvas for invalidation
06:00:59     INFO - REFTEST INFO | Saved log: DoDrawWindow 0,0,800,1000
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_TO_FINISH
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: Completed
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] RecordResult fired
06:00:59     INFO - REFTEST INFO | Saved log: RecordResult fired
06:00:59     INFO - REFTEST INFO | Saved log: START file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/613433-1-ref.html
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] OnDocumentLoad triggering WaitForTestEnd
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] WaitForTestEnd: Adding listeners
06:00:59     INFO - REFTEST INFO | Saved log: Initializing canvas snapshot
06:00:59     INFO - REFTEST INFO | Saved log: DoDrawWindow 0,0,800,1000
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_TO_FIRE_INVALIDATE_EVENT
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for MozAfterPaint
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///C:/Users/task_1536212605/build/tests/reftest/tests/layout/reftests/bugs/613433-1-ref.html
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] Webrender enabled, sending update whole canvas for invalidation
06:00:59     INFO - REFTEST INFO | Saved log: Updating entire canvas for invalidation
06:00:59     INFO - REFTEST INFO | Saved log: DoDrawWindow 0,0,800,1000
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_TO_FIRE_INVALIDATE_EVENT
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: dispatching MozReftestInvalidate
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_SPELL_CHECKS
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for spell checks to end
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_SPELL_CHECKS
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_APZ_FLUSH
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: done requesting APZ flush
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: apz-repaints-flushed fired
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_TO_FINISH
06:00:59     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for MozAfterPaint
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.