1161701 - Intermittent testReaderView | testReaderView.js - == Article title - See following stack:

Status: RESOLVED FIXED

(Firefox for Android Graveyard :: Reader View, defect)

Description

[Wes Kocher (:KWierso) (Not reading bugmail; email directly if needed)]

10:45:44 INFO - TEST-START | testReaderView
10:45:44 INFO - TEST-PASS | testReaderView | Robocop tests need the test device screen to be powered on. -
10:45:44 INFO - TEST-PASS | testReaderView | Given message occurred for registered event: {"type":"Gecko:Ready"} - Gecko:Ready should equal Gecko:Ready
10:45:44 INFO - EventExpecter: no longer listening for Gecko:Ready
10:45:44 INFO - Registered listener for Robocop:JS
10:45:44 INFO - Loading JavaScript test from http://mochi.test:8888/tests/robocop/robocop_javascript.html?slug=1430846522531&path=testReaderView.js
10:45:44 INFO - TEST-PASS | testReaderView | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | test 1 pending\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
10:45:44 INFO - (xpcshell/head.js) | test 1 pending
10:45:44 INFO - TEST-PASS | testReaderView | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | test 2 pending\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
10:45:44 INFO - (xpcshell/head.js) | test 2 pending
10:45:44 INFO - TEST-PASS | testReaderView | Given message occurred for registered event: {"message":"\nTEST-INFO | testReaderView.js | Starting test_reader_view_visibility","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
10:45:44 INFO - testReaderView.js | Starting test_reader_view_visibility
10:45:44 INFO - TEST-PASS | testReaderView | Given message occurred for registered event: {"message":"\nTEST-INFO | testReaderView.js | Now waiting for load event from browser\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
10:45:44 INFO - testReaderView.js | Now waiting for load event from browser
10:45:44 INFO - TEST-PASS | testReaderView | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | test 2 finished\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
10:45:44 INFO - (xpcshell/head.js) | test 2 finished
10:45:44 INFO - TEST-PASS | testReaderView | Given message occurred for registered event: {"message":"\nTEST-INFO | testReaderView.js | Received event load from browser\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
10:45:44 INFO - testReaderView.js | Received event load from browser
10:45:44 INFO - TEST-PASS | testReaderView | Given message occurred for registered event: {"message":"\nTEST-INFO | testReaderView.js | Condition didn't pass. Moving on.\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
10:45:44 INFO - testReaderView.js | Condition didn't pass. Moving on.
10:45:44 INFO - TEST-PASS | testReaderView | Given message occurred for registered event: {"message":"\nTEST-UNEXPECTED-FAIL | testReaderView.js | == Article title - See following stack:\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
10:45:44 WARNING - TEST-UNEXPECTED-FAIL | testReaderView | testReaderView.js - == Article title - See following stack:
10:45:44 INFO - TEST-PASS | testReaderView | Given message occurred for registered event: {"message":"JS frame :: robocop_head.js :: do_throw :: line 471\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
10:45:44 INFO - testReaderView.js | JS frame :: robocop_head.js :: do_throw :: line 471
10:45:44 INFO - TEST-PASS | testReaderView | Given message occurred for registered event: {"message":"JS frame :: robocop_head.js :: do_report_result :: line 573\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
10:45:44 INFO - testReaderView.js | JS frame :: robocop_head.js :: do_report_result :: line 573
10:45:44 INFO - TEST-PASS | testReaderView | Given message occurred for registered event: {"message":"JS frame :: robocop_head.js :: _do_check_eq :: line 583\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
10:45:44 INFO - testReaderView.js | JS frame :: robocop_head.js :: _do_check_eq :: line 583
10:45:44 INFO - TEST-PASS | testReaderView | Given message occurred for registered event: {"message":"JS frame :: robocop_head.js :: do_check_eq :: line 590\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
10:45:44 INFO - testReaderView.js | JS frame :: robocop_head.js :: do_check_eq :: line 590
10:45:44 INFO - TEST-PASS | testReaderView | Given message occurred for registered event: {"message":"JS frame :: testReaderView.js :: test_reader_view_visibility :: line 64\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
10:45:44 INFO - testReaderView.js | JS frame :: testReaderView.js :: test_reader_view_visibility :: line 64
10:45:44 INFO - TEST-PASS | testReaderView | Given message occurred for registered event: {"message":"JS frame :: self-hosted :: next :: line 624\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
10:45:44 INFO - testReaderView.js | JS frame :: self-hosted :: next :: line 624
10:45:44 INFO - TEST-PASS | testReaderView | Given message occurred for registered event: {"message":"JS frame :: resource:\/\/gre\/modules\/Task.jsm :: TaskImpl_run :: line 314\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
10:45:44 INFO - testReaderView.js | JS frame :: resource://gre/modules/Task.jsm :: TaskImpl_run :: line 314
10:45:44 INFO - TEST-PASS | testReaderView | Given message occurred for registered event: {"message":"native frame :: <unknown filename> :: <TOP_LEVEL> :: line 0\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
10:45:44 INFO - testReaderView.js | native frame :: <unknown filename> :: <TOP_LEVEL> :: line 0
10:45:44 INFO - TEST-PASS | testReaderView | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | exiting test\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
10:45:44 INFO - (xpcshell/head.js) | exiting test
10:45:44 INFO - EventExpecter: no longer listening for Robocop:JS
10:45:44 INFO - Unregistered listener for Robocop:JS
10:45:44 INFO - TEST-OK | testReaderView | took 114967ms
10:45:44 INFO - TEST-START | Shutdown

Comment 1

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=fx-team&job_id=2939179
repository: fx-team
start_time: 2015-05-05T09:45:45
who: wkocher[at]mozilla[dot]com
machine: tst-linux64-spot-215
buildname: Android 4.3 armv7 API 11+ fx-team opt test robocop-3
revision: 3b908ec52d4e

TEST-UNEXPECTED-FAIL | testReaderView | testReaderView.js -  == Article title - See following stack:

Comment 2

[:Margaret Leibovic]

I suspect this happens if we're just slow to load the about:reader content, which is why we try to wait before doing this check:
http://mxr.mozilla.org/mozilla-central/source/mobile/android/tests/browser/robocop/testReaderView.js#61

A real fix here would be to emit a notification from AboutReader.jsm that's only used for testing to know that we should move on.

Comment 3

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=fx-team&job_id=2960288
repository: fx-team
start_time: 2015-05-06T10:46:04
who: rvandermeulen[at]mozilla[dot]com
machine: tst-linux64-spot-328
buildname: Android 4.3 armv7 API 11+ fx-team opt test robocop-3
revision: 09a0d7b4527a

TEST-UNEXPECTED-FAIL | testReaderView | testReaderView.js -  == Article title - See following stack:

Comment 4

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=fx-team&job_id=2977490
repository: fx-team
start_time: 2015-05-07T11:19:52
who: philringnalda[at]gmail[dot]com
machine: tst-linux64-spot-379
buildname: Android 4.3 armv7 API 11+ fx-team opt test robocop-3
revision: de4f4de880fd

TEST-UNEXPECTED-FAIL | testReaderView | testReaderView.js -  == Article title - See following stack:

Comment 5

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=9671127
repository: mozilla-inbound
start_time: 2015-05-07T13:54:07
who: wkocher[at]mozilla[dot]com
machine: tst-linux64-spot-1552
buildname: Android armv7 API 9 mozilla-inbound opt test robocop-3
revision: 8778cdfb891b

TEST-UNEXPECTED-FAIL | testReaderView | testReaderView.js -  == Article title - See following stack:

Comment 6

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=fx-team&job_id=2980997
repository: fx-team
start_time: 2015-05-07T15:06:18
who: wkocher[at]mozilla[dot]com
machine: tst-linux64-spot-121
buildname: Android 4.3 armv7 API 11+ fx-team opt test robocop-3
revision: 441bcf641aa7

TEST-UNEXPECTED-FAIL | testOfflinePage | testOfflinePage.js - Services.io.offline is true. - See following stack:
TEST-UNEXPECTED-FAIL | testReaderView | testReaderView.js -  == Article title - See following stack:

Comment 7

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=9682862
repository: mozilla-inbound
start_time: 2015-05-07T19:13:41
who: philringnalda[at]gmail[dot]com
machine: tst-linux64-spot-619
buildname: Android 4.3 armv7 API 11+ mozilla-inbound opt test robocop-3
revision: a455c8bce215

TEST-UNEXPECTED-FAIL | testOfflinePage | testOfflinePage.js - Services.io.offline is true. - See following stack:
TEST-UNEXPECTED-FAIL | testReaderView | testReaderView.js -  == Article title - See following stack:

Comment 8

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=try&job_id=7336675
repository: try
start_time: 2015-05-07T19:57:30
who: ehsan[dot]akhgari[at]gmail[dot]com
machine: tst-linux64-spot-209
buildname: Android 4.3 armv7 API 11+ try opt test robocop-3
revision: bed5e448d5af

TEST-UNEXPECTED-FAIL | testReaderView | testReaderView.js -  == Article title - See following stack:

Comment 9

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=9707710
repository: mozilla-inbound
start_time: 2015-05-08T14:50:07
who: wkocher[at]mozilla[dot]com
machine: tst-linux64-spot-562
buildname: Android armv7 API 9 mozilla-inbound opt test robocop-3
revision: 0536b35fa2fa

TEST-UNEXPECTED-FAIL | testReaderView | testReaderView.js -  == Article title - See following stack:

Comment 10

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=fx-team&job_id=3014046
repository: fx-team
start_time: 2015-05-08T23:10:31
who: archaeopteryx[at]coole-files[dot]de
machine: tst-linux64-spot-558
buildname: Android armv7 API 9 fx-team opt test robocop-3
revision: aa4eac3a4336

TEST-UNEXPECTED-FAIL | testReaderView | testReaderView.js -  == Article title - See following stack:

Comment 11

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=fx-team&job_id=3014217
repository: fx-team
start_time: 2015-05-08T23:20:27
who: archaeopteryx[at]coole-files[dot]de
machine: tst-linux64-spot-2071
buildname: Android 4.3 armv7 API 11+ fx-team opt test robocop-3
revision: aa4eac3a4336

TEST-UNEXPECTED-FAIL | testOfflinePage | testOfflinePage.js - Services.io.offline is true. - See following stack:
TEST-UNEXPECTED-FAIL | testReaderView | testReaderView.js -  == Article title - See following stack:

Comment 12

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=try&job_id=7395298
repository: try
start_time: 2015-05-09T19:36:10
who: gbrown[at]mozilla[dot]com
machine: tst-linux64-spot-929
buildname: Android 4.3 armv7 API 11+ try debug test robocop-3
revision: 58e6d3923a17

TEST-UNEXPECTED-FAIL | testReaderView | testReaderView.js -  == Article title - See following stack:

Comment 13

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=9749406
repository: mozilla-inbound
start_time: 2015-05-11T11:29:08
who: philringnalda[at]gmail[dot]com
machine: tst-linux64-spot-1792
buildname: Android 4.3 armv7 API 11+ mozilla-inbound opt test robocop-3
revision: 6bc3e88e0107

TEST-UNEXPECTED-FAIL | testOfflinePage | testOfflinePage.js - Services.io.offline is true. - See following stack:
TEST-UNEXPECTED-FAIL | testReaderView | testReaderView.js -  == Article title - See following stack:

Comment 14

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=9758581
repository: mozilla-inbound
start_time: 2015-05-11T15:12:55
who: wkocher[at]mozilla[dot]com
machine: tst-linux64-spot-1017
buildname: Android 4.3 armv7 API 11+ mozilla-inbound opt test robocop-3
revision: f86c4ccf1d17

TEST-UNEXPECTED-FAIL | testOfflinePage | testOfflinePage.js - Services.io.offline is true. - See following stack:
TEST-UNEXPECTED-FAIL | testReaderView | testReaderView.js -  == Article title - See following stack:

Comment 15

[:Margaret Leibovic]

Attachment: Listen for AboutReader:Ready notification instead of waiting for content to appear in testReaderView

Passed locally. Here's the try push:

https://treeherder.mozilla.org/#/jobs?repo=try&revision=8c372f253632

Comment 16

[Pulsebot]

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

Comment 17

[:Margaret Leibovic]

Comment on attachment 8604378 [details] [diff] [review]
Listen for AboutReader:Ready notification instead of waiting for content to appear in testReaderView

Approval Request Comment
[Feature/regressing bug #]: bug 1158885
[User impact if declined]: intermittent oranges on Fx40 trees
[Describe test coverage new/current, TreeHerder]: green try run
[Risks and why]: low-risk, only modifies test logic
[String/UUID change made/needed]: none

Comment 18

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-central&job_id=1483502
repository: mozilla-central
start_time: 2015-05-12T08:17:10
who: rvandermeulen[at]mozilla[dot]com
machine: tst-linux64-spot-401
buildname: Android 4.3 armv7 API 11+ mozilla-central opt test robocop-3
revision: bedce1b405a3

TEST-UNEXPECTED-FAIL | testReaderView | testReaderView.js -  == Article title - See following stack:

Comment 19

[Wes Kocher (:KWierso) (Not reading bugmail; email directly if needed)]

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

Comment 20

[Lawrence Mandel [:lmandel] (use needinfo)]

Comment on attachment 8604378 [details] [diff] [review]
Listen for AboutReader:Ready notification instead of waiting for content to appear in testReaderView

Let's fix d'em oranges! Aurora+

Comment 21

[Wes Kocher (:KWierso) (Not reading bugmail; email directly if needed)]

https://hg.mozilla.org/releases/mozilla-aurora/rev/2bef28fc7f9e

Comment 22

[Legacy TBPL/Treeherder Robot]

log: https://treeherder.mozilla.org/logviewer.html#?repo=b2g-inbound&job_id=2458001
repository: b2g-inbound
start_time: 2015-08-04T06:44:33
who: tomcat[at]mozilla[dot]com
machine: tst-linux64-spot-163
buildname: Android armv7 API 9 b2g-inbound opt test robocop-3
revision: 25eeaecb471d

TEST-UNEXPECTED-FAIL | testReaderView | GeckoEventExpecter - blockForEvent timeout: Robocop:JS
0 ERROR Exception caught during test! - junit.framework.AssertionFailedError: TEST-UNEXPECTED-FAIL | testReaderView | GeckoEventExpecter - blockForEvent timeout: Robocop:JS
TEST-UNEXPECTED-FAIL | testReaderView | Exception caught - junit.framework.AssertionFailedError: TEST-UNEXPECTED-FAIL | testReaderView | GeckoEventExpecter - blockForEvent timeout: Robocop:JS
Return code: 1