1713975 - Intermittent browser/components/sessionstore/test/browser_sessionHistory.js | url is correct - Got "https://example.com/browser/browser/components/sessionstore/test/browser_scrollPositions_sample.html", expected "http://example.com/"

Status: RESOLVED FIXED

(Firefox :: Session Restore, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: abutkovits [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=341469070&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/eQHIIWW0Rdmt6y0zfrp_NQ/runs/0/artifacts/public/logs/live_backing.log

---

[task 2021-06-01T22:33:47.205Z] 22:33:47     INFO - TEST-START | browser/components/sessionstore/test/browser_sessionHistory.js
[task 2021-06-01T22:33:47.233Z] 22:33:47     INFO - GECKO(6412) | [Child 10467: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f24575bb400 == 1 [pid = 10467] [id = 25]
[task 2021-06-01T22:33:47.234Z] 22:33:47     INFO - GECKO(6412) | [Child 10467: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (7f245660c3c0) [pid = 10467] [serial = 56] [outer = 0]
[task 2021-06-01T22:33:47.235Z] 22:33:47     INFO - GECKO(6412) | [Child 10467: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (7f24575bdc00) [pid = 10467] [serial = 57] [outer = 7f245660c3c0]
[task 2021-06-01T22:33:47.337Z] 22:33:47     INFO - GECKO(6412) | [Child 10467: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 6 (7f24575c2000) [pid = 10467] [serial = 58] [outer = 7f245660c3c0]
[task 2021-06-01T22:33:47.374Z] 22:33:47     INFO - GECKO(6412) | [Child 15200: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f0784db0400 == 0 [pid = 15200] [id = 0] [url = about:reader?url=http%3A%2F%2Fexample.com%2Fbrowser%2Fbrowser%2Fcomponents%2Fsessionstore%2Ftest%2Fbrowser_scrollPositions_readerModeArticle.html]
[task 2021-06-01T22:33:47.452Z] 22:33:47     INFO - GECKO(6412) | [Child 15268: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f6d7c8b0400 == 1 [pid = 15268] [id = 0]
[task 2021-06-01T22:33:47.453Z] 22:33:47     INFO - GECKO(6412) | [Child 15268: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7f6d92f64c80) [pid = 15268] [serial = 1] [outer = 0]
[task 2021-06-01T22:33:47.459Z] 22:33:47     INFO - GECKO(6412) | [Child 15268: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7f6d7c8b7000) [pid = 15268] [serial = 2] [outer = 7f6d92f64c80]
[task 2021-06-01T22:33:47.511Z] 22:33:47     INFO - GECKO(6412) | [Child 15200: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7f0784db7000) [pid = 15200] [serial = 2] [outer = 0] [url = about:blank]
[task 2021-06-01T22:33:47.513Z] 22:33:47     INFO - GECKO(6412) | [Child 15200: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7f079b364c80) [pid = 15200] [serial = 1] [outer = 0] [url = about:reader?url=http%3A%2F%2Fexample.com%2Fbrowser%2Fbrowser%2Fcomponents%2Fsessionstore%2Ftest%2Fbrowser_scrollPositions_readerModeArticle.html]
[task 2021-06-01T22:33:47.516Z] 22:33:47     INFO - GECKO(6412) | [Child 15200: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7f077e1c0400) [pid = 15200] [serial = 3] [outer = 0] [url = about:reader?url=http%3A%2F%2Fexample.com%2Fbrowser%2Fbrowser%2Fcomponents%2Fsessionstore%2Ftest%2Fbrowser_scrollPositions_readerModeArticle.html]
[task 2021-06-01T22:33:47.520Z] 22:33:47     INFO - GECKO(6412) | [Child 15200, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-06-01T22:33:47.544Z] 22:33:47     INFO - GECKO(6412) | [Child 15200, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-06-01T22:33:47.565Z] 22:33:47     INFO - GECKO(6412) | [Child 15268, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-06-01T22:33:47.571Z] 22:33:47     INFO - GECKO(6412) | [Child 15268: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7f6d75cb8c00) [pid = 15268] [serial = 3] [outer = 7f6d92f64c80]
[task 2021-06-01T22:33:47.608Z] 22:33:47     INFO - GECKO(6412) | [Parent 6412, Main Thread] WARNING: WebProgress Ignored: BrowsingContext is null or discarded: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:2914
[task 2021-06-01T22:33:47.627Z] 22:33:47     INFO - GECKO(6412) | [Child 10336: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f3e7752d000 == 2 [pid = 10336] [id = 69]
[task 2021-06-01T22:33:47.627Z] 22:33:47     INFO - GECKO(6412) | [Child 10336: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7f3e77c17200) [pid = 10336] [serial = 169] [outer = 0]
[task 2021-06-01T22:33:47.628Z] 22:33:47     INFO - GECKO(6412) | [Child 10336: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (7f3e7752ec00) [pid = 10336] [serial = 170] [outer = 7f3e77c17200]
[task 2021-06-01T22:33:47.685Z] 22:33:47     INFO - GECKO(6412) | [Child 15273: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7fee3a1b0400 == 1 [pid = 15273] [id = 0]
[task 2021-06-01T22:33:47.685Z] 22:33:47     INFO - GECKO(6412) | [Child 15273: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7fee50864c80) [pid = 15273] [serial = 1] [outer = 0]
[task 2021-06-01T22:33:47.687Z] 22:33:47     INFO - GECKO(6412) | [Child 15273: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7fee3a1b6800) [pid = 15273] [serial = 2] [outer = 7fee50864c80]
[task 2021-06-01T22:33:47.744Z] 22:33:47     INFO - GECKO(6412) | [Child 10336: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f3e7752fc00 == 1 [pid = 10336] [id = 68] [url = about:blank]
[task 2021-06-01T22:33:47.746Z] 22:33:47     INFO - GECKO(6412) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-01T22:33:47.748Z] 22:33:47     INFO - GECKO(6412) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-01T22:33:47.809Z] 22:33:47     INFO - GECKO(6412) | [Parent 6412, Main Thread] WARNING: WebProgress Ignored: no longer current window global: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:2938
[task 2021-06-01T22:33:47.840Z] 22:33:47     INFO - GECKO(6412) | [Parent 6412, Main Thread] WARNING: WebProgress Ignored: no longer current window global: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:2938
[task 2021-06-01T22:33:47.843Z] 22:33:47     INFO - GECKO(6412) | [Parent 6412, Main Thread] WARNING: WebProgress Ignored: no longer current window global: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:2938
[task 2021-06-01T22:33:47.849Z] 22:33:47     INFO - GECKO(6412) | [Parent 6412, Main Thread] WARNING: WebProgress Ignored: no longer current window global: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:2938
[task 2021-06-01T22:33:47.850Z] 22:33:47     INFO - GECKO(6412) | [Parent 6412, Main Thread] WARNING: WebProgress Ignored: no longer current window global: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:2938
[task 2021-06-01T22:33:47.900Z] 22:33:47     INFO - GECKO(6412) | [Child 15268: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f6d7c8b0400 == 0 [pid = 15268] [id = 0] [url = http://example.com/]
[task 2021-06-01T22:33:47.964Z] 22:33:47     INFO - GECKO(6412) | [Child 15268: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7f6d92f64c80) [pid = 15268] [serial = 1] [outer = 0] [url = http://example.com/]
[task 2021-06-01T22:33:47.970Z] 22:33:47     INFO - GECKO(6412) | [Child 15268: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7f6d75cb8c00) [pid = 15268] [serial = 3] [outer = 0] [url = http://example.com/]
[task 2021-06-01T22:33:47.972Z] 22:33:47     INFO - GECKO(6412) | [Child 15268: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7f6d7c8b7000) [pid = 15268] [serial = 2] [outer = 0] [url = about:blank]
[task 2021-06-01T22:33:47.974Z] 22:33:47     INFO - GECKO(6412) | [Child 15268, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-06-01T22:33:48.009Z] 22:33:48     INFO - GECKO(6412) | [Child 15268, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-06-01T22:33:48.081Z] 22:33:48     INFO - GECKO(6412) | [Child 15273, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp:348
[task 2021-06-01T22:33:48.087Z] 22:33:48     INFO - GECKO(6412) | [Child 15273: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7fee335bf800) [pid = 15273] [serial = 3] [outer = 7fee50864c80]
[task 2021-06-01T22:33:48.229Z] 22:33:48     INFO - GECKO(6412) | [Child 9507: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (7f5d632a1c00) [pid = 9507] [serial = 129] [outer = 0] [url = about:blank]
[task 2021-06-01T22:33:48.230Z] 22:33:48     INFO - GECKO(6412) | [Child 9507: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (7f5d64bb9000) [pid = 9507] [serial = 127] [outer = 0] [url = about:newtab]
[task 2021-06-01T22:33:48.231Z] 22:33:48     INFO - GECKO(6412) | [Child 9507: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (7f5d6329a800) [pid = 9507] [serial = 126] [outer = 0] [url = about:blank]
[task 2021-06-01T22:33:48.303Z] 22:33:48     INFO - TEST-INFO | started process screentopng
[task 2021-06-01T22:33:48.667Z] 22:33:48     INFO - TEST-INFO | screentopng: exit 0
[task 2021-06-01T22:33:48.667Z] 22:33:48     INFO - Buffered messages logged at 22:33:47
[task 2021-06-01T22:33:48.667Z] 22:33:48     INFO - Entering test bound test_load_start
[task 2021-06-01T22:33:48.669Z] 22:33:48     INFO - Buffered messages finished
[task 2021-06-01T22:33:48.670Z] 22:33:48     INFO - TEST-UNEXPECTED-FAIL | browser/components/sessionstore/test/browser_sessionHistory.js | url is correct - Got "https://example.com/browser/browser/components/sessionstore/test/browser_scrollPositions_sample.html", expected "http://example.com/"
[task 2021-06-01T22:33:48.671Z] 22:33:48     INFO - Stack trace:
[task 2021-06-01T22:33:48.672Z] 22:33:48     INFO - chrome://mochikit/content/browser-test.js:test_is:1362
[task 2021-06-01T22:33:48.672Z] 22:33:48     INFO - chrome://mochitests/content/browser/browser/components/sessionstore/test/browser_sessionHistory.js:test_load_start:33
[task 2021-06-01T22:33:48.674Z] 22:33:48     INFO - Leaving test bound test_load_start

Comment 1

[Intermittent Failures Robot]

19 failures in 626 pushes (0.03 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 19

Platform and build breakdown:

• linux1804-64-asan: 11
  • opt: 11
• windows10-64-qr: 6
  • debug: 6
• linux1804-64-shippable: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1713975&startday=2021-06-02&endday=2021-06-02&tree=all

Comment 2

[Sandor Molnar[:smolnar]]

@kmadan, could you take a look at these failures ?

Backfill seems to indicate they started from your push.

Comment 3

[u608768]

Looking

Comment 4

[u608768]

Forgetting closed tabs before starting the test makes it fail with "Invalid index: not in the closed tabs": https://treeherder.mozilla.org/jobs?repo=try&revision=dfd6af44da7835620aa1ea1c5d4b360bfc51ba18, which means a) we're proceeding before we can write the history change to the TabStateCache, or b) we're not even recording that there was a history change in the listener.

Comment 5

[Intermittent Failures Robot]

45 failures in 598 pushes (0.075 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 43
• mozilla-central: 2

Platform and build breakdown:

• linux1804-64-asan: 31
  • opt: 31
• windows10-64-qr: 11
  • debug: 11
• linux1804-64: 3
  • debug: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1713975&startday=2021-06-03&endday=2021-06-03&tree=all

Comment 6

[Intermittent Failures Robot]

50 failures in 575 pushes (0.087 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 42
• try: 1
• mozilla-central: 7

Platform and build breakdown:

• windows10-64-qr: 12
  • debug: 12
• linux1804-64-asan: 34
  • opt: 34
• windows10-64: 1
  • debug: 1
• linux1804-64: 2
  • debug: 2
• macosx1015-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1713975&startday=2021-06-04&endday=2021-06-04&tree=all

Comment 7

[Intermittent Failures Robot]

18 failures in 96 pushes (0.188 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 6
• autoland: 12

Platform and build breakdown:

• linux1804-64: 4
  • debug: 4
• linux1804-64-asan: 6
  • opt: 6
• windows10-64-qr: 7
  • debug: 7
• linux1804-64-shippable: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1713975&startday=2021-06-05&endday=2021-06-05&tree=all

Comment 8

[Atila Butkovits]

Attachment: Bug 1713975 - disable browser_sessionHistory.js on Linux_opt for frequent failures. r=#intermittent-reviewers

Comment 9

[Intermittent Failures Robot]

151 failures in 3065 pushes (0.049 failures/push) were associated with this bug in the last 7 days.

This is the #4 most frequent failure this week.

** 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: 130
• mozilla-central: 18
• try: 3

Platform and build breakdown:

• linux1804-64: 15
  • debug: 13
  • opt: 2
• linux1804-64-asan: 89
  • opt: 89
• windows10-64-qr: 42
  • debug: 42
• linux1804-64-shippable: 3
  • opt: 3
• windows10-64: 1
  • debug: 1
• macosx1015-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1713975&startday=2021-05-31&endday=2021-06-06&tree=all

Comment 10

[BugBot [:suhaib / :marco/ :calixte]]

Set release status flags based on info from the regressing bug 1706445

Comment 11

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/ef0f52d797d9
disable browser_sessionHistory.js on Linux_opt for frequent failures. r=intermittent-reviewers,bhearsum

Comment 12

[Cristian Brindusan [:cbrindusan]]

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

Comment 13

[Intermittent Failures Robot]

49 failures in 681 pushes (0.072 failures/push) were associated with this bug yesterday.

Repository breakdown:

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

Platform and build breakdown:

• windows10-64-qr: 8
  • debug: 8
• linux1804-64-asan: 37
  • opt: 37
• linux1804-64: 4
  • debug: 3
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1713975&startday=2021-06-07&endday=2021-06-07&tree=all

Comment 14

[u608768]

Will be fixed in bug 1715264.

Comment 15

[u608768]

Leaving this open until bug 1715264 lands.

Comment 16

[u608768]

It landed.

Comment 17

[Intermittent Failures Robot]

20 failures in 621 pushes (0.032 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 4
• autoland: 16

Platform and build breakdown:

• macosx1015-64-qr: 13
  • debug: 5
  • opt: 8
• macosx1015-64-shippable-qr: 6
  • opt: 6
• linux1804-64: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1713975&startday=2021-06-10&endday=2021-06-10&tree=all

Comment 18

[Intermittent Failures Robot]

77 failures in 3502 pushes (0.022 failures/push) were associated with this bug in the last 7 days.

This is the #15 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: 67
• mozilla-central: 8
• try: 2

Platform and build breakdown:

• windows10-64-qr: 8
  • debug: 8
• linux1804-64-asan: 37
  • opt: 37
• linux1804-64: 8
  • debug: 7
  • opt: 1
• macosx1015-64-qr: 17
  • opt: 11
  • debug: 6
• macosx1015-64-shippable-qr: 7
  • opt: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1713975&startday=2021-06-07&endday=2021-06-13&tree=all