Open Bug 1381451 Opened 2 years ago Updated 6 months ago

Intermittent browser/components/sessionstore/test/browser_819510_perwindowpb.js | sessionstore state: 2 windows in data being written to disk - Got 3, expected 2

Categories

(Firefox :: Session Restore, defect, P4)

defect

Tracking

()

Tracking Status
firefox58 --- disabled
firefox59 --- disabled

People

(Reporter: aryx, Unassigned)

References

Details

(Keywords: intermittent-failure, leave-open, Whiteboard: [stockwell disabled])

+++ This bug was initially created as a clone of Bug #1341980 +++

https://treeherder.mozilla.org/logviewer.html#?job_id=114800510&repo=mozilla-inbound

10:50:19     INFO -  479 INFO TEST-START | browser/components/sessionstore/test/browser_819510_perwindowpb.js
10:50:21     INFO -  TEST-INFO | started process screenshot
10:50:21     INFO -  TEST-INFO | screenshot: exit 0
10:50:21     INFO -  Buffered messages logged at 10:50:19
10:50:21     INFO -  480 INFO Entering test bound test_1
10:50:21     INFO -  Buffered messages logged at 10:50:21
10:50:21     INFO -  481 INFO TEST-PASS | browser/components/sessionstore/test/browser_819510_perwindowpb.js | Browser has opened 5 windows -
10:50:21     INFO -  482 INFO TEST-PASS | browser/components/sessionstore/test/browser_819510_perwindowpb.js | Window is private -
10:50:21     INFO -  483 INFO TEST-PASS | browser/components/sessionstore/test/browser_819510_perwindowpb.js | Last window is private -
10:50:21     INFO -  484 INFO TEST-PASS | browser/components/sessionstore/test/browser_819510_perwindowpb.js | Last window opened is the one selected -
10:50:21     INFO -  Buffered messages finished
10:50:21    ERROR -  485 INFO TEST-UNEXPECTED-FAIL | browser/components/sessionstore/test/browser_819510_perwindowpb.js | sessionstore state: 2 windows in data being written to disk - Got 3, expected 2
10:50:21     INFO -  Stack trace:
10:50:21     INFO -  chrome://mochikit/content/browser-test.js:test_is:967
10:50:21     INFO -  chrome://mochitests/content/browser/browser/components/sessionstore/test/browser_819510_perwindowpb.js:test_1:30
10:50:21     INFO -  Not taking screenshot here: see the one that was previously logged
10:50:21    ERROR -  486 INFO TEST-UNEXPECTED-FAIL | browser/components/sessionstore/test/browser_819510_perwindowpb.js | Selected window is updated to match one of the saved windows - Got 3, expected 2
10:50:21     INFO -  Stack trace:
10:50:21     INFO -  chrome://mochikit/content/browser-test.js:test_is:967
10:50:21     INFO -  chrome://mochitests/content/browser/browser/components/sessionstore/test/browser_819510_perwindowpb.js:test_1:32
Priority: -- → P4
In the last 7 days there have been 36 failures.
Most of the failures occur on the Linux x64 platform and there are also some on the linux, linux32-stylo-disabled, linux64-stylo-disabled ones. The most affected build is the pgo one, but there are some failures on other build types like: opt, debug and asan.

Here is an example of a recent log: https://treeherder.mozilla.org/logviewer.html#?repo=autoland&job_id=140575239&lineNumber=3315
And a relevant snippet of the log:

