Closed Bug 1423386 Opened 2 years ago Closed 2 years ago

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

Categories

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

defect

Tracking

()

RESOLVED FIXED

People

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

References

Details

(Keywords: intermittent-failure, Whiteboard: [domsecurity-intermittent][stockwell fixed:product])

Attachments

(1 file)

Whiteboard: [domsecurity-intermittent]
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?
Flags: needinfo?(ckerschb)
Whiteboard: [domsecurity-intermittent] → [domsecurity-intermittent][stockwell needswork]
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?
This test was recently updated in bug 1422657, but it looks like it ran reliably for about a week before this failure began.
See Also: → 1422657
Attachment #8939469 - Flags: review?(tihuang)
Comment on attachment 8939469 [details]
Bug 1423386 - add logs to debug timeout.

https://reviewboard.mozilla.org/r/209808/#review215302
Attachment #8939469 - Flags: review?(tihuang) → review+
Thanks!
Keywords: leave-open
Pushed by jwwang@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/ec1c0b20e0e7
add logs to debug timeout. r=timhuang
:jwwang, have the added logs helped out here?
Flags: needinfo?(jwwang)
I guess bug 1427699 will help.
Depends on: 1427699
Flags: needinfo?(jwwang)
Tim, any chance you could take a closer look and help fixing this intermittent?
Flags: needinfo?(ckerschb) → needinfo?(tihuang)
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.
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.
Whiteboard: [domsecurity-intermittent][stockwell disable-recommended] → [domsecurity-intermittent][stockwell needswork]
: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?
Flags: needinfo?(tihuang) → needinfo?(jwwang)
Whiteboard: [domsecurity-intermittent][stockwell disable-recommended] → [domsecurity-intermittent][stockwell fixed:product]
The logs are handy for future debugging. Just leave them in.
Flags: needinfo?(jwwang)
Status: NEW → RESOLVED
Closed: 2 years ago
Keywords: leave-open
Resolution: --- → FIXED
Assignee: nobody → suro001
You need to log in before you can comment on or make changes to this bug.