Closed Bug 992311 Opened 6 years ago Closed Last year

Intermittent test_leaf_layers_partition_browser_window.xul | Test timed out.

Categories

(Core :: Graphics: Layers, defect, P3)

defect

Tracking

()

RESOLVED INACTIVE
Tracking Status
firefox45 --- wontfix
firefox46 --- disabled
firefox47 --- disabled
firefox-esr45 --- disabled

People

(Reporter: emorley, Unassigned)

References

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell disabled][gfx-noted])

Attachments

(1 file)

Windows XP 32-bit mozilla-inbound debug test mochitest-other on 2014-04-04 06:51:10 PDT for push 6ba86b56023e

slave: t-xp32-ix-101

https://tbpl.mozilla.org/php/getParsedLog.php?id=37278304&tree=Mozilla-Inbound

{
07:01:28     INFO -  3084 INFO TEST-START | chrome://mochitests/content/chrome/layout/base/tests/chrome/test_leaf_layers_partition_browser_window.xul
07:01:28     INFO -  ++DOMWINDOW == 70 (1C6C9DE8) [pid = 3576] [serial = 3035] [outer = 14FF0340]
07:01:28     INFO -  ++DOCSHELL 1B6D4028 == 16 [pid = 3576] [id = 609]
07:01:28     INFO -  ++DOMWINDOW == 71 (1521E868) [pid = 3576] [serial = 3036] [outer = 00000000]
07:01:28     INFO -  ++DOMWINDOW == 72 (1B8A9DD8) [pid = 3576] [serial = 3037] [outer = 1521E868]
07:01:28     INFO -  ++DOCSHELL 150A3438 == 17 [pid = 3576] [id = 610]
07:01:28     INFO -  ++DOMWINDOW == 73 (147D6260) [pid = 3576] [serial = 3038] [outer = 00000000]
07:01:28     INFO -  ++DOCSHELL 1B1E62D8 == 18 [pid = 3576] [id = 611]
07:01:28     INFO -  ++DOMWINDOW == 74 (1541BF70) [pid = 3576] [serial = 3039] [outer = 00000000]
07:01:28     INFO -  ++DOCSHELL 1545FB60 == 19 [pid = 3576] [id = 612]
07:01:28     INFO -  ++DOMWINDOW == 75 (13D3A278) [pid = 3576] [serial = 3040] [outer = 00000000]
07:01:29     INFO -  ++DOMWINDOW == 76 (1BB2B908) [pid = 3576] [serial = 3041] [outer = 13D3A278]
07:01:29     INFO -  [Parent 3576] WARNING: NS_ENSURE_TRUE(NS_SUCCEEDED(rv) && subjPrincipal) failed: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/docshell/base/nsDocShell.cpp, line 8686
07:01:29     INFO -  [Parent 3576] WARNING: NS_ENSURE_TRUE(globalObject && globalObject->GetGlobalJSObject()) failed: file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\content\html\document\src\nsHTMLContentSink.cpp, line 740
07:01:29     INFO -  [Parent 3576] WARNING: Subdocument container has no frame: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/layout/base/nsDocumentViewer.cpp, line 2418
07:01:29     INFO -  ++DOMWINDOW == 77 (1A097120) [pid = 3576] [serial = 3042] [outer = 147D6260]
07:01:29     INFO -  [Parent 3576] WARNING: NS_ENSURE_TRUE(globalObject && globalObject->GetGlobalJSObject()) failed: file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\content\html\document\src\nsHTMLContentSink.cpp, line 740
07:01:29     INFO -  [Parent 3576] WARNING: Subdocument container has no frame: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/layout/base/nsDocumentViewer.cpp, line 2418
07:01:29     INFO -  ++DOMWINDOW == 78 (1B9C8858) [pid = 3576] [serial = 3043] [outer = 1541BF70]
07:01:29     INFO -  ++DOMWINDOW == 79 (1A36B010) [pid = 3576] [serial = 3044] [outer = 13D3A278]
07:01:29     INFO -  [Parent 3576] WARNING: NS_ENSURE_TRUE(gIMM32Handler) failed: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/widget/windows/nsIMM32Handler.cpp, line 254
07:01:29     INFO -  ++DOMWINDOW == 80 (1AED2A30) [pid = 3576] [serial = 3045] [outer = 13D3A278]
07:01:29     INFO -  [Parent 3576] WARNING: NS_ENSURE_TRUE(treeItem) failed: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/layout/base/nsPresShell.cpp, line 6086
07:01:29     INFO -  [Parent 3576] WARNING: NS_ENSURE_TRUE(treeItem) failed: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/layout/base/nsPresShell.cpp, line 6086
07:01:29     INFO -  [Parent 3576] WARNING: NS_ENSURE_TRUE(gIMM32Handler) failed: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/widget/windows/nsIMM32Handler.cpp, line 254
07:01:31     INFO -  --DOCSHELL 1B3E5CE8 == 18 [pid = 3576] [id = 608]
07:01:31     INFO -  --DOCSHELL 1B19DB60 == 17 [pid = 3576] [id = 607]
07:01:31     INFO -  --DOCSHELL 1B9C8C68 == 16 [pid = 3576] [id = 606]
07:01:31     INFO -  --DOCSHELL 265AFB80 == 15 [pid = 3576] [id = 605]
07:01:31     INFO -  --DOCSHELL 19449300 == 14 [pid = 3576] [id = 604]
07:01:31     INFO -  --DOMWINDOW == 79 (1BF43918) [pid = 3576] [serial = 3013] [outer = 00000000] [url = about:blank]
07:01:31     INFO -  --DOMWINDOW == 78 (1A9A5430) [pid = 3576] [serial = 2999] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug551434.html]
07:01:31     INFO -  --DOMWINDOW == 77 (09913F98) [pid = 3576] [serial = 2998] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
07:01:31     INFO -  --DOMWINDOW == 76 (1A5320B0) [pid = 3576] [serial = 2997] [outer = 00000000] [url = data:text/html,<html><body%20onclick='document.body.textContent=1'>This%20is%20a%20panel!</body></html>]
07:01:31     INFO -  --DOMWINDOW == 75 (1BB61EE8) [pid = 3576] [serial = 2995] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug533845.xul]
07:01:31     INFO -  --DOMWINDOW == 74 (1AE0A4E8) [pid = 3576] [serial = 2994] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
07:01:31     INFO -  --DOMWINDOW == 73 (1AAE9500) [pid = 3576] [serial = 2993] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug514660.xul]
07:01:31     INFO -  --DOMWINDOW == 72 (1AF30758) [pid = 3576] [serial = 2992] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
07:01:31     INFO -  --DOMWINDOW == 71 (1497EC48) [pid = 3576] [serial = 2991] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug504311.xul]
07:01:31     INFO -  --DOMWINDOW == 70 (1505ADD0) [pid = 3576] [serial = 2990] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
07:01:31     INFO -  --DOMWINDOW == 69 (1A2C4668) [pid = 3576] [serial = 2989] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug495648.xul]
07:01:31     INFO -  --DOMWINDOW == 68 (262143C0) [pid = 3576] [serial = 2988] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
07:01:31     INFO -  --DOMWINDOW == 67 (1A526610) [pid = 3576] [serial = 3010] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
07:01:31     INFO -  --DOMWINDOW == 66 (1A01B318) [pid = 3576] [serial = 3009] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug847890_paintFlashing.html]
07:01:31     INFO -  --DOMWINDOW == 65 (1A917400) [pid = 3576] [serial = 3008] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
07:01:31     INFO -  --DOMWINDOW == 64 (1BFEA718) [pid = 3576] [serial = 3007] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug812817.xul]
07:01:31     INFO -  --DOMWINDOW == 63 (26D7EC90) [pid = 3576] [serial = 3006] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
07:01:31     INFO -  --DOMWINDOW == 62 (1B9483A8) [pid = 3576] [serial = 3005] [outer = 00000000] [url = data:text/html,A<div%20id='d'%20style='position:fixed;width:170px;top:0;right:0;height:1px;background:yellow;'>]
07:01:31     INFO -  --DOMWINDOW == 61 (1A1B0048) [pid = 3576] [serial = 3003] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug708062.html]
07:01:31     INFO -  --DOMWINDOW == 60 (149EDCC8) [pid = 3576] [serial = 3002] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
07:01:31     INFO -  --DOMWINDOW == 59 (19BD2DD8) [pid = 3576] [serial = 3001] [outer = 00000000] [url = http://example.org/chrome/layout/base/tests/chrome/bug551434_childframe.html]
07:01:31     INFO -  --DOMWINDOW == 58 (1A156A20) [pid = 3576] [serial = 2983] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug420499.xul]
07:01:31     INFO -  --DOMWINDOW == 57 (1B1630B0) [pid = 3576] [serial = 2984] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
07:01:31     INFO -  --DOMWINDOW == 56 (1A521800) [pid = 3576] [serial = 3015] [outer = 00000000] [url = about:blank]
07:01:31     INFO -  --DOMWINDOW == 55 (1BB2B908) [pid = 3576] [serial = 3041] [outer = 13D3A278] [url = about:blank]
07:01:32     INFO -  ++DOCSHELL 1C718968 == 15 [pid = 3576] [id = 613]
07:01:32     INFO -  ++DOMWINDOW == 56 (1A9E4BB8) [pid = 3576] [serial = 3046] [outer = 00000000]
07:01:32     INFO -  ++DOMWINDOW == 57 (1B8CDBC0) [pid = 3576] [serial = 3047] [outer = 1A9E4BB8]
07:01:32     INFO -  ++DOMWINDOW == 58 (1C55CD30) [pid = 3576] [serial = 3048] [outer = 1A9E4BB8]
07:01:33     INFO -  [Parent 3576] WARNING: NS_ENSURE_TRUE(obj) failed: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/content/base/src/nsObjectLoadingContent.cpp, line 3416
07:01:33     INFO -  [Parent 3576] WARNING: NS_ENSURE_TRUE(obj) failed: file c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/content/base/src/nsObjectLoadingContent.cpp, line 3416
07:01:33     INFO -  --DOMWINDOW == 57 (1B4578B8) [pid = 3576] [serial = 3018] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/chrome_over_plugin_window.xul]
07:01:33     INFO -  --DOMWINDOW == 56 (19EC71D0) [pid = 3576] [serial = 3020] [outer = 00000000] [url = data:text/html,<embed%20id='e'%20type='application/x-test'%20wmode='window'%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20style='position:absolute;left:0;top:0;width:100px;height:100px'></embed>%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20<div%20style='position:absolute;left:0;top:80px;width:100px;height:10px;background:rgba(0,0,128,0.5)'></div>%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20<div%20style='position:absolute;left:0;top:90px;width:100px;height:10px;background:blue'></div>]
07:01:33     INFO -  --DOMWINDOW == 55 (1AE22B10) [pid = 3576] [serial = 3024] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/default_background_window.xul]
07:01:33     INFO -  --DOMWINDOW == 54 (1B027A70) [pid = 3576] [serial = 3026] [outer = 00000000] [url = about:blank]
07:01:33     INFO -  --DOMWINDOW == 53 (1ADB4948) [pid = 3576] [serial = 3030] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/dialog_with_positioning_window.xul]
07:01:39     INFO -  --DOMWINDOW == 52 (153FC880) [pid = 3576] [serial = 3034] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
07:01:39     INFO -  --DOMWINDOW == 51 (19767008) [pid = 3576] [serial = 3033] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_fixed_bg_scrolling_repaints.html]
07:01:39     INFO -  --DOMWINDOW == 50 (1A36B010) [pid = 3576] [serial = 3044] [outer = 00000000] [url = about:blank]
07:01:39     INFO -  --DOMWINDOW == 49 (1A114D98) [pid = 3576] [serial = 3016] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
07:01:39     INFO -  --DOMWINDOW == 48 (1ACFB928) [pid = 3576] [serial = 3017] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_chrome_over_plugin.xul]
07:01:39     INFO -  --DOMWINDOW == 47 (0AB31F90) [pid = 3576] [serial = 3019] [outer = 00000000] [url = about:blank]
07:01:39     INFO -  --DOMWINDOW == 46 (2690FF20) [pid = 3576] [serial = 3021] [outer = 00000000] [url = about:blank]
07:01:39     INFO -  --DOMWINDOW == 45 (1C7EA798) [pid = 3576] [serial = 3022] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
07:01:39     INFO -  --DOMWINDOW == 44 (13C263E0) [pid = 3576] [serial = 3023] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_default_background.xul]
07:01:39     INFO -  --DOMWINDOW == 43 (262C7E90) [pid = 3576] [serial = 3025] [outer = 00000000] [url = about:blank]
07:01:39     INFO -  --DOMWINDOW == 42 (19EB2E60) [pid = 3576] [serial = 3027] [outer = 00000000] [url = about:blank]
07:01:39     INFO -  --DOMWINDOW == 41 (1AEAC950) [pid = 3576] [serial = 3028] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
07:01:39     INFO -  --DOMWINDOW == 40 (19926028) [pid = 3576] [serial = 3029] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_dialog_with_positioning.html]
07:01:39     INFO -  --DOMWINDOW == 39 (1AFA3DD8) [pid = 3576] [serial = 3031] [outer = 00000000] [url = about:blank]
07:01:39     INFO -  --DOMWINDOW == 38 (13FD98A8) [pid = 3576] [serial = 3032] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
07:01:39     INFO -  --DOMWINDOW == 37 (19A9B028) [pid = 3576] [serial = 2985] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug458898.html]
07:01:39     INFO -  --DOMWINDOW == 36 (1453C4B0) [pid = 3576] [serial = 3011] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_chrome_content_integration.xul]
07:01:39     INFO -  --DOMWINDOW == 35 (1BF40150) [pid = 3576] [serial = 2987] [outer = 00000000] [url = about:blank]
07:01:39     INFO -  --DOMWINDOW == 34 (1B8CDBC0) [pid = 3576] [serial = 3047] [outer = 1A9E4BB8] [url = about:blank]
07:02:18     INFO -  [Parent 3576] WARNING: 1 sort operation has occurred for the SQL statement '0xaee19d0'.  See https://developer.mozilla.org/En/Storage/Warnings details.: file c:\builds\moz2_slave\m-in-w32-d-0000000000000000000\build\storage\src\mozStoragePrivateHelpers.cpp, line 109
07:04:26     INFO -  NPP_Destroy
07:04:26     INFO -  nsStringStats
07:04:26     INFO -   => mAllocCount:             84
07:04:26     INFO -   => mReallocCount:            1
07:04:26     INFO -   => mFreeCount:              84
07:04:26     INFO -   => mShareCount:            137
07:04:26     INFO -   => mAdoptCount:              0
07:04:26     INFO -   => mAdoptFreeCount:          0
07:04:26     INFO -   => Process ID: 3156, Thread ID: 3192
07:06:43     INFO -  3085 INFO TEST-INFO | dumping last 3 message(s)
07:06:43     INFO -  3086 INFO TEST-INFO | if you need more context, please use SimpleTest.requestCompleteLog() in your test
07:06:43     INFO -  3087 INFO TEST-PASS | chrome://mochitests/content/chrome/layout/base/tests/chrome/test_leaf_layers_partition_browser_window.xul | Skipping non-maximized test 0 on winLowerThanVista since the resizer causes overlapping layers
07:06:43     INFO -  3088 INFO TEST-INFO | chrome://mochitests/content/chrome/layout/base/tests/chrome/test_leaf_layers_partition_browser_window.xul | Maximizing test 1 window (maximized)
07:06:43     INFO -  3089 INFO TEST-PASS | chrome://mochitests/content/chrome/layout/base/tests/chrome/test_leaf_layers_partition_browser_window.xul | mozPaintCount has increased (maximized)
07:06:44     INFO -  TEST-INFO | screenshot: exit status 0
07:06:44     INFO -  3090 INFO TEST-UNEXPECTED-FAIL | chrome://mochitests/content/chrome/layout/base/tests/chrome/test_leaf_layers_partition_browser_window.xul | Test timed out.
07:06:44     INFO -  3091 INFO TEST-INFO | MEMORY STAT vsize after test: 806023168
07:06:44     INFO -  3092 INFO TEST-INFO | MEMORY STAT vsizeMaxContiguous after test: 339476480
07:06:44     INFO -  3093 INFO TEST-INFO | MEMORY STAT residentFast after test: 441237504
07:06:45     INFO -  3094 INFO TEST-END | chrome://mochitests/content/chrome/layout/base/tests/chrome/test_leaf_layers_partition_browser_window.xul | finished in 316317ms
}
Intermittent failure not seen for >3 months; filter on mass-intermittent-wfm-20140812.
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → WORKSFORME
Definitely continuing to happen. Re-opening.
Status: RESOLVED → REOPENED
Resolution: WORKSFORME → ---
Based on the log, the test spends 25 seconds GCing documents from previous tests, then five minutes elapse until the test starts executing (and gets through one of the two subtests), at which point the harness times out and shuts it down.
Daniel, any thoughts on this layout intermittent?
Flags: needinfo?(dholbert)
I will not likely have cycles to get to this in the near term (backlogged with reviews, regressions on nightly, and intern-hiring stuff).