[task 2017-10-29T23:08:13.077Z] 23:08:13     INFO - LoadInOtherProcess@chrome://browser/content/browser.js:1131:3
3301
[task 2017-10-29T23:08:13.078Z] 23:08:13     INFO - _loadURIWithFlags@chrome://browser/content/browser.js:1099:7
3302
[task 2017-10-29T23:08:13.079Z] 23:08:13     INFO - loadURIWithFlags@chrome://browser/content/tabbrowser.xml:8642:13
3303
[task 2017-10-29T23:08:13.079Z] 23:08:13     INFO - loadURI/<@chrome://global/content/bindings/browser.xml:114:15
3304
[task 2017-10-29T23:08:13.082Z] 23:08:13     INFO - _wrapURIChangeCall@chrome://global/content/bindings/browser.xml:48:15
3305
[task 2017-10-29T23:08:13.082Z] 23:08:13     INFO - loadURI@chrome://global/content/bindings/browser.xml:113:13
3306
[task 2017-10-29T23:08:13.083Z] 23:08:13     INFO - _createPreloadBrowser@chrome://browser/content/tabbrowser.xml:2202:13
3307
[task 2017-10-29T23:08:13.083Z] 23:08:13     INFO - _handleNewTab@chrome://browser/content/tabbrowser.xml:7152:11
3308
[task 2017-10-29T23:08:13.084Z] 23:08:13     INFO - onxbltransitionend@chrome://browser/content/tabbrowser.xml:7194:13
3309
[task 2017-10-29T23:08:13.091Z] 23:08:13     INFO - 
3310
[task 2017-10-29T23:08:13.092Z] 23:08:13     INFO - TEST-PASS | browser/components/sessionstore/test/browser_819510_perwindowpb.js | Browser has opened 5 windows - 
3311
[task 2017-10-29T23:08:13.093Z] 23:08:13     INFO - TEST-PASS | browser/components/sessionstore/test/browser_819510_perwindowpb.js | Window is private - 
3312
[task 2017-10-29T23:08:13.094Z] 23:08:13     INFO - TEST-PASS | browser/components/sessionstore/test/browser_819510_perwindowpb.js | Last window is private - 
3313
[task 2017-10-29T23:08:13.094Z] 23:08:13     INFO - TEST-PASS | browser/components/sessionstore/test/browser_819510_perwindowpb.js | Last window opened is the one selected - 
3314
[task 2017-10-29T23:08:13.095Z] 23:08:13     INFO - Buffered messages finished
3315
[task 2017-10-29T23:08:13.096Z] 23:08:13     INFO - TEST-UNEXPECTED-FAIL | browser/components/sessionstore/test/browser_819510_perwindowpb.js | sessionstore state: 2 windows in data being written to disk - Got 3, expected 2
3316
[task 2017-10-29T23:08:13.096Z] 23:08:13     INFO - Stack trace:
3317
[task 2017-10-29T23:08:13.097Z] 23:08:13     INFO - chrome://mochikit/content/browser-test.js:test_is:1011
3318
[task 2017-10-29T23:08:13.098Z] 23:08:13     INFO - chrome://mochitests/content/browser/browser/components/sessionstore/test/browser_819510_perwindowpb.js:test_1:30
3319
[task 2017-10-29T23:08:13.098Z] 23:08:13     INFO - Not taking screenshot here: see the one that was previously logged


:mikedeboer, could you please take a look?
Flags: needinfo?(mdeboer)
Whiteboard: [stockwell needswork]
I suspect this might be related to switching from single-core slow machines in AWS -> multi core faster machines (bug 1408506). Maybe that will be a hint in fixing this.
There have been 48 failures related to this in the last 7 days.


:mikedeboer, do you have any updates on this?
In the last week there have been 54 failures:

Number of occurrences / affected platforms : 
- 23 on linux x64
- 13 on linux32-stylo-disabled
- 11 on linux
- 6  on linux64-stylo-disabled
- 1  on linux64-nightly

Number of occurrences / affected build type:
- 29 on opt
- 16 on pgo
- 8  on debug
- 1  on asan

Here is an example of a recent log:
https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=144826317&lineNumber=3730

And a relevant snippet from it:

