1423386 - Intermittent browser/components/originattributes/test/browser/browser_cache.js | Test timed out -

Status: RESOLVED FIXED

(Core :: DOM: Security, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: csabou [at] mozilla.com

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

https://queue.taskcluster.net/v1/task/ZPCb7_IFSJyVO76UmZxLxg/runs/0/artifacts/public/logs/live_backing.log

Comment 1

[Intermittent Failures Robot]

9 failures in 889 pushes (0.01 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* try: 4
* mozilla-inbound: 4
* mozilla-central: 1

Platform breakdown:
* linux32-stylo-disabled: 3
* linux64: 2
* linux32: 2
* linux64-stylo-disabled: 1
* linux64-nightly: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1423386&startday=2017-12-04&endday=2017-12-10&tree=all

Comment 2

[Intermittent Failures Robot]

26 failures in 170 pushes (0.153 failures/push) were associated with this bug yesterday.    

Repository breakdown:
* autoland: 15
* mozilla-inbound: 8
* mozilla-central: 3

Platform breakdown:
* linux32: 9
* linux64: 8
* linux32-stylo-disabled: 5
* linux64-stylo-disabled: 4

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1423386&startday=2017-12-21&endday=2017-12-21&tree=all

Comment 3

[Dorel Luca [:dluca]]

This bug has failed 35 times in the last 7 days, on Linux and Linux32-64 Stylo-Desabled on debug, pgo and opt build types.

Here is a recent log: 
https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=153058679

And here is a part of the log:

[task 2017-12-22T00:39:04.658Z] 00:39:04     INFO - 
[task 2017-12-22T00:39:04.659Z] 00:39:04     INFO - Buffered messages finished
[task 2017-12-22T00:39:04.660Z] 00:39:04     INFO - TEST-UNEXPECTED-FAIL | browser/components/originattributes/test/browser/browser_cache.js | Test timed out - 
[task 2017-12-22T00:39:04.661Z] 00:39:04     INFO - GECKO(2018) | MEMORY STAT | vsize 2331MB | residentFast 283MB | heapAllocated 79MB
[task 2017-12-22T00:39:04.662Z] 00:39:04     INFO - TEST-OK | browser/components/originattributes/test/browser/browser_cache.js | took 360215ms
[task 2017-12-22T00:39:04.663Z] 00:39:04     INFO - Not taking screenshot here: see the one that was previously logged
[task 2017-12-22T00:39:04.664Z] 00:39:04     INFO - TEST-UNEXPECTED-FAIL | browser/components/originattributes/test/browser/browser_cache.js | Found a tab after previous test timed out: http://example.net/browser/browser/components/originattributes/test/browser/file_cache.html - 
[task 2017-12-22T00:39:04.665Z] 00:39:04     INFO - Not taking screenshot here: see the one that was previously logged
[task 2017-12-22T00:39:04.666Z] 00:39:04     INFO - TEST-UNEXPECTED-FAIL | browser/components/originattributes/test/browser/browser_cache.js | Found a tab after previous test timed out: http://example.net/browser/browser/components/originattributes/test/browser/file_cache.html - 
[task 2017-12-22T00:39:04.667Z] 00:39:04     INFO - GECKO(2018) | --DOCSHELL 0x7f6d19657000 == 4 [pid = 2072] [id = {b2ac7449-602e-4df2-9e6e-bdd117364643}]
[task 2017-12-22T00:39:04.668Z] 00:39:04     INFO - GECKO(2018) | ++DOCSHELL 0x7f0299d33800 == 1 [pid = 2119] [id = {3181b013-831a-4bb4-8668-509d5afd2bbc}]
[task 2017-12-22T00:39:04.669Z] 00:39:04     INFO - GECKO(2018) | ++DOMWINDOW == 1 (0x7f02a0586660) [pid = 2119] [serial = 3] [outer = (nil)]
[task 2017-12-22T00:39:04.670Z] 00:39:04     INFO - GECKO(2018) | --DOCSHELL 0x7f6d191c6800 == 3 [pid = 2072] [id = {89421e09-9f71-4797-b7a4-18993b6443e2}]
[task 2017-12-22T00:39:04.671Z] 00:39:04     INFO - checking window state 

Hi :ckerschb, Can you please take a look at this bug?

Comment 4

[Intermittent Failures Robot]

59 failures in 590 pushes (0.1 failures/push) were associated with this bug in the last 7 days. 

This is the #14 most frequent failure this week.  

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

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

Repository breakdown:
* autoland: 24
* mozilla-central: 16
* mozilla-inbound: 14
* try: 5

Platform breakdown:
* linux64-stylo-disabled: 15
* linux64: 15
* linux32-stylo-disabled: 14
* linux32: 14
* linux64-nightly: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1423386&startday=2017-12-18&endday=2017-12-24&tree=all

Comment 5

[Eliza Balazs [:ebalazs_]]

There are 52 failures in the last 7 days.
They occur linux 32 and Linux x64. The affected builds type are asan, debug, opt, pgo.
Here is a recent log example: https://treeherder.mozilla.org/logviewer.html#?repo=autoland&job_id=153590002&lineNumber=2974
and a relevant part of the log:
[task 2017-12-30T01:37:27.968Z] 01:37:27     INFO - TEST-START | browser/components/originattributes/test/browser/browser_favicon_userContextId.js
3036
[task 2017-12-30T01:37:28.057Z] 01:37:28     INFO - Not taking screenshot here: see the one that was previously logged
3037
[task 2017-12-30T01:37:28.058Z] 01:37:28     INFO - Buffered messages logged at 01:37:27
3038
[task 2017-12-30T01:37:28.059Z] 01:37:28     INFO - Entering test bound setup
3039
[task 2017-12-30T01:37:28.059Z] 01:37:28     INFO - Leaving test bound setup
3040
[task 2017-12-30T01:37:28.060Z] 01:37:28     INFO - Entering test bound test_favicon_userContextId
3041
[task 2017-12-30T01:37:28.061Z] 01:37:28     INFO - Buffered messages finished
3042
[task 2017-12-30T01:37:28.062Z] 01:37:28     INFO - TEST-UNEXPECTED-FAIL | browser/components/originattributes/test/browser/browser_favicon_userContextId.js | uncaught exception - ReferenceError: ok is not defined at startObservingChannels/stopObservingChannels<@chrome://mochitests/content/browser/browser/components/originattributes/test/browser/browser_cache.js:107:11
3043
[task 2017-12-30T01:37:28.062Z] 01:37:28     INFO - applyFilter@chrome://mochitests/content/browser/browser/components/originattributes/test/browser/browser_cache.js:78:7
3044
[task 2017-12-30T01:37:28.063Z] 01:37:28     INFO - 
3045
[task 2017-12-30T01:37:28.063Z] 01:37:28     INFO - Stack trace:
3046
[task 2017-12-30T01:37:28.064Z] 01:37:28     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:simpletestOnerror:1645
3047
[task 2017-12-30T01:37:28.065Z] 01:37:28     INFO - GECKO(2325) | JavaScript error: chrome://mochitests/content/browser/browser/components/originattributes/test/browser/browser_cache.js, line 107: ReferenceError: ok is not defined
3048
[task 2017-12-30T01:37:28.086Z] 01:37:28     INFO - Console message: [JavaScript Error: "ReferenceError: ok is not defined" {file: "chrome://mochitests/content/browser/browser/components/originattributes/test/browser/browser_cache.js" line: 107}]
3049
[task 2017-12-30T01:37:28.087Z] 01:37:28     INFO - startObservingChannels/stopObservingChannels<@chrome://mochitests/content/browser/browser/components/originattributes/test/browser/browser_cache.js:107:11
3050
[task 2017-12-30T01:37:28.088Z] 01:37:28     INFO - applyFilter@chrome://mochitests/content/browser/browser/components/originattributes/test/browser/browser_cache.js:78:7
3051
[task 2017-12-30T01:37:28.088Z] 01:37:28     INFO - 
3052
[task 2017-12-30T01:37:28.128Z] 01:37:28     INFO - Not taking screenshot here: see the one that was previously logged
3053
[task 2017-12-30T01:37:28.129Z] 01:37:28     INFO - TEST-UNEXPECTED-FAIL | browser/components/originattributes/test/browser/browser_favicon_userContextId.js | uncaught exception - ReferenceError: ok is not defined at startObservingChannels/stopObservingChannels<@chrome://mochitests/content/browser/browser/components/originattributes/test/browser/browser_cache.js:107:11
3054
[task 2017-12-30T01:37:28.130Z] 01:37:28     INFO - applyFilter@chrome://mochitests/content/browser/browser/components/originattributes/test/browser/browser_cache.js:78:7
:ckerschb, any updates on this bug?

Comment 6

[Intermittent Failures Robot]

44 failures in 147 pushes (0.299 failures/push) were associated with this bug in the last 7 days. 

This is the #2 most frequent failure this week.  

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

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

Repository breakdown:
* mozilla-central: 17
* autoland: 13
* mozilla-inbound: 12
* try: 2

Platform breakdown:
* linux64: 14
* linux32-stylo-disabled: 12
* linux64-stylo-disabled: 11
* linux32: 7

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1423386&startday=2017-12-25&endday=2017-12-31&tree=all

Comment 7

[Geoff Brown [:gbrown]]

This test was recently updated in bug 1422657, but it looks like it ran reliably for about a week before this failure began.

Comment 8

[JW Wang [:jwwang] [:jw_wang]]

Attachment: Bug 1423386 - add logs to debug timeout.

Review commit: https://reviewboard.mozilla.org/r/209808/diff/#index_header
See other reviews: https://reviewboard.mozilla.org/r/209808/

Comment 9

[Tim Huang[:timhuang]]

Comment on attachment 8939469 [details]
Bug 1423386 - add logs to debug timeout.

https://reviewboard.mozilla.org/r/209808/#review215302

Comment 10

[JW Wang [:jwwang] [:jw_wang]]

Thanks!

Comment 11

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/ec1c0b20e0e7
add logs to debug timeout. r=timhuang

Comment 12

[Andrei Ciure[:aciure]]

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

Comment 13

[Intermittent Failures Robot]

47 failures in 111 pushes (0.423 failures/push) were associated with this bug yesterday.    

Repository breakdown:
* autoland: 21
* mozilla-inbound: 15
* mozilla-central: 9
* try: 2

Platform breakdown:
* linux64: 19
* linux64-stylo-disabled: 11
* linux32: 9
* linux32-stylo-disabled: 4
* osx-10-10: 3
* windows7-32: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1423386&startday=2018-01-03&endday=2018-01-03&tree=all

Comment 14

[Intermittent Failures Robot]

41 failures in 130 pushes (0.315 failures/push) were associated with this bug yesterday.    

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

Repository breakdown:
* autoland: 16
* try: 10
* mozilla-inbound: 9
* mozilla-central: 6

Platform breakdown:
* linux32: 14
* linux64-stylo-disabled: 11
* linux64: 11
* linux32-stylo-disabled: 3
* osx-10-10: 1
* linux64-nightly: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1423386&startday=2018-01-04&endday=2018-01-04&tree=all

Comment 15

[Joel Maher ( :jmaher ) (UTC -8)]

:jwwang, have the added logs helped out here?

Comment 16

[Intermittent Failures Robot]

39 failures in 119 pushes (0.328 failures/push) were associated with this bug yesterday.    

Repository breakdown:
* mozilla-inbound: 17
* autoland: 15
* mozilla-central: 5
* try: 2

Platform breakdown:
* linux32: 18
* linux64: 10
* linux32-stylo-disabled: 6
* linux64-stylo-disabled: 5

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1423386&startday=2018-01-05&endday=2018-01-05&tree=all

Comment 17

[Intermittent Failures Robot]

16 failures in 46 pushes (0.348 failures/push) were associated with this bug yesterday.    

Repository breakdown:
* mozilla-inbound: 6
* try: 4
* autoland: 4
* mozilla-central: 2

Platform breakdown:
* linux32-stylo-disabled: 7
* linux64: 5
* linux32: 3
* linux64-stylo-disabled: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1423386&startday=2018-01-06&endday=2018-01-06&tree=all

Comment 18

[Intermittent Failures Robot]

174 failures in 462 pushes (0.377 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: 62
* mozilla-inbound: 56
* mozilla-central: 36
* try: 20

Platform breakdown:
* linux64: 52
* linux32: 49
* linux64-stylo-disabled: 40
* linux32-stylo-disabled: 25
* osx-10-10: 4
* linux64-nightly: 2
* windows7-32: 1
* macosx64-nightly: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1423386&startday=2018-01-01&endday=2018-01-07&tree=all

Comment 19

[JW Wang [:jwwang] [:jw_wang]]

I guess bug 1427699 will help.

Comment 20

[Christoph Kerschbaumer [:ckerschb]]

Tim, any chance you could take a closer look and help fixing this intermittent?

Comment 21

[Tim Huang[:timhuang]]

Per comment 19, bug 1427699 could provide some help on this bug. And it has been fixed today. I think we should wait for several days to see if this still happens. Leave ni for now to track this.

Comment 22

[Joel Maher ( :jmaher ) (UTC -8)]

this seems to have reduced a lot in frequency:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1423386

lets see what the 7 days summary looks like next Monday.

Comment 23

[Intermittent Failures Robot]

3 failures in 149 pushes (0.02 failures/push) were associated with this bug yesterday.    

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

Repository breakdown:
* try: 3

Platform breakdown:
* linux64-stylo-disabled: 1
* linux64: 1
* linux32: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1423386&startday=2018-01-09&endday=2018-01-09&tree=all

Comment 24

[Joel Maher ( :jmaher ) (UTC -8)]

:jwwang, there is a debug patch landed here for investigation, but the failure seems to be fixed with bug 1427699- should we back out your debug patch or leave it in?

Comment 25

[JW Wang [:jwwang] [:jw_wang]]

The logs are handy for future debugging. Just leave them in.

Comment 26

[Intermittent Failures Robot]

11 failures in 788 pushes (0.014 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* try: 4
* autoland: 4
* mozilla-inbound: 3

Platform breakdown:
* linux32-stylo-disabled: 4
* linux64-stylo-disabled: 3
* linux32: 3
* linux64: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1423386&startday=2018-01-08&endday=2018-01-14&tree=all