Looks like it's been investigated a bit already and seems mysterious (comment 34), so I suspect it'll take a bit of digging to get to the bottom of this one.
Flags: needinfo?(dholbert)
Coverage is getting a little thin here: it's been disabled on OS X since 2012 claiming to be because of bug 748219, but despite that being fixed last spring, it still times out 100% on OS X (https://treeherder.mozilla.org/#/jobs?repo=try&revision=2d76d573b8e6&selectedJob=16135638 - we don't really *do* maximize on Mac, do we?), and now I'm disabling it on Linux so we're down to just Windows, where it's partially disabled on WinXP so we're down to Win7/Win8.
Keywords: leave-open
OS: Windows XP → All
Hardware: x86 → All
Whiteboard: [disabled on OS X][disabled on Linux]
I may have been misreading the log earlier. I assumed that the timestamps corresponded to the actual timestamp of the output produced, but it's possible that the output suppression code makes the timestamp unreliable for timeout cases. Specifically, the output that corresponds to the same second as the reported timeout is likely being reported after the fact, and therefore the strange result of the test waiting for five minutes before proceeding was really the test sitting idle after failing to proceed.
https://hg.mozilla.org/releases/mozilla-aurora/rev/206d1c939536
Whiteboard: [disabled on OS X][disabled on Linux] → [disabled on OS X][disabled on Linux][checkin-needed-esr45]
https://hg.mozilla.org/releases/mozilla-esr45/rev/29b186d96bd1
Whiteboard: [disabled on OS X][disabled on Linux][checkin-needed-esr45] → [disabled on OS X][disabled on Linux]
Bulk assigning P3 to all open intermittent bugs without a priority set in Firefox components per bug 1298978.
Priority: -- → P3
This bug has been open for a long time, but the test has been trouble free since it was disabled on linux and osx. It is failing frequently on Windows Debug now.