[task 2017-11-15T03:30:27.280Z] 03:30:27     INFO - TEST-PASS | browser/components/sessionstore/test/browser_819510_perwindowpb.js | Browser has opened 5 windows - 
3726
[task 2017-11-15T03:30:27.280Z] 03:30:27     INFO - TEST-PASS | browser/components/sessionstore/test/browser_819510_perwindowpb.js | Window is private - 
3727
[task 2017-11-15T03:30:27.282Z] 03:30:27     INFO - TEST-PASS | browser/components/sessionstore/test/browser_819510_perwindowpb.js | Last window is private - 
3728
[task 2017-11-15T03:30:27.283Z] 03:30:27     INFO - TEST-PASS | browser/components/sessionstore/test/browser_819510_perwindowpb.js | Last window opened is the one selected - 
3729
[task 2017-11-15T03:30:27.284Z] 03:30:27     INFO - Buffered messages finished
3730
[task 2017-11-15T03:30:27.285Z] 03:30:27     INFO - TEST-UNEXPECTED-FAIL | browser/components/sessionstore/test/browser_819510_perwindowpb.js | sessionstore state: 2 windows in data being written to disk - Got 3, expected 2
3731
[task 2017-11-15T03:30:27.286Z] 03:30:27     INFO - Stack trace:
3732
[task 2017-11-15T03:30:27.287Z] 03:30:27     INFO - chrome://mochikit/content/browser-test.js:test_is:1269
3733
[task 2017-11-15T03:30:27.291Z] 03:30:27     INFO - chrome://mochitests/content/browser/browser/components/sessionstore/test/browser_819510_perwindowpb.js:test_1:30
3734
[task 2017-11-15T03:30:27.291Z] 03:30:27     INFO - Not taking screenshot here: see the one that was previously logged
3735
[task 2017-11-15T03:30:27.292Z] 03:30:27     INFO - TEST-UNEXPECTED-FAIL | browser/components/sessionstore/test/browser_819510_perwindowpb.js | Selected window is updated to match one of the saved windows - Got 3, expected 2
3736
[task 2017-11-15T03:30:27.292Z] 03:30:27     INFO - Stack trace:
3737
[task 2017-11-15T03:30:27.293Z] 03:30:27     INFO - chrome://mochikit/content/browser-test.js:test_is:1269
3738
[task 2017-11-15T03:30:27.293Z] 03:30:27     INFO - chrome://mochitests/content/browser/browser/components/sessionstore/test/browser_819510_perwindowpb.js:test_1:32
3739
[task 2017-11-15T03:30:27.294Z] 03:30:27     INFO - TEST-PASS | browser/components/sessionstore/test/browser_819510_perwindowpb.js | Saved windows are not private - 
3740
[task 2017-11-15T03:30:27.296Z] 03:30:27     INFO - TEST-PASS | browser/components/sessionstore/test/browser_819510_perwindowpb.js | sessionstore state: no closed windows in data being written to disk - 
3741
[task 2017-11-15T03:30:27.296Z] 03:30:27     INFO - Leaving test bound test_1
See Also: → 1284312, 1341980
Pushed by gbrown@mozilla.com:
https://hg.mozilla.org/integration/mozilla-inbound/rev/8918e897abce
Disable browser-chrome test browser_819510_perwindowpb.js on linux for frequent intermittent failures; r=me, a=test-only
Keywords: leave-open
Whiteboard: [stockwell disable-recommended] → [stockwell disabled]
During Quantum/ Photon I wasn't able to take a look at this, but now I might actually. Leaving the n-i for that reason.
Flags: needinfo?(mdeboer)
There have been 35 failures in the last 7 days.
All of the failures occur on the debug build type.
Failures per platform:

windows10-32-msvc: 4
windows7-32: 31

Summary: browser/components/sessionstore/test/browser_819510_perwindowpb.js | sessionstore state: 2 windows in data being written to disk - Got 3, expected 2

Recent relevant log file: https://treeherder.mozilla.org/logviewer.html#?job_id=205254866&repo=mozilla-inbound&lineNumber=16540
There are 44 failures asociated to this bug in the last 7 days. These are occurring on windows7-32 debug.

