1174851 - Intermittent browser_readerMode_hidden_nodes.js | Test timed out

Status: RESOLVED FIXED

(Toolkit :: Reader Mode, defect)

Description

[Ryan VanderMeulen [:RyanVM]]

10:17:14 INFO - 428 INFO TEST-START | browser/base/content/test/general/browser_readerMode_hidden_nodes.js
10:17:17 INFO - JavaScript error: , line 0: NetworkError: A network error occurred.
10:17:17 INFO - JavaScript error: , line 0: NetworkError: A network error occurred.
10:17:59 INFO - Xlib: extension "RANDR" missing on display ":0".
10:18:01 INFO - TEST-INFO | screentopng: exit 0
10:18:01 INFO - 429 INFO checking window state
10:18:01 INFO - 430 INFO Entering test test_reader_button
10:18:01 INFO - 431 INFO TEST-PASS | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Element should not be null, when checking visibility
10:18:01 INFO - 432 INFO TEST-PASS | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Reader mode button is not present on a new tab
10:18:01 INFO - 433 INFO Wait tab event: load
10:18:01 INFO - 434 INFO Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://example.com/browser/browser/base/content/test/general/readerModeArticleHiddenNodes.html" line: 0}]
10:18:01 INFO - 435 INFO Tab event received: load
10:18:01 INFO - 436 INFO Console message: [JavaScript Error: "NetworkError: A network error occurred."]
10:18:01 INFO - 437 INFO Console message: [JavaScript Error: "NetworkError: A network error occurred."]
10:18:01 INFO - 438 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Test timed out - expected PASS
10:18:01 INFO - MEMORY STAT vsize after test: 21991871655936
10:18:01 INFO - MEMORY STAT residentFast after test: 758071296
10:18:01 INFO - 439 INFO TEST-OK | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | took 45377ms

Comment 1

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=fx-team&job_id=3459672
repository: fx-team
start_time: 2015-06-15T10:06:20
who: rvandermeulen[at]mozilla[dot]com
machine: tst-linux64-spot-051
buildname: Ubuntu ASAN VM 12.04 x64 fx-team opt test mochitest-e10s-browser-chrome-2
revision: 819d7887fdc4

438 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Test timed out - expected PASS
SUMMARY: AddressSanitizer: 464 byte(s) leaked in 4 allocation(s).
SUMMARY: AddressSanitizer: 720 byte(s) leaked in 10 allocation(s).

Comment 2

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-central&job_id=1660111
repository: mozilla-central
start_time: 2015-06-15T13:36:03
who: wkocher[at]mozilla[dot]com
machine: tst-linux32-spot-726
buildname: Ubuntu VM 12.04 mozilla-central opt test mochitest-e10s-browser-chrome-3
revision: e14631051d83

438 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Test timed out - expected PASS

Comment 3

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-central&job_id=1661578
repository: mozilla-central
start_time: 2015-06-15T14:12:01
who: wkocher[at]mozilla[dot]com
machine: tst-linux32-spot-606
buildname: Ubuntu VM 12.04 mozilla-central debug test mochitest-e10s-browser-chrome-3
revision: cd0d976e5f5c

453 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Test timed out - expected PASS

Comment 4

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-central&job_id=1662129
repository: mozilla-central
start_time: 2015-06-15T14:46:58
who: wkocher[at]mozilla[dot]com
machine: t-yosemite-r5-0082
buildname: Rev5 MacOSX Yosemite 10.10 mozilla-central opt test mochitest-e10s-browser-chrome-1
revision: e14631051d83

448 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Test timed out - expected PASS

Comment 5

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=fx-team&job_id=3464538
repository: fx-team
start_time: 2015-06-15T15:58:17
who: wkocher[at]mozilla[dot]com
machine: tst-linux32-spot-663
buildname: Ubuntu VM 12.04 fx-team pgo test mochitest-e10s-browser-chrome-3
revision: 59851463cc09

438 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Test timed out - expected PASS

Comment 6

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=b2g-inbound&job_id=2166675
repository: b2g-inbound
start_time: 2015-06-15T14:11:50
who: wkocher[at]mozilla[dot]com
machine: tst-linux64-spot-1468
buildname: Ubuntu ASAN VM 12.04 x64 b2g-inbound opt test mochitest-e10s-browser-chrome-2
revision: 9d4200cadeaf

