1473640 - Perma-failing TV browser/components/preferences/in-content/tests/browser_hometab_restore_defaults.js | Uncaught exception - Wait for the preference checkbox to load - timed out after 50 tries.

Status: RESOLVED FIXED

(Firefox :: Settings UI, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: apavel [at] mozilla.com

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

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

[task 2018-07-05T11:13:21.682Z]     INFO - TEST-START | browser/components/preferences/in-content/tests/browser_hometab_restore_defaults.js
[task 2018-07-05T11:13:27.927Z]     INFO - TEST-INFO | started process screentopng
[task 2018-07-05T11:13:28.350Z]     INFO - TEST-INFO | screentopng: exit 0
[task 2018-07-05T11:13:28.350Z]     INFO - Buffered messages logged at 11:13:21
[task 2018-07-05T11:13:28.351Z]     INFO - Entering test bound testRestoreDefaultsBtn
[task 2018-07-05T11:13:28.351Z]     INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/FileUtils.jsm" line: 170}]
[task 2018-07-05T11:13:28.353Z]     INFO - Buffered messages finished
[task 2018-07-05T11:13:28.353Z]     INFO - TEST-UNEXPECTED-FAIL | browser/components/preferences/in-content/tests/browser_hometab_restore_defaults.js | Uncaught exception - Wait for the preference checkbox to load - timed out after 50 tries.
[task 2018-07-05T11:13:28.353Z]     INFO - Leaving test bound testRestoreDefaultsBtn
[task 2018-07-05T11:13:28.354Z]     INFO - Entering test bound testRestoreDefaultsBtn
[task 2018-07-05T11:13:28.592Z]     INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-07-05T11:13:28.596Z]     INFO - TEST-UNEXPECTED-FAIL | browser/components/preferences/in-content/tests/browser_hometab_restore_defaults.js | When no prefs are changed button should not show up - "visible" == "hidden" - JS frame :: chrome://mochitests/content/browser/browser/components/preferences/in-content/tests/browser_hometab_restore_defaults.js :: testRestoreDefaultsBtn :: line 59
[task 2018-07-05T11:13:28.596Z]     INFO - Stack trace:
[task 2018-07-05T11:13:28.596Z]     INFO - chrome://mochitests/content/browser/browser/components/preferences/in-content/tests/browser_hometab_restore_defaults.js:testRestoreDefaultsBtn:59
[task 2018-07-05T11:13:28.597Z]     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1098
[task 2018-07-05T11:13:28.597Z]     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1089
[task 2018-07-05T11:13:28.598Z]     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:991
[task 2018-07-05T11:13:28.598Z]     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:795
[task 2018-07-05T11:13:33.735Z]     INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-07-05T11:13:33.737Z]     INFO - TEST-UNEXPECTED-FAIL | browser/components/preferences/in-content/tests/browser_hometab_restore_defaults.js | Uncaught exception - Should have checked preference - timed out after 50 tries.
[task 2018-07-05T11:13:33.738Z]     INFO - Leaving test bound testRestoreDefaultsBtn
[task 2018-07-05T11:13:33.776Z]     INFO - GECKO(1958) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2018-07-05T11:13:33.778Z]     INFO - GECKO(1958) | MEMORY STAT | vsize 2088MB | residentFast 301MB | heapAllocated 109MB
[task 2018-07-05T11:13:33.779Z]     INFO - TEST-OK | browser/components/preferences/in-content/tests/browser_hometab_restore_defaults.js | took 12093ms
[task 2018-07-05T11:13:33.780Z]     INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-07-05T11:13:33.781Z]     INFO - TEST-UNEXPECTED-FAIL | browser/components/preferences/in-content/tests/browser_hometab_restore_defaults.js | Found an unexpected tab at the end of test run: about:preferences#home - 
[task 2018-07-05T11:13:33.828Z]     INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-07-05T11:13:33.828Z]     INFO - TEST-UNEXPECTED-FAIL | browser/components/preferences/in-content/tests/browser_hometab_restore_defaults.js | Found an unexpected tab at the end of test run: about:preferences#home - 
[task 2018-07-05T11:13:33.893Z]     INFO - checking window state
[task 2018-07-05T11:13:34.826Z]     INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/FileUtils.jsm" line: 170}]
[task 2018-07-05T11:13:35.527Z]     INFO - GECKO(1958) | Completed ShutdownLeaks collections in process 2131
[task 2018-07-05T11:13:35.564Z]     INFO - GECKO(1958) | Completed ShutdownLeaks collections in process 2086
[task 2018-07-05T11:13:35.585Z]     INFO - GECKO(1958) | Completed ShutdownLeaks collections in process 2028
[task 2018-07-05T11:13:36.050Z]     INFO - GECKO(1958) | Completed ShutdownLeaks collections in process 1958
[task 2018-07-05T11:13:36.051Z]     INFO - TEST-START | Shutdown
[task 2018-07-05T11:13:36.052Z]     INFO - Browser Chrome Test Summary
[task 2018-07-05T11:13:36.055Z]     INFO - Passed:  0
[task 2018-07-05T11:13:36.055Z]     INFO - Failed:  5
[task 2018-07-05T11:13:36.055Z]     INFO - Todo:    0
[task 2018-07-05T11:13:36.056Z]     INFO - Mode:    e10s
[task 2018-07-05T11:13:36.059Z]     INFO - *** End BrowserChrome Test Results ***

