Filed by: rmaries [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=285671973&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/UUTcer55QZicfvUFfKoSLA/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/UUTcer55QZicfvUFfKoSLA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

The patch on bug 1610377 should fix this failure.

There are 89 total failures in the last 7 days on

• linux1804-64 debug and opt
• linux1804-64-asan opt
• linux1804-64-ccov opt
• linux1804-64-shippable opt
• windows10-64 asan, opt and debug
• windows7-32 opt and debug

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

[task 2020-01-26T04:51:40.320Z] 04:51:40 INFO - TEST-START | remote/test/browser/page/browser_navigateToHistoryEntry.js
[task 2020-01-26T04:51:40.348Z] 04:51:40 INFO - GECKO(3300) | [Parent 3300: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f34289ee000 == 11 [pid = 3300] [id = {a8a3d6e1-6d9c-45f4-8787-00ca848f1529}]
[task 2020-01-26T04:51:40.349Z] 04:51:40 INFO - GECKO(3300) | [Parent 3300: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 57 (0x7f34288b76a0) [pid = 3300] [serial = 137] [outer = (nil)]
[task 2020-01-26T04:51:40.350Z] 04:51:40 INFO - GECKO(3300) | [Parent 3300: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 58 (0x7f342afe5c00) [pid = 3300] [serial = 138] [outer = 0x7f34288b76a0]
[task 2020-01-26T04:51:40.351Z] 04:51:40 INFO - GECKO(3300) | [Parent 3300: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 59 (0x7f342b4b8800) [pid = 3300] [serial = 139] [outer = 0x7f34288b76a0]
[task 2020-01-26T04:51:40.445Z] 04:51:40 INFO - GECKO(3300) | [Parent 3300, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, nullptr) failed with result 0x804B000A: file /builds/worker/workspace/build/src/extensions/permissions/nsPermission.cpp, line 45
[task 2020-01-26T04:51:40.446Z] 04:51:40 INFO - GECKO(3300) | [Parent 3300, Main Thread] WARNING: NS_ENSURE_TRUE(principal) failed: file /builds/worker/workspace/build/src/extensions/permissions/nsPermission.cpp, line 57
[task 2020-01-26T04:51:40.446Z] 04:51:40 INFO - GECKO(3300) | [Parent 3300, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, nullptr) failed with result 0x804B000A: file /builds/worker/workspace/build/src/extensions/permissions/nsPermission.cpp, line 45
[task 2020-01-26T04:51:40.446Z] 04:51:40 INFO - GECKO(3300) | [Parent 3300, Main Thread] WARNING: NS_ENSURE_TRUE(principal) failed: file /builds/worker/workspace/build/src/extensions/permissions/nsPermission.cpp, line 57
[task 2020-01-26T04:51:40.463Z] 04:51:40 INFO - GECKO(3300) | [Child 3447: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f2ce0c53000 == 2 [pid = 3447] [id = {50eb89e3-4619-4c6f-8945-149ae6ca3adf}]
[task 2020-01-26T04:51:40.464Z] 04:51:40 INFO - GECKO(3300) | [Child 3447: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f2ce0cd43e0) [pid = 3447] [serial = 92] [outer = (nil)]

[task 2020-01-26T04:51:53.195Z] 04:51:53 INFO - TEST-PASS | remote/test/browser/page/browser_navigateToHistoryEntry.js | Expected URL loaded -
[task 2020-01-26T04:51:53.195Z] 04:51:53 INFO - CDP client closed
[task 2020-01-26T04:51:53.195Z] 04:51:53 INFO - CDP server stopped
[task 2020-01-26T04:51:53.195Z] 04:51:53 INFO - Leaving test bound toFirstEntryInHistory
[task 2020-01-26T04:51:53.195Z] 04:51:53 INFO - Entering test bound toLastEntryInHistory
[task 2020-01-26T04:51:53.196Z] 04:51:53 INFO - CDP server started
[task 2020-01-26T04:51:53.196Z] 04:51:53 INFO - Buffered messages logged at 04:51:52
[task 2020-01-26T04:51:53.196Z] 04:51:53 INFO - CDP client instantiated
[task 2020-01-26T04:51:53.199Z] 04:51:53 INFO - Buffered messages finished
[task 2020-01-26T04:51:53.199Z] 04:51:53 INFO - TEST-UNEXPECTED-FAIL | remote/test/browser/page/browser_navigateToHistoryEntry.js | Got expected current index - Got 0, expected 2
[task 2020-01-26T04:51:53.199Z] 04:51:53 INFO - Stack trace:
[task 2020-01-26T04:51:53.199Z] 04:51:53 INFO - chrome://mochikit/content/browser-test.js:test_is:1320
[task 2020-01-26T04:51:53.200Z] 04:51:53 INFO - chrome://mochitests/content/browser/remote/test/browser/page/head.js:assertHistoryEntries:16
[task 2020-01-26T04:51:53.200Z] 04:51:53 INFO - chrome://mochitests/content/browser/remote/test/browser/page/browser_navigateToHistoryEntry.js:toLastEntryInHistory:120
[task 2020-01-26T04:51:53.200Z] 04:51:53 INFO - chrome://mochitests/content/browser/remote/test/browser/head.js:fn:67
[task 2020-01-26T04:51:53.200Z] 04:51:53 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1062
[task 2020-01-26T04:51:53.200Z] 04:51:53 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1097
[task 2020-01-26T04:51:53.200Z] 04:51:53 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:925
[task 2020-01-26T04:51:53.200Z] 04:51:53 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:808
[task 2020-01-26T04:51:53.200Z] 04:51:53 INFO - TEST-PASS | remote/test/browser/page/browser_navigateToHistoryEntry.js | Found expected count of history entries -
[task 2020-01-26T04:51:53.201Z] 04:51:53 INFO - TEST-PASS | remote/test/browser/page/browser_navigateToHistoryEntry.js | History entry has an id set -
[task 2020-01-26T04:51:53.201Z] 04:51:53 INFO - TEST-PASS | remote/test/browser/page/browser_navigateToHistoryEntry.js | History entry has the correct URL set -
[task 2020-01-26T04:51:53.201Z] 04:51:53 INFO - TEST-PASS | remote/test/browser/page/browser_navigateToHistoryEntry.js | History entry has the correct user typed URL set -
[task 2020-01-26T04:51:53.201Z] 04:51:53 INFO - TEST-PASS | remote/test/browser/page/browser_navigateToHistoryEntry.js | History entry has the correct title set -
[task 2020-01-26T04:51:53.205Z] 04:51:53 INFO - TEST-PASS | remote/test/browser/page/browser_navigateToHistoryEntry.js | History entry has an id set -
[task 2020-01-26T04:51:53.205Z] 04:51:53 INFO - TEST-PASS | remote/test/browser/page/browser_navigateToHistoryEntry.js | History entry has the correct URL set -
[task 2020-01-26T04:51:53.205Z] 04:51:53 INFO - TEST-PASS | remote/test/browser/page/browser_navigateToHistoryEntry.js | History entry has the correct user typed URL set -
[task 2020-01-26T04:51:53.205Z] 04:51:53 INFO - TEST-PASS | remote/test/browser/page/browser_navigateToHistoryEntry.js | History entry has the correct title set -
[task 2020-01-26T04:51:53.205Z] 04:51:53 INFO - TEST-PASS | remote/test/browser/page/browser_navigateToHistoryEntry.js | History entry has an id set -
[task 2020-01-26T04:51:53.205Z] 04:51:53 INFO - TEST-PASS | remote/test/browser/page/browser_navigateToHistoryEntry.js | History entry has the correct URL set -
[task 2020-01-26T04:51:53.205Z] 04:51:53 INFO - TEST-PASS | remote/test/browser/page/browser_navigateToHistoryEntry.js | History entry has the correct user typed URL set -
[task 2020-01-26T04:51:53.205Z] 04:51:53 INFO - TEST-PASS | remote/test/browser/page/browser_navigateToHistoryEntry.js | History entry has the correct title set -
[task 2020-01-26T04:51:53.206Z] 04:51:53 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-01-26T04:51:53.210Z] 04:51:53 INFO - TEST-UNEXPECTED-FAIL | remote/test/browser/page/browser_navigateToHistoryEntry.js | Expected URL loaded - Got data:text/html;charset=utf-8,%3C!doctype%20html%3E%0A%3Cmeta%20charset%3Dutf-8%3E%0A%3Chead%3E%3Ctitle%3ETest%201%3C%2Ftitle%3E%3C%2Fhead%3E, expected data:text/html;charset=utf-8,%3C!doctype%20html%3E%0A%3Cmeta%20charset%3Dutf-8%3E%0A%3Chead%3E%3Ctitle%3ETest%203%3C%2Ftitle%3E%3C%2Fhead%3E
[task 2020-01-26T04:51:53.210Z] 04:51:53 INFO - Stack trace:
[task 2020-01-26T04:51:53.210Z] 04:51:53 INFO - chrome://mochikit/content/browser-test.js:test_is:1320
[task 2020-01-26T04:51:53.210Z] 04:51:53 INFO - chrome://mochitests/content/browser/remote/test/browser/page/browser_navigateToHistoryEntry.js:toLastEntryInHistory:122
[task 2020-01-26T04:51:53.210Z] 04:51:53 INFO - chrome://mochitests/content/browser/remote/test/browser/head.js:fn:67
[task 2020-01-26T04:51:53.211Z] 04:51:53 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1062
[task 2020-01-26T04:51:53.211Z] 04:51:53 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1097
[task 2020-01-26T04:51:53.211Z] 04:51:53 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:925
[task 2020-01-26T04:51:53.211Z] 04:51:53 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:808

Following progress on bug 1610377.

This bug is now in the disable-recommended list, I've made a patch if disable is needed.

Henrik, do you want us to wait for the patch from Bug 1610377?

Lets skip for now. The other bug takes longer than expected.

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

The patch on bug 1610377 will not fix that particular issue. Reason is that on slow machines the history index selection is slow, and when the command returns we still have the old index selected. Similar to bug 1610377 I would suggest to wait for the requested index to be selected.

Important regression / intermittent which will be fixed soon, and the test reenabled.

Backed out changeset 2aad23c07f68 for causing lint failures in remote/test/browser/page/head.js

Backout link: https://hg.mozilla.org/integration/autoland/rev/9db39cd56bbaca8884e6c74f543dc85295598bf6

Push with failures: https://treeherder.mozilla.org/#/jobs?repo=autoland&resultStatus=testfailed%2Cbusted%2Cexception&tochange=10d15000963d18997b0e8d96ef4ee8a8bd4e033b&fromchange=2aad23c07f68cda6094bc89f32ddc39087317f23&selectedJob=289028726

Failure logs:

• lint failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=289026281&repo=autoland&lineNumber=321
• remote failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=289028726&repo=autoland&lineNumber=2614

Sorry, something went wrong with the latest rebase and the import of PollPromise dropped from the head.js file. I will update and push once more now.

https://hg.mozilla.org/mozilla-central/rev/308dd8bb9e06