Closed Bug 1610374 Opened 3 years ago Closed 2 years ago

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

Categories

(Remote Protocol :: Agent, defect, P1)

defect

Tracking

(firefox74 disabled, firefox75 fixed)

RESOLVED FIXED
Firefox 75
Tracking Status
firefox74 --- disabled
firefox75 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: whimboo)

References

(Regression)

Details

(Keywords: intermittent-failure, Whiteboard: [puppeteer-beta-mvp])

Attachments

(2 files)

Duplicate of this bug: 1610376
Summary: Intermittent TV remote/test/browser/page/browser_navigateToHistoryEntry.js | Got expected current index - Got 2, expected 1 → Intermittent remote/test/browser/page/browser_navigateToHistoryEntry.js | Got expected current index - Got 2, expected 1
Depends on: 1610377

The patch on bug 1610377 should fix this failure.

Regressed by: 1609627

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.

Whiteboard: [stockwell needswork:owner]
Assignee: nobody → ccoroiu
Status: NEW → ASSIGNED

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?

Flags: needinfo?(hskupin)
Pushed by hskupin@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/abfe9c13a2f4
Disable browser_navigateToHistoryEntry.js on linux1804 and win for frequent failures r=remote-protocol-reviewers,whimboo

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

Flags: needinfo?(hskupin)
Keywords: leave-open
See Also: → 1610378
Assignee: ccoroiu → nobody
Status: ASSIGNED → NEW
Keywords: test-disabled
Whiteboard: [stockwell disable-recommended] → [stockwell disabled]

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.

Assignee: nobody → hskupin
Status: NEW → ASSIGNED
Priority: P5 → P1
Blocks: 1610378
See Also: 1610378

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

Whiteboard: [stockwell disabled] → [puppeteer-beta-mvp][stockwell disabled]
Pushed by hskupin@mozilla.com:
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

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.

Flags: needinfo?(hskupin)
Pushed by hskupin@mozilla.com:
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
Status: ASSIGNED → RESOLVED
Closed: 2 years ago
Resolution: --- → FIXED
Whiteboard: [puppeteer-beta-mvp][stockwell disabled] → [puppeteer-beta-mvp]
Target Milestone: --- → Firefox 75
You need to log in before you can comment on or make changes to this bug.