:mikedeboer any updates on this?
Flags: needinfo?(mdeboer)
Whiteboard: [stockwell disabled] → [stockwell disabled][stockwell needswork]
This test is already skipped everywhere but windows 7:

skip-if = (os == 'win' && bits == 64) || (os == "mac") || (os == "linux") # Win: Bug 1284312, Mac: Bug 1341980, Linux: bug 1381451
In the last 7 days there have been 43 occurrences on Windows 7 32, debug.
In the last 7 days there are 33 new failures for this bug, with a spike in 3rd of Nov.
This occurs only on windows7-32 platform, debug build type.

Here is the most recent log link:
https://treeherder.mozilla.org/logviewer.html#?job_id=210639438&repo=autoland&lineNumber=16808

Log snippet:

19:51:39     INFO - TEST-INFO | screenshot: exit 0
19:51:39     INFO - Buffered messages logged at 19:51:34
19:51:39     INFO - Entering test bound test_1
19:51:39     INFO - Buffered messages logged at 19:51:39
19:51:39     INFO - TEST-PASS | browser/components/sessionstore/test/browser_819510_perwindowpb.js | Browser has opened 5 windows - 
19:51:39     INFO - TEST-PASS | browser/components/sessionstore/test/browser_819510_perwindowpb.js | Window is private - 
19:51:39     INFO - TEST-PASS | browser/components/sessionstore/test/browser_819510_perwindowpb.js | Last window is private - 
19:51:39     INFO - TEST-PASS | browser/components/sessionstore/test/browser_819510_perwindowpb.js | Last window opened is the one selected - 
19:51:39     INFO - Buffered messages finished
19:51:39     INFO - TEST-UNEXPECTED-FAIL | browser/components/sessionstore/test/browser_819510_perwindowpb.js | sessionstore state: 2 windows in data being written to disk - Got 3, expected 2
19:51:39     INFO - Stack trace:
19:51:39     INFO - chrome://mochikit/content/browser-test.js:test_is:1308
19:51:39     INFO - chrome://mochitests/content/browser/browser/components/sessionstore/test/browser_819510_perwindowpb.js:test_1:30
19:51:39     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1098
19:51:39     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1089
19:51:39     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:987
19:51:39     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:803
19:51:39     INFO - Not taking screenshot here: see the one that was previously logged
19:51:39     INFO - TEST-UNEXPECTED-FAIL | browser/components/sessionstore/test/browser_819510_perwindowpb.js | Selected window is updated to match one of the saved windows - Got 3, expected 2
19:51:39     INFO - Stack trace:
19:51:39     INFO - chrome://mochikit/content/browser-test.js:test_is:1308
19:51:39     INFO - chrome://mochitests/content/browser/browser/components/sessionstore/test/browser_819510_perwindowpb.js:test_1:32
19:51:39     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1098
19:51:39     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1089
19:51:39     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:987
19:51:39     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:803
This bug failed 43 times in the last 7 days. This occurs on windows7-32 on debug build type.

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

mikedeboer: Can you please take a look at this bug?
(In reply to Geoff Brown [:gbrown] from comment #49)
> This test is already skipped everywhere but windows 7:
> 
> skip-if = (os == 'win' && bits == 64) || (os == "mac") || (os == "linux") #
> Win: Bug 1284312, Mac: Bug 1341980, Linux: bug 1381451

Should we disable it on windows7-32 as well?  i see that there is no other progress here.
Flags: needinfo?(gbrown)
Pushed by gbrown@mozilla.com:
https://hg.mozilla.org/integration/mozilla-inbound/rev/d57891d03e68
Skip test browser_819510_perwindowpb.js on remaining platforms; r=me,a=test-only
Flags: needinfo?(gbrown)
Whiteboard: [stockwell disabled][stockwell needswork] → [stockwell disabled]
Flags: needinfo?(mdeboer)
You need to log in before you can comment on or make changes to this bug.