1610374 - Intermittent remote/test/browser/page/browser_navigateToHistoryEntry.js | Got expected current index - Got 2, expected 1

Status: RESOLVED FIXED

(Remote Protocol :: Agent, defect, P1)

Description

[Treeherder Bug Filer]

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

---

Comment 2

[Intermittent Failures Robot]

19 failures in 732 pushes (0.026 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 5
• autoland: 14

Platform breakdown:

• linux1804-64-asan: 6
• linux1804-64: 6
• linux1804-64-shippable: 4
• windows10-64-shippable: 1
• windows10-64: 1
• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1610374&startday=2020-01-21&endday=2020-01-21&tree=all

Comment 3

[Intermittent Failures Robot]

22 failures in 827 pushes (0.027 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 9
• try: 7
• mozilla-central: 6

Platform breakdown:

• windows7-32: 1
• linux1804-64: 11
• linux64: 2
• linux1804-64-asan: 6
• windows10-aarch64: 1
• linux1804-64-ccov: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1610374&startday=2020-01-22&endday=2020-01-22&tree=all

Comment 4

[Henrik Skupin [:whimboo][⌚️UTC+2]]

The patch on bug 1610377 should fix this failure.

Comment 5

[Intermittent Failures Robot]

20 failures in 838 pushes (0.024 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 17
• try: 1
• mozilla-central: 2

Platform breakdown:

• linux1804-64-asan: 5
• windows10-64: 3
• linux1804-64-shippable: 2
• linux1804-64: 7
• linux1804-64-ccov: 1
• windows7-32: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1610374&startday=2020-01-23&endday=2020-01-23&tree=all

Comment 6

[Intermittent Failures Robot]

20 failures in 694 pushes (0.029 failures/push) were associated with this bug yesterday.

Repository breakdown:

• try: 2
• autoland: 9
• mozilla-central: 9

Platform breakdown:

• linux1804-64: 11
• linux1804-64-shippable: 3
• linux1804-64-asan: 3
• linux1804-64-ccov: 1
• windows10-64: 1
• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1610374&startday=2020-01-24&endday=2020-01-24&tree=all

Comment 7

[Andreea Pavel [:apavel]]

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.

Comment 8

[Intermittent Failures Robot]

113 failures in 4004 pushes (0.028 failures/push) were associated with this bug in the last 7 days.

This is the #6 most frequent failure this week.

** This failure happened more than 75 times this week! Resolving this bug is a very high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 1 week, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 72
• mozilla-central: 28
• try: 13

Platform breakdown:

• linux1804-64: 46
• windows7-32: 8
• linux1804-64-shippable: 11
• windows7-32-shippable: 2
• linux1804-64-asan: 29
• windows10-64-shippable: 2
• windows10-64: 7
• linux64: 2
• linux1804-64-ccov: 5
• windows10-aarch64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1610374&startday=2020-01-20&endday=2020-01-26&tree=all

Comment 9

[Intermittent Failures Robot]

15 failures in 170 pushes (0.088 failures/push) were associated with this bug yesterday.

** This test has failed more than 150 times in the last 21 days. It should be disabled until it can be fixed. **

Repository breakdown:

• autoland: 9
• mozilla-central: 6

Platform breakdown:

• linux1804-64-asan: 7
• linux1804-64: 4
• linux1804-64-shippable: 2
• linux64: 1
• windows7-32: 1

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

Comment 10

[Cristina Coroiu [:ccoroiu]]

Attachment: Bug 1610374 - Disable browser_navigateToHistoryEntry.js on linux1804 and win for frequent failures r=#intermittent-reviewers

Comment 11

[Cristina Coroiu [:ccoroiu]]

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?

Comment 12

[Intermittent Failures Robot]

78 failures in 2190 pushes (0.036 failures/push) were associated with this bug in the last 7 days.

This is the #3 most frequent failure this week.

** This failure happened more than 75 times this week! Resolving this bug is a very high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 1 week, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 51
• mozilla-central: 23
• try: 4

Platform breakdown:

• linux1804-64-asan: 29
• windows10-64: 3
• linux1804-64: 29
• linux1804-64-ccov: 3
• linux1804-64-shippable: 7
• macosx1014-64: 2
• windows7-32: 4
• linux64: 1

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

Comment 13

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/abfe9c13a2f4
Disable browser_navigateToHistoryEntry.js on linux1804 and win for frequent failures r=remote-protocol-reviewers,whimboo

Comment 14

[Henrik Skupin [:whimboo][⌚️UTC+2]]

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

Comment 15

[Natalia Csoregi [:nataliaCs]]

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

Comment 16

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4
• mozilla-central: 2
• try: 1

Platform breakdown:

• linux1804-64-asan: 1
• linux1804-64: 2
• linux1804-64-shippable: 2
• linux1804-64-ccov: 1
• macosx1014-64: 1

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

Comment 17

[Henrik Skupin [:whimboo][⌚️UTC+2]]

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.

Comment 18

[Henrik Skupin [:whimboo][⌚️UTC+2]]

Attachment: Bug 1610374 - [remote] Page.navigateToHistoryEntry() has to wait for requested index been set.

Comment 19

[Henrik Skupin [:whimboo][⌚️UTC+2]]

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

Comment 20

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/2aad23c07f68
[remote] Page.navigateToHistoryEntry() has to wait for requested index been set. r=remote-protocol-reviewers,maja_zf

Comment 21

[Alexandru Michis [:malexandru]]

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

Comment 22

[Henrik Skupin [:whimboo][⌚️UTC+2]]

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.

Comment 23

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/308dd8bb9e06
[remote] Page.navigateToHistoryEntry() has to wait for requested index been set. r=remote-protocol-reviewers,maja_zf

Comment 24

[Natalia Csoregi [:nataliaCs]]

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

Comment 25

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1
• autoland: 3
• try: 1

Platform breakdown:

• macosx1014-64: 2
• macosx1014-64-shippable: 2
• linux1804-64: 1

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