2:04:30     INFO - TEST-START | layout/base/tests/chrome/test_leaf_layers_partition_browser_window.xul
12:04:30     INFO - GECKO(1288) | [1288] WARNING: Cannot set transparency mode on top-level windows.: file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/widget/windows/nsWindow.cpp, line 3048
12:04:30     INFO - GECKO(1288) | ++DOMWINDOW == 98 (0C2C4400) [pid = 1288] [serial = 98] [outer = 1D888400]
12:04:30     INFO - GECKO(1288) | ++DOCSHELL 0D4C6800 == 16 [pid = 1288] [id = {a3e59453-22a6-4fbc-9aa1-2482f40f7e8b}]
12:04:30     INFO - GECKO(1288) | ++DOMWINDOW == 99 (0D4C7C00) [pid = 1288] [serial = 99] [outer = 00000000]
12:04:30     INFO - GECKO(1288) | ++DOMWINDOW == 100 (0D4C8C00) [pid = 1288] [serial = 100] [outer = 0D4C7C00]
12:04:30     INFO - GECKO(1288) | ++DOCSHELL 0E5F3000 == 17 [pid = 1288] [id = {4fc9e209-15b3-4df3-8fc7-dd156bb5d449}]
12:04:30     INFO - GECKO(1288) | ++DOMWINDOW == 101 (0E5F8C00) [pid = 1288] [serial = 101] [outer = 00000000]
12:04:30     INFO - GECKO(1288) | ++DOCSHELL 0EF5D800 == 18 [pid = 1288] [id = {6cf54a59-6876-499a-b43e-f7f06c28a1aa}]
12:04:30     INFO - GECKO(1288) | ++DOMWINDOW == 102 (0F0DFC00) [pid = 1288] [serial = 102] [outer = 00000000]
12:04:30     INFO - GECKO(1288) | ++DOCSHELL 13AC7800 == 19 [pid = 1288] [id = {2254d71a-990a-45b8-b87c-7bfe65b4e1e6}]
12:04:30     INFO - GECKO(1288) | ++DOMWINDOW == 103 (13AC9800) [pid = 1288] [serial = 103] [outer = 00000000]
12:04:30     INFO - GECKO(1288) | ++DOMWINDOW == 104 (17DE2800) [pid = 1288] [serial = 104] [outer = 13AC9800]
12:04:30     INFO - GECKO(1288) | [1288] WARNING: 'NS_FAILED(aResult)', file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/extensions/cookie/nsPermissionManager.cpp, line 649
12:04:30     INFO - GECKO(1288) | [1288] WARNING: 'NS_FAILED(aResult)', file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/extensions/cookie/nsPermissionManager.cpp, line 649
12:04:30     INFO - GECKO(1288) | [1288] WARNING: 'NS_FAILED(aResult)', file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/extensions/cookie/nsPermissionManager.cpp, line 649
12:04:30     INFO - GECKO(1288) | [1288] WARNING: Cannot set transparency mode on top-level windows.: file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/widget/windows/nsWindow.cpp, line 3048
12:04:30     INFO - GECKO(1288) | ++DOMWINDOW == 105 (1C188400) [pid = 1288] [serial = 105] [outer = 0E5F8C00]
12:04:30     INFO - GECKO(1288) | ++DOMWINDOW == 106 (1C85F400) [pid = 1288] [serial = 106] [outer = 0F0DFC00]
12:04:30     INFO - GECKO(1288) | ++DOMWINDOW == 107 (1CBAC800) [pid = 1288] [serial = 107] [outer = 13AC9800]
12:04:30     INFO - GECKO(1288) | [1288] WARNING: 'NS_FAILED(aResult)', file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/extensions/cookie/nsPermissionManager.cpp, line 649
12:04:30     INFO - GECKO(1288) | [1288] WARNING: 'NS_FAILED(aResult)', file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/extensions/cookie/nsPermissionManager.cpp, line 649
12:04:30     INFO - GECKO(1288) | [1288] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/caps/BasePrincipal.cpp, line 43
12:04:30     INFO - GECKO(1288) | [1288] WARNING: 'NS_FAILED(rv)', file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/dom/workers/ServiceWorkerManager.cpp, line 1976
12:04:30     INFO - GECKO(1288) | [1288] WARNING: 'NS_FAILED(aResult)', file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/extensions/cookie/nsPermissionManager.cpp, line 649
12:04:30     INFO - GECKO(1288) | [1288] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/caps/BasePrincipal.cpp, line 43
12:04:30     INFO - GECKO(1288) | [1288] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/caps/ExpandedPrincipal.cpp, line 95
12:04:31     INFO - GECKO(1288) | ++DOMWINDOW == 108 (1346CC00) [pid = 1288] [serial = 108] [outer = 13AC9800]
12:04:31     INFO - GECKO(1288) | [1288] WARNING: 'NS_FAILED(aResult)', file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/extensions/cookie/nsPermissionManager.cpp, line 649
12:04:31     INFO - GECKO(1288) | [1288] WARNING: 'NS_FAILED(aResult)', file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/extensions/cookie/nsPermissionManager.cpp, line 649
12:04:31     INFO - GECKO(1288) | [1288] WARNING: 'NS_FAILED(aResult)', file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/extensions/cookie/nsPermissionManager.cpp, line 649
12:04:31     INFO - GECKO(1288) | [1288] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/caps/BasePrincipal.cpp, line 43
12:04:31     INFO - GECKO(1288) | [1288] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/caps/ExpandedPrincipal.cpp, line 95
12:04:31     INFO - GECKO(1288) | [1288] WARNING: Cannot set transparency mode on top-level windows.: file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/widget/windows/nsWindow.cpp, line 3048
12:04:31     INFO - GECKO(1288) | [1288] WARNING: Cannot set transparency mode on top-level windows.: file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/widget/windows/nsWindow.cpp, line 3048
12:04:31     INFO - GECKO(1288) | [1288] WARNING: Cannot set transparency mode on top-level windows.: file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/widget/windows/nsWindow.cpp, line 3048
12:04:31     INFO - GECKO(1288) | [1288] WARNING: Cannot set transparency mode on top-level windows.: file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/widget/windows/nsWindow.cpp, line 3048
12:04:31     INFO - GECKO(1288) | [1288] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/caps/BasePrincipal.cpp, line 43
12:04:31     INFO - GECKO(1288) | [1288] WARNING: 'NS_FAILED(rv)', file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/dom/workers/ServiceWorkerManager.cpp, line 1976
12:04:31     INFO - GECKO(1288) | [1288] WARNING: 'NS_FAILED(aResult)', file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/extensions/cookie/nsPermissionManager.cpp, line 649
12:04:31     INFO - GECKO(1288) | [1288] WARNING: Cannot set transparency mode on top-level windows.: file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/widget/windows/nsWindow.cpp, line 3048
12:04:31     INFO - GECKO(1288) | [1288] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/caps/BasePrincipal.cpp, line 43
12:04:31     INFO - GECKO(1288) | [1288] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/caps/ExpandedPrincipal.cpp, line 95
12:04:31     INFO - GECKO(1288) | [1288] WARNING: Cannot set transparency mode on top-level windows.: file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/widget/windows/nsWindow.cpp, line 3048
12:04:31     INFO - GECKO(1288) | [1288] WARNING: Cannot set transparency mode on top-level windows.: file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/widget/windows/nsWindow.cpp, line 3048
12:04:33     INFO - GECKO(1288) | --DOCSHELL 1F1DC400 == 18 [pid = 1288] [id = {cccd40b2-e28f-487f-85f0-fb3b8c50d69b}]
12:04:33     INFO - GECKO(1288) | --DOCSHELL 1BCC4C00 == 17 [pid = 1288] [id = {edd73963-3a1a-4f75-ac21-2c9ea94579d3}]
12:04:33     INFO - GECKO(1288) | --DOCSHELL 1B11D400 == 16 [pid = 1288] [id = {5eb83b55-13c0-4fe8-a20a-e1a5b7987e1e}]
12:04:33     INFO - GECKO(1288) | --DOCSHELL 1A5DB000 == 15 [pid = 1288] [id = {f0c82d82-2609-4118-9481-d94602845078}]
12:04:33     INFO - GECKO(1288) | --DOCSHELL 1919F800 == 14 [pid = 1288] [id = {0b6b136a-beb6-4856-a97e-d1f64e29649e}]
12:04:33     INFO - GECKO(1288) | --DOCSHELL 16D89800 == 13 [pid = 1288] [id = {4af6bc41-e719-40ae-a5a5-1fa93696813b}]
12:04:33     INFO - GECKO(1288) | --DOCSHELL 16D7E800 == 12 [pid = 1288] [id = {1e6e6dc0-82ae-476e-a6fc-ddf4c0bd1cf2}]
12:04:33     INFO - GECKO(1288) | --DOCSHELL 0D378800 == 11 [pid = 1288] [id = {957b083d-ba6b-497e-8191-227c50237e49}]
12:04:33     INFO - GECKO(1288) | --DOCSHELL 1E4B9400 == 10 [pid = 1288] [id = {70ddcf3b-c258-4a16-8788-37431906a2a7}]
12:04:33     INFO - GECKO(1288) | --DOMWINDOW == 107 (140BD400) [pid = 1288] [serial = 9] [outer = 00000000] [url = about:blank]
12:04:33     INFO - GECKO(1288) | --DOMWINDOW == 106 (0D4C2000) [pid = 1288] [serial = 4] [outer = 00000000] [url = about:blank]
12:04:33     INFO - GECKO(1288) | --DOMWINDOW == 105 (1E99CC00) [pid = 1288] [serial = 22] [outer = 00000000] [url = about:blank]
12:04:33     INFO - GECKO(1288) | --DOMWINDOW == 104 (1D9A7000) [pid = 1288] [serial = 21] [outer = 00000000] [url = about:blank]
12:04:33     INFO - GECKO(1288) | --DOMWINDOW == 103 (13AC8400) [pid = 1288] [serial = 14] [outer = 00000000] [url = about:blank]
12:04:37     INFO - GECKO(1288) | --DOMWINDOW == 102 (1B111C00) [pid = 1288] [serial = 78] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/chrome_over_plugin_window.xul]
12:04:37     INFO - GECKO(1288) | --DOMWINDOW == 101 (1A5E8000) [pid = 1288] [serial = 72] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/chrome_content_integration_window.xul]
12:04:37     INFO - GECKO(1288) | --DOMWINDOW == 100 (1919FC00) [pid = 1288] [serial = 64] [outer = 00000000] [url = data:text/html,A<div%20id='d'%20style='position:fixed;width:170px;top:0;right:0;height:1px;background:yellow;'>]
12:04:37     INFO - GECKO(1288) | --DOMWINDOW == 99 (16D7F000) [pid = 1288] [serial = 56] [outer = 00000000] [url = data:text/html,<html><body%20onclick='document.body.textContent=1'>This%20is%20a%20panel!</body></html>]
12:04:37     INFO - GECKO(1288) | --DOMWINDOW == 98 (0F0E5000) [pid = 1288] [serial = 46] [outer = 00000000] [url = data:text/html,<div%20style='height:200px;%20width:100px;'>]
12:04:37     INFO - GECKO(1288) | --DOMWINDOW == 97 (0D37A400) [pid = 1288] [serial = 35] [outer = 00000000] [url = data:text/html,<body%20onload='parent.childLoaded()'%20style='background:lime'><p>Hello<p>Hello<p>Hello<p>Hello<p>Hello<p>0.5765476199922932]
12:04:37     INFO - GECKO(1288) | --DOMWINDOW == 96 (1E4B7400) [pid = 1288] [serial = 24] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/file_bug1018265.xul]
12:04:37     INFO - GECKO(1288) | --DOMWINDOW == 95 (0E2DD800) [pid = 1288] [serial = 8] [outer = 00000000] [url = chrome://gfxsanity/content/sanitytest.html]
12:04:37     INFO - GECKO(1288) | --DOMWINDOW == 94 (0EF59800) [pid = 1288] [serial = 1] [outer = 00000000] [url = chrome://gfxsanity/content/sanityparent.html]
12:04:37     INFO - GECKO(1288) | --DOMWINDOW == 93 (1E64C400) [pid = 1288] [serial = 28] [outer = 00000000] [url = about:blank]
12:04:37     INFO - GECKO(1288) | --DOMWINDOW == 92 (0CAC3800) [pid = 1288] [serial = 33] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/bug1041200_window.html]
12:04:37     INFO - GECKO(1288) | --DOMWINDOW == 91 (17543000) [pid = 1288] [serial = 60] [outer = 00000000] [url = http://example.org/chrome/layout/base/tests/chrome/bug551434_childframe.html]
12:04:37     INFO - GECKO(1288) | --DOMWINDOW == 90 (1BCBF000) [pid = 1288] [serial = 84] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/default_background_window.xul]
12:04:37     INFO - GECKO(1288) | --DOMWINDOW == 89 (1E4BA000) [pid = 1288] [serial = 26] [outer = 00000000] [url = data:text/html,<iframe></iframe>]
12:04:37     INFO - GECKO(1288) | --DOMWINDOW == 88 (1AD22400) [pid = 1288] [serial = 74] [outer = 00000000] [url = data:text/html,<div%20style='position:absolute;left:0;top:0;width:100%;height:100px;background:yellow;border-bottom:100px%20solid%20black'>]
12:04:37     INFO - GECKO(1288) | --DOMWINDOW == 87 (1C05A400) [pid = 1288] [serial = 86] [outer = 00000000] [url = about:blank]
12:04:37     INFO - GECKO(1288) | --DOMWINDOW == 86 (1BCC5C00) [pid = 1288] [serial = 91] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/dialog_with_positioning_window.xul]
12:04:37     INFO - GECKO(1288) | --DOMWINDOW == 85 (1B1C4C00) [pid = 1288] [serial = 80] [outer = 00000000] [url = data:text/html,<embed%20id='e'%20type='application/x-test'%20wmode='window'%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20style='position:absolute;left:0;top:0;width:100px;height:100px'></embed>%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20<div%20style='position:absolute;left:0;top:80px;width:100px;height:10px;background:rgba(0,0,128,0.5)'></div>%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20<div%20style='position:absolute;left:0;top:90px;width:100px;height:10px;background:blue'></div>]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 84 (1C85C800) [pid = 1288] [serial = 95] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 83 (0CAC3C00) [pid = 1288] [serial = 34] [outer = 00000000] [url = about:blank]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 82 (18E9C800) [pid = 1288] [serial = 17] [outer = 00000000] [url = about:blank]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 81 (1754B000) [pid = 1288] [serial = 61] [outer = 00000000] [url = http://example.org/chrome/layout/base/tests/chrome/bug551434_childframe.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 80 (1BCC1800) [pid = 1288] [serial = 85] [outer = 00000000] [url = about:blank]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 79 (1E4BD400) [pid = 1288] [serial = 27] [outer = 00000000] [url = about:blank]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 78 (1AD28C00) [pid = 1288] [serial = 75] [outer = 00000000] [url = about:blank]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 77 (13AC7C00) [pid = 1288] [serial = 89] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 76 (1C181800) [pid = 1288] [serial = 87] [outer = 00000000] [url = about:blank]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 75 (1C181400) [pid = 1288] [serial = 93] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 74 (1BCC7400) [pid = 1288] [serial = 92] [outer = 00000000] [url = about:blank]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 73 (1B1D0800) [pid = 1288] [serial = 81] [outer = 00000000] [url = about:blank]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 72 (0D372000) [pid = 1288] [serial = 88] [outer = 00000000] [url = about:blank]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 71 (1B1C5000) [pid = 1288] [serial = 82] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 70 (1E64CC00) [pid = 1288] [serial = 29] [outer = 00000000] [url = about:blank]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 69 (1E6B2800) [pid = 1288] [serial = 30] [outer = 00000000] [url = about:neterror?e=fileNotFound&u=file%3A///C%3A/slave/test/build/tests/mochitest/chrome/layout/base/tests/chrome/foobarpage&c=UTF-8&f=regular&d=Firefox%20can%E2%80%99t%20find%20the%20file%20at%20/C%3A/slave/test/build/tests/mochitest/chrome/layout/base/tests/chrome/foobarpage.]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 68 (1D728C00) [pid = 1288] [serial = 18] [outer = 00000000] [url = http://mochi.test:8888/redirect.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 67 (1AF95C00) [pid = 1288] [serial = 76] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 66 (1A5E8400) [pid = 1288] [serial = 73] [outer = 00000000] [url = about:blank]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 65 (1A50C400) [pid = 1288] [serial = 70] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 64 (196F1000) [pid = 1288] [serial = 68] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 63 (196E6400) [pid = 1288] [serial = 66] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 62 (191A2400) [pid = 1288] [serial = 65] [outer = 00000000] [url = data:text/html,A<div%20id='d'%20style='position:fixed;width:170px;top:0;right:0;height:1px;background:yellow;'>]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 61 (17DE8400) [pid = 1288] [serial = 62] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 60 (17549800) [pid = 1288] [serial = 58] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 59 (16D7FC00) [pid = 1288] [serial = 57] [outer = 00000000] [url = about:blank]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 58 (150A4000) [pid = 1288] [serial = 54] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 57 (146ED000) [pid = 1288] [serial = 52] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 56 (13AD5C00) [pid = 1288] [serial = 50] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 55 (13282C00) [pid = 1288] [serial = 48] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 54 (11FBE800) [pid = 1288] [serial = 44] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 53 (0E558800) [pid = 1288] [serial = 42] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 52 (0DCEBC00) [pid = 1288] [serial = 40] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 51 (0D715400) [pid = 1288] [serial = 38] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 50 (0D712000) [pid = 1288] [serial = 37] [outer = 00000000] [url = data:text/html,<body%20onload='parent.childLoaded()'%20style='background:lime'><p>Hello<p>Hello<p>Hello<p>Hello<p>Hello<p>0.5765476199922932]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 49 (0D37B800) [pid = 1288] [serial = 36] [outer = 00000000] [url = about:blank]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 48 (0C86BC00) [pid = 1288] [serial = 31] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 47 (1E4B7800) [pid = 1288] [serial = 25] [outer = 00000000] [url = about:blank]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 46 (0E2E9800) [pid = 1288] [serial = 10] [outer = 00000000] [url = chrome://gfxsanity/content/sanitytest.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 45 (0EF59C00) [pid = 1288] [serial = 2] [outer = 00000000] [url = about:blank]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 44 (0D711800) [pid = 1288] [serial = 55] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug533845.xul]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 43 (150A0C00) [pid = 1288] [serial = 59] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug551434.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 42 (0DCF1C00) [pid = 1288] [serial = 63] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug708062.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 41 (18E9F800) [pid = 1288] [serial = 67] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug812817.xul]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 40 (192E6C00) [pid = 1288] [serial = 69] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug847890_paintFlashing.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 39 (1997C800) [pid = 1288] [serial = 71] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_chrome_content_integration.xul]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 38 (0D7F3800) [pid = 1288] [serial = 77] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_chrome_over_plugin.xul]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 37 (120A5400) [pid = 1288] [serial = 83] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_default_background.xul]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 36 (0C6FC000) [pid = 1288] [serial = 90] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_dialog_with_positioning.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 35 (0C863400) [pid = 1288] [serial = 94] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_fixed_bg_scrolling_repaints.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 34 (1D9A8400) [pid = 1288] [serial = 23] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug1018265.xul]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 33 (0C864000) [pid = 1288] [serial = 32] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug1041200.xul]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 32 (0C86B400) [pid = 1288] [serial = 39] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug396367-1.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 31 (0D7F7000) [pid = 1288] [serial = 41] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug396367-2.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 30 (0CF0A400) [pid = 1288] [serial = 43] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug420499.xul]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 29 (0DC97C00) [pid = 1288] [serial = 45] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug458898.html]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 28 (0D37D400) [pid = 1288] [serial = 49] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug495648.xul]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 27 (1346FC00) [pid = 1288] [serial = 51] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug504311.xul]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 26 (0DC9E800) [pid = 1288] [serial = 53] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_bug514660.xul]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 25 (11FC0400) [pid = 1288] [serial = 47] [outer = 00000000] [url = about:blank]
12:04:42     INFO - GECKO(1288) | --DOMWINDOW == 24 (1B112400) [pid = 1288] [serial = 79] [outer = 00000000] [url = about:blank]
12:04:49     INFO - GECKO(1288) | --DOMWINDOW == 23 (1CBADC00) [pid = 1288] [serial = 97] [outer = 00000000] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
12:04:49     INFO - GECKO(1288) | --DOMWINDOW == 22 (17DE2800) [pid = 1288] [serial = 104] [outer = 00000000] [url = about:blank]
12:04:49     INFO - GECKO(1288) | --DOMWINDOW == 21 (1BCC7800) [pid = 1288] [serial = 96] [outer = 00000000] [url = chrome://mochitests/content/chrome/layout/base/tests/chrome/test_getClientRectsAndTexts.html]
12:04:54     INFO - GECKO(1288) | --DOMWINDOW == 20 (1CBAC800) [pid = 1288] [serial = 107] [outer = 00000000] [url = about:blank]
12:04:58     INFO - GECKO(1288) | [NPAPI 2528] WARNING: pipe error: 109: file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
12:04:58     INFO - GECKO(1288) | [1288] WARNING: '!aObserver', file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/xpcom/ds/nsObserverService.cpp, line 234
12:04:58     INFO - GECKO(1288) | nsStringStats
12:04:58     INFO - GECKO(1288) |  => mAllocCount:            146
12:04:58     INFO - GECKO(1288) |  => mReallocCount:            1
12:04:58     INFO - GECKO(1288) |  => mFreeCount:             146
12:04:58     INFO - GECKO(1288) |  => mShareCount:            327
12:04:58     INFO - GECKO(1288) |  => mAdoptCount:              0
12:04:58     INFO - GECKO(1288) |  => mAdoptFreeCount:          0
12:04:58     INFO - GECKO(1288) |  => Process ID: 2528, Thread ID: 2108
12:06:15     INFO - GECKO(1288) | [1288] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/widget/windows/WinUtils.cpp, line 1468
12:08:15     INFO - GECKO(1288) | [1288] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file c:/builds/moz2_slave/autoland-w32-d-000000000000000/build/src/widget/windows/WinUtils.cpp, line 1468
12:09:48     INFO - TEST-INFO | started process screenshot
12:09:48     INFO - TEST-INFO | screenshot: exit 0
12:09:48     INFO - Buffered messages logged at 12:04:31
12:09:48     INFO - TEST-PASS | layout/base/tests/chrome/test_leaf_layers_partition_browser_window.xul | mozPaintCount has increased (non-maximized) 
12:09:48     INFO - Buffered messages finished
12:09:48     INFO - TEST-UNEXPECTED-FAIL | layout/base/tests/chrome/test_leaf_layers_partition_browser_window.xul | Test timed out. 
12:09:48     INFO - reportError@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:120:7
12:09:48     INFO - TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:141:7
12:09:48     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:162:5
12:09:48     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:162:5
12:09:48     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:162:5
12:09:48     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:162:5
12:09:48     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:162:5
12:09:48     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:162:5
12:09:48     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:162:5
12:09:48     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:162:5
12:09:48     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:162:5
12:09:48     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:162:5
12:09:48     INFO - setTimeout handler*TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:162:5
12:09:48     INFO - TestRunner.runTests@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:379:5
12:09:48     INFO - RunSet.runtests@chrome://mochikit/content/tests/SimpleTest/setup.js:190:3
12:09:48     INFO - RunSet.runall@chrome://mochikit/content/tests/SimpleTest/setup.js:169:5
12:09:48     INFO - hookupTests@chrome://mochikit/content/tests/SimpleTest/setup.js:262:5
12:09:48     INFO - parseTestManifest@chrome://mochikit/content/manifestLibrary.js:38:5
12:09:48     INFO - getTestManifest/req.onload@chrome://mochikit/content/manifestLibrary.js:49:11
12:09:48     INFO - EventHandlerNonNull*getTestManifest@chrome://mochikit/content/manifestLibrary.js:45:3
12:09:48     INFO - hookup@chrome://mochikit/content/tests/SimpleTest/setup.js:242:5
12:09:48     INFO - linkAndHookup@chrome://mochikit/content/harness.xul:59:3
12:09:48     INFO - parseTestManifest@chrome://mochikit/content/manifestLibrary.js:38:5
12:09:48     INFO - getTestManifest/req.onload@chrome://mochikit/content/manifestLibrary.js:49:11
12:09:48     INFO - EventHandlerNonNull*getTestManifest@chrome://mochikit/content/manifestLibrary.js:45:3
12:09:48     INFO - getTestList@chrome://mochikit/content/chrome-harness.js:260:3
12:09:48     INFO - loadTests@chrome://mochikit/content/harness.xul:38:3
12:09:48     INFO - EventListener.handleEvent*@chrome://mochikit/content/harness.xul:62:5
12:09:49     INFO - GECKO(1288) | MEMORY STAT | vsize 800MB | vsizeMaxContiguous 667MB | residentFast 110MB | heapAllocated 65MB
12:09:49     INFO - TEST-OK | layout/base/tests/chrome/test_leaf_layers_partition_browser_window.xul | took 319280ms
Comment 60 is not conclusive, but I wonder if mconley's change for bug 1344839 might have triggered this.
...and now this failure seems to have fixed itself again, with no failures since March 29.
Flags: needinfo?(gbrown)
Whiteboard: [disabled on OS X][disabled on Linux] → [disabled on OS X][disabled on Linux][stockwell unknown]
I see now...fixed by backout of bug 1344839.
Blocks: 1344839
Whiteboard: [disabled on OS X][disabled on Linux][stockwell unknown] → [disabled on OS X][disabled on Linux][stockwell fixed]
Whiteboard: [disabled on OS X][disabled on Linux][stockwell fixed] → [disabled on OS X][disabled on Linux][stockwell fixed:product]
as this test looks to be only run on windows, it is failing quite often:
https://brasstacks.mozilla.com/orangefactor/index.html?display=Bug&bugid=992311

it looks to be win8-debug is the primary set of failures.

:jet, can you help find someone to look at this test given that it is failing quite often now?
Flags: needinfo?(bugs)
Whiteboard: [disabled on OS X][disabled on Linux][stockwell fixed:product] → [disabled on OS X][disabled on Linux][stockwell needswork]
(In reply to Geoff Brown [:gbrown] from comment #64)
> I see now...fixed by backout of bug 1344839.

Note that bug 1344839 relanded shortly after that ^^ (5 months ago), and the relanding didn't reintroduce this bug. So bug 1344839 is innocent of recent failures.

After that, this bug was still open but dormant (no reported failures) for months, and then we got a whole bunch of reports for Try runs all of a sudden (comment 65, comment 66, comment 67).  That makes those Try runs' patches a bit suspect. And then a bit later, it started happening on other trees (comment 68, comment 69).


So that strongly suggests that something in one of those Try runs, which later landed on Trunk, has caused this test to regress.  All the Try runs I looked at were from masayuki, so I suspect this was from one of his changes (e.g. the oldest report was from this Try run from bug 1060051:
  https://treeherder.mozilla.org/#/jobs?repo=try&revision=7620c5182b1fd3e7d2c1b4ba3db49e6c1587fffe
)

Masayuki, perhaps you could take a look at this, since it seems like the recent failures may have been introduced by your changes?  Note that, per comment 71, this is an extremely frequent failure (#11 most frequent right now), so it's pretty high-priority to figure out what's going on here.
Flags: needinfo?(bugs) → needinfo?(masayuki)
But the 4 try runs in comment 65 are from 3 different people, with 4 unique sets of patches.  So I don't think I buy the theory that it was introduced by a patch from Masayuki (especially given how long it was try-only).
Yeah, the fix for bug 1060051 shouldn't cause such kind of oranges because it just made some editor related interfaces "builtinclass". So, it could cause permanent oranges if some tests implemented XPCOM objects which have one or more such interfaces, but I checked all related code in m-c/c-c and BlueGriffon.
Flags: needinfo?(masayuki)
Okay, looks like that this is a focus handling issue of the test.
Assignee: nobody → masayuki
Status: REOPENED → ASSIGNED
Hmm, that's odd. If I use SimpleTest.executeSoon() for creating the second popup window, that works only on Win8.1 or Win10. Perhaps, doing everything in same stack (closing the first popup window, moving focus to the main window and creating new window) causes the timeout at creating the second window and listening to its "load" event.

However, still timed out on Win7. I have no idea what's wrong because it seems that the test, the API or tested behavior is actually broken on Win7.
Assignee: masayuki → nobody
Status: ASSIGNED → NEW
:masayuki, thanks for looking into this!  Do you know who else can work on this, the failure rate is very high and if we do not have anyone actively working on fixing this test failure we should consider disabling the test for windows.
Flags: needinfo?(masayuki)
According to the log, the original author is roc, unfortunately:
https://hg.mozilla.org/mozilla-central/log/tip/layout/base/tests/chrome/test_leaf_layers_partition_browser_window.xul

I don't know who is working on layers a lot in these days.
Flags: needinfo?(masayuki)
Attachment #8902246 - Flags: review?(gbrown) → review+
Pushed by jmaher@mozilla.com:
https://hg.mozilla.org/integration/mozilla-inbound/rev/23bbbf4cafea
Disable test_leaf_layers_partition_browser_window.xul everywhere. r=gbrown
Whiteboard: [disabled on OS X][disabled on Linux][stockwell needswork] → [stockwell disabled]
Flags: needinfo?(gijskruitbosch+bugs)
My understanding from Australis days (bug 886281) was that having overlapping leaf layers was bad for perf. Is that no longer the case? Like, do we have other things that would catch bad-ness in this area or should we invest more time into getting this fixed?

(I only came across this because I noticed this intermittent on my own trypushes, and I was a bit scar(r)ed after my experience a few years back...)
Flags: needinfo?(gijskruitbosch+bugs) → needinfo?(milan)
This would be the "overdraw" value, where we count how many times we re-paint the same pixels in composition.  And, yes, having that number high is not helpful.  We have the counters, but I'm not sure we have any tests where we compare the numbers and complain if they start changing for the worse.
Flags: needinfo?(milan)
Whiteboard: [stockwell disabled] → [stockwell disabled][gfx-noted]
The leave-open keyword is there and there is no activity for 6 months.
:davidb, maybe it's time to close this bug?
Flags: needinfo?(dbolter)
(In reply to Milan Sreckovic [:milan] (needinfo for best results) from comment #90)
> This would be the "overdraw" value, where we count how many times we
> re-paint the same pixels in composition.  And, yes, having that number high
> is not helpful.  We have the counters, but I'm not sure we have any tests
> where we compare the numbers and complain if they start changing for the
> worse.

Jeff do we want to monitor overdraw counters? (And if so, on a new bug or here?)
Flags: needinfo?(dbolter) → needinfo?(jmuizelaar)
In theory we'd like to. I don't know that anyone has the bandwidth to care right now though.
Status: NEW → RESOLVED
Closed: 6 years agoLast year
Flags: needinfo?(jmuizelaar)
Resolution: --- → INCOMPLETE
Resolution: INCOMPLETE → INACTIVE
You need to log in before you can comment on or make changes to this bug.