Comment 1

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

This is a new test that landed in bug 1434751 (great to see new tests), :andreio can you fix this test to work on linux test-verify (appears to only fail on linux), or can you disable in in a manifest (examples: https://searchfox.org/mozilla-central/search?q=verify&path=browser.ini )

Comment 2

[Andrei Oprea [:andreio]]

Hitting an automation issue apparently https://treeherder.mozilla.org/#/jobs?repo=try&revision=34e089fa635d267dad0dfb00bddc9e1e5f536b28&selectedJob=186799970 but I'll continue to investigate.

Comment 3

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

I think the issue you are seeing is that there is an error in running the browser (possibly with your changes, or the base revision)- some errors found in a random log:
[task 2018-07-06T10:21:26.551Z] 10:21:26     INFO - GECKO(1027) | JavaScript error: chrome://global/content/bindings/browser.xml, line 393: TypeError: this.docShell is undefined
[task 2018-07-06T10:21:26.850Z] 10:21:26     INFO - GECKO(1027) | Extension error: this.webNavigation is undefined chrome://global/content/bindings/browser.xml:123 :: loadURI/<@chrome://global/content/bindings/browser.xml:123:15
[task 2018-07-06T10:21:28.093Z] 10:21:28     INFO - GECKO(1027) | JavaScript error: chrome://global/content/bindings/browser.xml, line 429: TypeError: this.contentDocument is undefined
[task 2018-07-06T10:21:28.155Z] 10:21:28     INFO - TEST-INFO | (browser-test.js) | Console message: [JavaScript Error: "TypeError: this.contentDocument is undefined" {file: "chrome://global/content/bindings/browser.xml" line: 429}]
[task 2018-07-06T10:21:28.156Z] 10:21:28     INFO - 
[task 2018-07-06T10:21:28.174Z] 10:21:28     INFO - GECKO(1027) | JavaScript error: chrome://global/content/bindings/browser.xml, line 393: TypeError: this.docShell is undefined
[task 2018-07-06T10:21:28.178Z] 10:21:28     INFO - TEST-INFO | (browser-test.js) | Console message: [JavaScript Error: "TypeError: this.docShell is undefined" {file: "chrome://global/content/bindings/browser.xml" line: 393}]
[task 2018-07-06T10:21:28.179Z] 10:21:28     INFO - 
[task 2018-07-06T10:21:28.431Z] 10:21:28     INFO - TEST-INFO | (browser-test.js) | Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/FileUtils.jsm" line: 170}]
[task 2018-07-06T10:21:28.432Z] 10:21:28     INFO - 
[task 2018-07-06T10:27:38.445Z] 10:27:38     INFO - Buffered messages finished
[task 2018-07-06T10:27:38.447Z] 10:27:38    ERROR - TEST-UNEXPECTED-TIMEOUT | automation.py | application timed out after 370 seconds with no output

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:
* autoland: 1

Platform breakdown:
* linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1473640&startday=2018-07-02&endday=2018-07-08&tree=trunk

Comment 5

[Andrei Oprea [:andreio]]

Attachment: Bug 1473640 - Update wait condition for Activity Stream mochitest to avoid Linux failure.

Review commit: https://reviewboard.mozilla.org/r/255780/diff/#index_header
See other reviews: https://reviewboard.mozilla.org/r/255780/

Comment 6

[Andrei Oprea [:andreio]]

Comment on attachment 8990692 [details]
Bug 1473640 - Update wait condition for Activity Stream mochitest to avoid Linux failure.

Review request updated; see interdiff: https://reviewboard.mozilla.org/r/255780/diff/1-2/

Comment 7

[Andrei Oprea [:andreio]]

https://treeherder.mozilla.org/#/jobs?repo=try&revision=6b328c00c6a3dc00af397b8e18d099dbe9ff229f
Did a couple of retries and it seems to pass whereas previously it would fail immediately.
Judging by the failure screenshot it looks like Activity Stream did not load yet so I added an extra wait condition for that.

Comment 8

[Ursula Sarracini (:ursula)]

Comment on attachment 8990692 [details]
Bug 1473640 - Update wait condition for Activity Stream mochitest to avoid Linux failure.

https://reviewboard.mozilla.org/r/255780/#review262534

Thanks!

Comment 9

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/08c76d30c604
Update wait condition for Activity Stream mochitest to avoid Linux failure. r=ursula

Comment 10

[Cosmin Sabou [:CosminS]]

https://hg.mozilla.org/mozilla-central/rev/08c76d30c604