436 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Test timed out - expected PASS
SUMMARY: AddressSanitizer: 464 byte(s) leaked in 4 allocation(s).
SUMMARY: AddressSanitizer: 720 byte(s) leaked in 10 allocation(s).

Comment 7

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=b2g-inbound&job_id=2168126
repository: b2g-inbound
start_time: 2015-06-15T19:31:01
who: philringnalda[at]gmail[dot]com
machine: tst-linux64-spot-777
buildname: Ubuntu VM 12.04 x64 b2g-inbound opt test mochitest-e10s-browser-chrome-2
revision: f42cf1d7c1bb

438 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Test timed out - expected PASS

Comment 8

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=b2g-inbound&job_id=2168095
repository: b2g-inbound
start_time: 2015-06-15T19:33:15
who: philringnalda[at]gmail[dot]com
machine: tst-linux64-spot-637
buildname: Ubuntu VM 12.04 x64 b2g-inbound debug test mochitest-e10s-browser-chrome-2
revision: f42cf1d7c1bb

432 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Test timed out - expected PASS

Comment 9

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=10808894
repository: mozilla-inbound
start_time: 2015-06-15T19:59:01
who: philringnalda[at]gmail[dot]com
machine: t-snow-r4-0114
buildname: Rev4 MacOSX Snow Leopard 10.6 mozilla-inbound opt test mochitest-e10s-browser-chrome-1
revision: b9562bd4954b

450 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Test timed out - expected PASS

Comment 10

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=10806423
repository: mozilla-inbound
start_time: 2015-06-15T18:45:22
who: philringnalda[at]gmail[dot]com
machine: tst-linux32-spot-446
buildname: Ubuntu VM 12.04 mozilla-inbound pgo test mochitest-e10s-browser-chrome-3
revision: f4367d79d062

438 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Test timed out - expected PASS

Comment 11

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-central&job_id=1662617
repository: mozilla-central
start_time: 2015-06-15T18:23:55
who: philringnalda[at]gmail[dot]com
machine: tst-linux64-spot-985
buildname: Ubuntu VM 12.04 x64 mozilla-central opt test mochitest-e10s-browser-chrome-2
revision: 5a1e229e8bff

438 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Test timed out - expected PASS

Comment 12

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=b2g-inbound&job_id=2168846
repository: b2g-inbound
start_time: 2015-06-15T19:58:21
who: philringnalda[at]gmail[dot]com
machine: tst-linux64-spot-374
buildname: Ubuntu ASAN VM 12.04 x64 b2g-inbound opt test mochitest-e10s-browser-chrome-2
revision: a71239680fd8

436 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Test timed out - expected PASS
SUMMARY: AddressSanitizer: 464 byte(s) leaked in 4 allocation(s).
SUMMARY: AddressSanitizer: 864 byte(s) leaked in 12 allocation(s).

Comment 13

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=fx-team&job_id=3468697
repository: fx-team
start_time: 2015-06-15T21:31:30
who: philringnalda[at]gmail[dot]com
machine: tst-linux64-spot-1572
buildname: Ubuntu ASAN VM 12.04 x64 fx-team opt test mochitest-e10s-browser-chrome-2
revision: 44d354ade75c

436 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Test timed out - expected PASS
SUMMARY: AddressSanitizer: 464 byte(s) leaked in 4 allocation(s).
SUMMARY: AddressSanitizer: 576 byte(s) leaked in 8 allocation(s).

Comment 14

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-central&job_id=1664260
repository: mozilla-central
start_time: 2015-06-15T21:52:49
who: philringnalda[at]gmail[dot]com
machine: tst-linux32-spot-411
buildname: Ubuntu VM 12.04 mozilla-central pgo test mochitest-e10s-browser-chrome-3
revision: ce863f9d8864

438 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Test timed out - expected PASS

Comment 15

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=fx-team&job_id=3469013
repository: fx-team
start_time: 2015-06-15T22:09:05
who: philringnalda[at]gmail[dot]com
machine: t-snow-r4-0154
buildname: Rev4 MacOSX Snow Leopard 10.6 fx-team opt test mochitest-e10s-browser-chrome-1
revision: 44d354ade75c

448 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Test timed out - expected PASS

