1495079 - Intermittent Windows 10 x64 QuantumRender debug Reftests - load failed: timed out after x ms waiting for 'load' event for undefined

Status: RESOLVED INCOMPLETE

(Core :: Layout, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: nerli [at] mozilla.com

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

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

18:36:11     INFO - REFTEST TEST-START | file:///C:/Users/task_1538158735/build/tests/reftest/tests/layout/reftests/bugs/613433-1.html == file:///C:/Users/task_1538158735/build/tests/reftest/tests/layout/reftests/bugs/613433-3-ref.html
18:36:11     INFO - REFTEST TEST-LOAD | file:///C:/Users/task_1538158735/build/tests/reftest/tests/layout/reftests/bugs/613433-3-ref.html | 1592 / 2045 (77%)
18:36:39     INFO - [NPAPI 5208, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
18:36:39     INFO - [NPAPI 5208, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
18:36:39     INFO - [Parent 2752, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
18:36:39     INFO - [NPAPI 5208, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
18:41:11     INFO - REFTEST TEST-UNEXPECTED-FAIL | file:///C:/Users/task_1538158735/build/tests/reftest/tests/layout/reftests/bugs/613433-1.html == file:///C:/Users/task_1538158735/build/tests/reftest/tests/layout/reftests/bugs/613433-3-ref.html | load failed: timed out after 4 ms waiting for 'load' event for undefined
18:41:11     INFO - REFTEST INFO | Saved log: RecordResult fired
18:41:11     INFO - REFTEST INFO | Saved log: START file:///C:/Users/task_1538158735/build/tests/reftest/tests/layout/reftests/bugs/613433-3-ref.html
18:41:11     INFO - REFTEST INFO | Saved log: [CONTENT] OnDocumentLoad triggering WaitForTestEnd
18:41:11     INFO - REFTEST INFO | Saved log: [CONTENT] WaitForTestEnd: Adding listeners
18:41:11     INFO - REFTEST INFO | Saved log: Initializing canvas snapshot
18:41:11     INFO - REFTEST INFO | Saved log: DoDrawWindow 0,0,800,1000

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* windows10-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1495079&startday=2018-09-24&endday=2018-09-30&tree=all

Comment 17

[Raul Gurzau (:RaulG)]

These are all happening on the same platform Windows 10 x64 QuantumRender debug.
Joel, can you please take a look here?

Comment 18

[Joel Maher ( :jmaher ) (UTC -8)]

this has only failed 3 times in the last 30 days, I don't see much work to do here, is this a fresh orange?

Comment 19

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

See the duplicates, those didn't get reclassified against this bug.

Comment 20

[Joel Maher ( :jmaher ) (UTC -8)]

I will wait for a larger sample size, maybe then we can determine if there is a pattern of tests that are commonly timing out, messages in log files, or time after the browser has started.

Comment 21

[Intermittent Failures Robot]

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

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

Platform breakdown:
* windows10-64-qr: 6

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

Comment 22

[Intermittent Failures Robot]

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

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

Platform breakdown:
* windows10-64-qr: 3
* macosx64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1495079&startday=2018-10-15&endday=2018-10-21&tree=all

Comment 23

[Intermittent Failures Robot]

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

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

Platform breakdown:
* windows10-64-qr: 5
* macosx64-qr: 1

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

Comment 24

[Intermittent Failures Robot]

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

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

Platform breakdown:
* windows10-64-qr: 2

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

Comment 25

[Firefox Bug Husbandry Bot]

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

Comment 26

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• windows10-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1495079&startday=2019-01-28&endday=2019-02-03&tree=all

Comment 27

[Andreea Pavel [:apavel]]

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=226118159&repo=autoland&lineNumber=77125

12:14:45 INFO - REFTEST TEST-START | file:///Z:/task_1549367109/build/tests/reftest/tests/layout/reftests/object/connection-refused.html == file:///Z:/task_1549367109/build/tests/reftest/tests/layout/reftests/object/connection-refused-ref.html
12:14:45 INFO - REFTEST TEST-LOAD | file:///Z:/task_1549367109/build/tests/reftest/tests/layout/reftests/object/connection-refused.html | 5 / 19 (26%)
12:14:45 INFO - ++DOMWINDOW == 28 (0000025194C0B000) [pid = 7928] [serial = 29] [outer = 0000025193E5C000]
12:14:45 INFO - [Parent 5956, Main Thread] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/netwerk/url-classifier/AsyncUrlChannelClassifier.cpp, line 756
12:14:45 INFO - [Parent 5956, Main Thread] WARNING: Unkown protocol version: 0x9. Please file a bug: file z:/build/build/src/netwerk/protocol/http/nsHttp.cpp, line 221
12:14:45 INFO - ++DOCSHELL 0000025193E7E800 == 7 [pid = 7928] [id = {a5b70825-4525-47be-81a3-60606d2a847f}]
12:14:45 INFO - ++DOMWINDOW == 29 (00000251880A3000) [pid = 7928] [serial = 30] [outer = 0000000000000000]
12:14:45 INFO - ++DOMWINDOW == 30 (0000025194B6B400) [pid = 7928] [serial = 31] [outer = 00000251880A3000]
12:14:45 INFO - [Child 7928, Main Thread] WARNING: Listener is not retargetable: file z:/build/build/src/netwerk/protocol/http/HttpChannelChild.cpp, line 3450
12:14:45 INFO - [Child 7928, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file z:/build/build/src/parser/html/nsHtml5StreamParser.cpp, line 1028
12:14:45 INFO - --DOCSHELL 000001AF8C3C6800 == 6 [pid = 5956] [id = {553601f7-f688-4d9b-903f-8e7e091c3897}]
12:14:45 INFO - --DOMWINDOW == 15 (000001AF8EF04C00) [pid = 5956] [serial = 8] [outer = 0000000000000000] [url = about:blank]
12:14:47 INFO - --DOMWINDOW == 14 (000001AF8B39D800) [pid = 5956] [serial = 4] [outer = 0000000000000000] [url = about:blank]
12:14:47 INFO - --DOMWINDOW == 13 (000001AF8E234800) [pid = 5956] [serial = 10] [outer = 0000000000000000] [url = about:blank]
12:14:47 INFO - --DOMWINDOW == 12 (000001AF90A89800) [pid = 5956] [serial = 12] [outer = 0000000000000000] [url = about:blank]
12:14:50 INFO - [Child 7928, Main Thread] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/dom/base/Element.cpp, line 1284
12:14:50 INFO - --DOCSHELL 0000025188049000 == 6 [pid = 7928] [id = {512ecf21-882d-490a-890f-28734a64cf98}]
12:14:50 INFO - --DOMWINDOW == 29 (0000025194C04000) [pid = 7928] [serial = 19] [outer = 0000000000000000] [url = file:///Z:/task_1549367109/build/tests/reftest/tests/layout/reftests/object/extra/pass.html]
12:14:50 INFO - --DOCSHELL 0000025194BC4800 == 5 [pid = 7928] [id = {a354df1f-d06c-45d1-a87a-0356cdfe4639}]
12:14:50 INFO - --DOMWINDOW == 28 (000002518DA88400) [pid = 7928] [serial = 1] [outer = 0000000000000000] [url = chrome://gfxsanity/content/sanitytest.html]
12:14:50 INFO - --DOCSHELL 000002518804A000 == 4 [pid = 7928] [id = {96565882-76d5-4ce7-a091-c80232f3c094}]
12:14:50 INFO - --DOMWINDOW == 27 (0000025194B6B000) [pid = 7928] [serial = 16] [outer = 0000000000000000] [url = file:///Z:/task_1549367109/build/tests/reftest/tests/layout/reftests/object/extra/pass.html]
12:14:55 INFO - --DOMWINDOW == 11 (000002A32EF58800) [pid = 6148] [serial = 6] [outer = 0000000000000000] [url = about:blank]
12:14:55 INFO - --DOMWINDOW == 10 (000002A32EF59000) [pid = 6148] [serial = 7] [outer = 0000000000000000] [url = about:blank]
12:14:55 INFO - --DOMWINDOW == 9 (000002A328B9AC00) [pid = 6148] [serial = 5] [outer = 0000000000000000] [url = about:blank]
12:14:55 INFO - --DOMWINDOW == 8 (000002A3233A1800) [pid = 6148] [serial = 8] [outer = 0000000000000000] [url = about:blank]
12:14:58 INFO - [Parent 5956, StreamTrans #104] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/modules/libjar/nsJARChannel.cpp, line 372
12:14:58 INFO - [Parent 5956, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/modules/libjar/nsJARChannel.cpp, line 1010
12:15:00 INFO - --DOMWINDOW == 26 (0000025194B6BC00) [pid = 7928] [serial = 17] [outer = 0000000000000000] [url = file:///Z:/task_1549367109/build/tests/reftest/tests/layout/reftests/object/extra/pass.html]
12:15:00 INFO - --DOMWINDOW == 25 (0000025193E5D000) [pid = 7928] [serial = 3] [outer = 0000000000000000] [url = chrome://gfxsanity/content/sanitytest.html]
12:15:00 INFO - --DOMWINDOW == 24 (0000025194C05000) [pid = 7928] [serial = 20] [outer = 0000000000000000] [url = file:///Z:/task_1549367109/build/tests/reftest/tests/layout/reftests/object/extra/pass.html]
12:15:00 INFO - --DOMWINDOW == 23 (0000025193E5E400) [pid = 7928] [serial = 14] [outer = 0000000000000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
12:15:00 INFO - --DOMWINDOW == 22 (0000025194B6F800) [pid = 7928] [serial = 18] [outer = 0000000000000000] [url = file:///Z:/task_1549367109/build/tests/reftest/tests/layout/reftests/object/page-as-data-ref.html]
12:15:00 INFO - --DOMWINDOW == 21 (0000025194058800) [pid = 7928] [serial = 6] [outer = 0000000000000000] [url = file:///Z:/task_1549367109/build/tests/reftest/tests/layout/reftests/object/no-attrs.html]
12:15:00 INFO - --DOMWINDOW == 20 (0000025193E57800) [pid = 7928] [serial = 10] [outer = 0000000000000000] [url = http://localhost:52334/1549368883458/1/404-data-ref.html]
12:15:00 INFO - --DOMWINDOW == 19 (0000025194B65C00) [pid = 7928] [serial = 15] [outer = 0000000000000000] [url = file:///Z:/task_1549367109/build/tests/reftest/tests/layout/reftests/object/page-as-data.html]
12:15:00 INFO - --DOMWINDOW == 18 (0000025194B65800) [pid = 7928] [serial = 13] [outer = 0000000000000000] [url = http://localhost:52334/1549368883460/2/404-data-with-type-ref.html]
12:15:00 INFO - --DOMWINDOW == 17 (0000025194057000) [pid = 7928] [serial = 5] [outer = 0000000000000000] [url = about:blank]
12:15:00 INFO - --DOMWINDOW == 16 (0000025194059400) [pid = 7928] [serial = 11] [outer = 0000000000000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
12:15:00 INFO - --DOMWINDOW == 15 (0000025193E58000) [pid = 7928] [serial = 9] [outer = 0000000000000000] [url = http://localhost:52334/1549368883458/1/404-data.html]
12:15:00 INFO - --DOMWINDOW == 14 (0000025194B60800) [pid = 7928] [serial = 8] [outer = 0000000000000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
12:15:00 INFO - --DOMWINDOW == 13 (0000025194B60C00) [pid = 7928] [serial = 7] [outer = 0000000000000000] [url = file:///Z:/task_1549367109/build/tests/reftest/tests/layout/reftests/object/no-attrs-ref.html]
12:15:00 INFO - --DOMWINDOW == 12 (0000025194058000) [pid = 7928] [serial = 12] [outer = 0000000000000000] [url = http://localhost:52334/1549368883460/2/404-data-with-type.html]
12:15:12 INFO - --DOMWINDOW == 11 (0000025194B6E800) [pid = 7928] [serial = 21] [outer = 0000000000000000] [url = data:text/html;charset=UTF-8,%3C%21%2D%2DCLEAR%2D%2D%3E]
12:17:39 INFO - [Parent 5956, Main Thread] WARNING: Suboptimal indexes for the SQL statement 0x1af8f220bd0 (http://mzl.la/1FuID0j).: file z:/build/build/src/storage/mozStoragePrivateHelpers.cpp, line 108
12:19:45 INFO - REFTEST TEST-UNEXPECTED-FAIL | file:///Z:/task_1549367109/build/tests/reftest/tests/layout/reftests/object/connection-refused.html == file:///Z:/task_1549367109/build/tests/reftest/tests/layout/reftests/object/connection-refused-ref.html | load failed: timed out after 28 ms waiting for 'load' event for undefined
12:19:45 INFO - REFTEST INFO | Saved log: START file:///Z:/task_1549367109/build/tests/reftest/tests/layout/reftests/object/connection-refused.html
12:19:45 INFO - REFTEST TEST-END | file:///Z:/task_1549367109/build/tests/reftest/tests/layout/reftests/object/connection-refused.html == file:///Z:/task_1549367109/build/tests/reftest/tests/layout/reftests/object/connection-refused-ref.html

Comment 28

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1495079&startday=2019-02-04&endday=2019-02-10&tree=all

Comment 29

[Firefox Bug Husbandry Bot]

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