Comment 16

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=10817216
repository: mozilla-inbound
start_time: 2015-06-15T22:38:09
who: tomcat[at]mozilla[dot]com
machine: tst-linux64-spot-538
buildname: Ubuntu ASAN VM 12.04 x64 mozilla-inbound opt test mochitest-e10s-browser-chrome-2
revision: 658e6dd34f36

436 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Test timed out - expected PASS
SUMMARY: AddressSanitizer: 464 byte(s) leaked in 4 allocation(s).
SUMMARY: AddressSanitizer: 576 byte(s) leaked in 8 allocation(s).

Comment 17

[Pulsebot]

https://hg.mozilla.org/integration/fx-team/rev/f817b696efb4

Comment 18

[:Gijs (he/him)]

Right. So what's happening here is this is failing on reasonably-fast non-Windows e10s machines.

The reason it's failing seems to be the fact that we listen for the tab to load in the parent process, then have another delay due to the implicit-async yield in the test, and then start listening for mozafterpaint. That misses the event, and on non-Windows (hi, vsync) and e10s (why would I paint anything in the static content area?) this means there are no more paint events until you do something to the content window (the test never does this) and the test then times out. Locally, I could get it to not time out by e.g. selecting some text in the content area, causing a paint, and kicking the rest of the test into action.

The code it's testing doesn't have this problem because it listens in the content process and then immediately attaches the new event handler (no async delays) and so it always gets called (I could repro this all locally so the correctness of the actual code was the first thing I checked).

The thing I just checked in simply changes the test to mimic the actual code more closely, and listens for all the events in the content process instead of the parent process. This fixed the race condition for me locally.

Comment 19

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=10821929
repository: mozilla-inbound
start_time: 2015-06-16T02:40:05
who: tomcat[at]mozilla[dot]com
machine: tst-linux64-spot-1525
buildname: Ubuntu VM 12.04 x64 mozilla-inbound pgo test mochitest-e10s-browser-chrome-2
revision: fd6c99f92485

438 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Test timed out - expected PASS

Comment 20

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=10822825
repository: mozilla-inbound
start_time: 2015-06-16T04:13:21
who: tomcat[at]mozilla[dot]com
machine: tst-linux64-spot-1031
buildname: Ubuntu VM 12.04 x64 mozilla-inbound debug test mochitest-e10s-browser-chrome-2
revision: b43de5b593e7

434 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Test timed out - expected PASS

Comment 21

[Carsten Book [:Tomcat]]

https://hg.mozilla.org/mozilla-central/rev/f817b696efb4

Comment 22

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=try&job_id=8602017
repository: try
start_time: 2015-06-16T08:22:42
who: bkelly[at]mozilla[dot]com
machine: tst-linux32-spot-424
buildname: Ubuntu VM 12.04 try debug test mochitest-e10s-browser-chrome-3
revision: d6b725070026

453 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Test timed out - expected PASS

Comment 23

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=try&job_id=8603051
repository: try
start_time: 2015-06-16T08:50:33
who: bkelly[at]mozilla[dot]com
machine: tst-linux64-spot-264
buildname: Ubuntu ASAN VM 12.04 x64 try opt test mochitest-e10s-browser-chrome-2
revision: d6b725070026

436 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Test timed out - expected PASS
SUMMARY: AddressSanitizer: 464 byte(s) leaked in 4 allocation(s).
SUMMARY: AddressSanitizer: 576 byte(s) leaked in 8 allocation(s).

Comment 24

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=10829444
repository: mozilla-inbound
start_time: 2015-06-16T08:37:30
who: rvandermeulen[at]mozilla[dot]com
machine: tst-linux32-spot-315
buildname: Ubuntu VM 12.04 mozilla-inbound debug test mochitest-e10s-browser-chrome-3
revision: c921bf16ff10

453 INFO TEST-UNEXPECTED-FAIL | browser/base/content/test/general/browser_readerMode_hidden_nodes.js | Test timed out - expected PASS
770 INFO TEST-UNEXPECTED-FAIL | layout/xul/test/browser_bug706743.js | Test timed out - expected PASS
772 INFO TEST-UNEXPECTED-FAIL | layout/xul/test/browser_bug706743.js | Found a tab after previous test timed out: data:text/html,<html><head></head><body><a%20id="target"%20href="about:blank"%20title="This%20is%20tooltip%20text"%20style="display:block;height:20px;margin:10px;"%20onclick="return%20false;">here%20is%20an%20anchor%20element</a></body></html> - expected PASS