867974 - Intermittent test_exceptions.html | Exited with code -2147483645 during test run | application crashed [Unknown top frame] (Assertion failure: mCount == 0 (AddPurpleRoot failed), at xpcom/base/nsCycleCollector.cpp:909)

Status: RESOLVED WORKSFORME

(Core :: XPCOM, defect)

Description

[Ryan VanderMeulen [:RyanVM]]

This assert was made fatal in bug 716716.

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

Rev3 WINNT 6.1 mozilla-inbound debug test mochitest-2 on 2013-05-01 13:05:04 PDT for push e13bb42d2872
slave: talos-r3-w7-083

13:27:53     INFO -  9648 INFO TEST-START | /tests/dom/imptests/webapps/DOMCore/tests/approved/test_exceptions.html
13:27:53     INFO -  ++DOMWINDOW == 509 (0CF9B1C0) [serial = 3276] [outer = 055081E0]
13:27:55     INFO -  --DOCSHELL 1680C790 == 7 [id = 951]
13:27:55     INFO -  --DOCSHELL 42129CE8 == 6 [id = 952]
13:27:55     INFO -  ++DOCSHELL 42129CE8 == 7 [id = 953]
13:27:55     INFO -  ++DOMWINDOW == 510 (4212B098) [serial = 3277] [outer = 00000000]
13:27:55     INFO -  ++DOMWINDOW == 511 (4212C8A8) [serial = 3278] [outer = 4212B098]
13:27:55     INFO -  [Parent 3548] WARNING: NS_ENSURE_TRUE(txToRemove) failed: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/docshell/shistory/src/nsSHistory.cpp, line 1320
13:27:55     INFO -  9649 INFO TEST-PASS | /tests/dom/imptests/webapps/DOMCore/tests/approved/test_exceptions.html | Elided 17 passes or known failures.
13:27:55     INFO -  9650 INFO TEST-END | /tests/dom/imptests/webapps/DOMCore/tests/approved/test_exceptions.html | finished in 2110ms
13:28:01     INFO -  Assertion failure: mCount == 0 (AddPurpleRoot failed), at e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/base/nsCycleCollector.cpp:909
13:28:04  WARNING -  TEST-UNEXPECTED-FAIL | /tests/dom/imptests/webapps/DOMCore/tests/approved/test_exceptions.html | Exited with code -2147483645 during test run
13:28:10  WARNING -  PROCESS-CRASH | /tests/dom/imptests/webapps/DOMCore/tests/approved/test_exceptions.html | application crashed [Unknown top frame]
13:28:10     INFO -  Crash dump filename: c:\users\cltbld\appdata\local\temp\tmpuwaudt\minidumps\e15d261b-5f77-4917-8494-65d9060e3726.dmp

Comment 1

[Andrew McCreight [:mccr8]]

I didn't think this would ever happen, which is why I made it fatal.  I should probably at least turn it into a non-fatal orange, perhaps logging what class it is.  I can turn it back into a warning if happens more than a handful of times a week.

Comment 2

[Legacy TBPL/Treeherder Robot]

philor
https://tbpl.mozilla.org/php/getParsedLog.php?id=22717208&tree=Fx-Team
Rev3 WINNT 6.1 fx-team debug test mochitest-2 on 2013-05-07 21:51:21
slave: talos-r3-w7-124

22:14:16     INFO -  Assertion failure: mCount == 0 (AddPurpleRoot failed), at e:/builds/moz2_slave/fx-team-w32-d-0000000000000000/build/xpcom/base/nsCycleCollector.cpp:913
22:14:20  WARNING -  TEST-UNEXPECTED-FAIL | /tests/dom/imptests/webapps/DOMCore/tests/approved/test_exceptions.html | Exited with code -2147483645 during test run
22:14:25  WARNING -  PROCESS-CRASH | /tests/dom/imptests/webapps/DOMCore/tests/approved/test_exceptions.html | application crashed [Unknown top frame]
22:16:42    ERROR - Return code: 1

Comment 3

[Legacy TBPL/Treeherder Robot]

edmorley
https://tbpl.mozilla.org/php/getParsedLog.php?id=22723801&tree=Mozilla-Inbound
Rev3 WINNT 6.1 mozilla-inbound debug test mochitest-2 on 2013-05-08 02:11:17
slave: talos-r3-w7-033

02:32:34     INFO -  Assertion failure: mCount == 0 (AddPurpleRoot failed), at e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/base/nsCycleCollector.cpp:913
02:32:36  WARNING -  TEST-UNEXPECTED-FAIL | /tests/dom/imptests/webapps/DOMCore/tests/approved/test_exceptions.html | Exited with code -2147483645 during test run
02:32:42  WARNING -  PROCESS-CRASH | /tests/dom/imptests/webapps/DOMCore/tests/approved/test_exceptions.html | application crashed [Unknown top frame]
02:33:39    ERROR - Return code: 1

Comment 4

[Legacy TBPL/Treeherder Robot]

RyanVM
https://tbpl.mozilla.org/php/getParsedLog.php?id=22739875&tree=Mozilla-Inbound
Rev3 WINNT 6.1 mozilla-inbound debug test mochitest-2 on 2013-05-08 11:22:32
slave: talos-r3-w7-119

11:50:16     INFO -  Assertion failure: mCount == 0 (AddPurpleRoot failed), at e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/base/nsCycleCollector.cpp:913
11:50:18  WARNING -  TEST-UNEXPECTED-FAIL | /tests/dom/imptests/webapps/DOMCore/tests/approved/test_exceptions.html | Exited with code -2147483645 during test run
11:50:25  WARNING -  PROCESS-CRASH | /tests/dom/imptests/webapps/DOMCore/tests/approved/test_exceptions.html | application crashed [Unknown top frame]
11:51:54    ERROR - Return code: 1

Comment 5

[Legacy TBPL/Treeherder Robot]

philor
https://tbpl.mozilla.org/php/getParsedLog.php?id=22751161&tree=Mozilla-Inbound
Rev3 WINNT 6.1 mozilla-inbound debug test mochitest-2 on 2013-05-08 16:00:54
slave: talos-r3-w7-133

16:22:33     INFO -  Assertion failure: mCount == 0 (AddPurpleRoot failed), at e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/base/nsCycleCollector.cpp:913
16:22:35  WARNING -  TEST-UNEXPECTED-FAIL | /tests/dom/imptests/webapps/DOMCore/tests/approved/test_exceptions.html | Exited with code -2147483645 during test run
16:22:41  WARNING -  PROCESS-CRASH | /tests/dom/imptests/webapps/DOMCore/tests/approved/test_exceptions.html | application crashed [Unknown top frame]
16:23:55    ERROR - Return code: 1

Comment 6

[Legacy TBPL/Treeherder Robot]

philor
https://tbpl.mozilla.org/php/getParsedLog.php?id=22751963&tree=Mozilla-Inbound
Rev3 WINNT 6.1 mozilla-inbound debug test mochitest-2 on 2013-05-08 16:20:43
slave: talos-r3-w7-090

16:23:57    ERROR -  abort: There is no Mercurial repository here (.hg not found)!
16:23:57    ERROR -  Automation Error: hg not responding
16:23:57    ERROR - Return code: -1
16:42:17     INFO -  Assertion failure: mCount == 0 (AddPurpleRoot failed), at e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/xpcom/base/nsCycleCollector.cpp:913
16:42:21  WARNING -  TEST-UNEXPECTED-FAIL | /tests/dom/imptests/webapps/DOMCore/tests/approved/test_exceptions.html | Exited with code -2147483645 during test run
16:42:28  WARNING -  PROCESS-CRASH | /tests/dom/imptests/webapps/DOMCore/tests/approved/test_exceptions.html | application crashed [Unknown top frame]
16:44:25    ERROR - Return code: 1

Comment 7

[Andrew McCreight [:mccr8]]

Hmm.  This is all in the same test.  Very curious.

Comment 8

[Andrew McCreight [:mccr8]]

Attachment: fail more gently, add some more logging

Comment 9

[Andrew McCreight [:mccr8]]

https://tbpl.mozilla.org/?tree=Try&rev=7b6e3991f520

Comment 10

[Legacy TBPL/Treeherder Robot]

msucan
https://tbpl.mozilla.org/php/getParsedLog.php?id=22772430&tree=Try
Rev3 WINNT 6.1 try debug test mochitest-2 on 2013-05-09 06:15:15
slave: talos-r3-w7-095

06:37:05     INFO -  Assertion failure: mCount == 0 (AddPurpleRoot failed), at e:/builds/moz2_slave/try-w32-d-00000000000000000000/build/xpcom/base/nsCycleCollector.cpp:913
06:37:09  WARNING -  TEST-UNEXPECTED-FAIL | /tests/dom/imptests/webapps/DOMCore/tests/approved/test_exceptions.html | Exited with code -2147483645 during test run
06:37:13  WARNING -  PROCESS-CRASH | /tests/dom/imptests/webapps/DOMCore/tests/approved/test_exceptions.html | application crashed [Unknown top frame]
06:38:36    ERROR - Return code: 1

Comment 11

[Andrew McCreight [:mccr8]]

note to sheriffs: this will cause the failure to change, which I guess will require a new bug.  CC me on that please.

https://hg.mozilla.org/integration/mozilla-inbound/rev/f7c710fa3295

Comment 12

[Ed Morley [:emorley]]

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

Comment 13

[Legacy TBPL/Treeherder Robot]

edmorley
https://tbpl.mozilla.org/php/getParsedLog.php?id=23068161&tree=Mozilla-Inbound
Rev3 WINNT 6.1 mozilla-inbound debug test mochitest-2 on 2013-05-16 22:50:52
slave: talos-r3-w7-112

23:14:37     INFO -  9689 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/imptests/webapps/DOMCore/tests/approved/test_exceptions.html | Assertion count 1 is greater than expected range 0-0 assertions.
23:16:49  WARNING -  TEST-UNEXPECTED-FAIL | leakcheck | 59845131 bytes leaked (AsyncStatement, AtomImpl, BackstagePass, BodyRule, CalculateFrecencyFunction, ...)

Comment 14

[Andrew McCreight [:mccr8]]

Still in the same test, plus it is leaking a lot.  I guess that isn't surprising.

It looks like we're running out of memory, as most of the log consists of this line:
23:12:25     INFO -  [Parent 3528] WARNING: out of memory: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/obj-firefox/xpcom/build/nsDeque.cpp, line 178

That line in nsDeque is from Push.

Comment 15

[Andrew McCreight [:mccr8]]

After all the OOM, there's this:

[Parent 3528] ###!!! ASSERTION: didn't call Disconnect: '!mTarget', file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/content/events/src/nsEventListenerManager.cpp, line 131
INFO -  9688 INFO TEST-END | /tests/dom/imptests/webapps/DOMCore/tests/approved/test_exceptions.html | finished in 125658ms

Comment 16

[Legacy TBPL/Treeherder Robot]

edmorley
https://tbpl.mozilla.org/php/getParsedLog.php?id=23311785&tree=Mozilla-Inbound
Rev3 WINNT 6.1 mozilla-inbound debug test mochitest-2 on 2013-05-23 03:21:58
slave: talos-r3-w7-097

03:47:21     INFO -  9746 ERROR TEST-UNEXPECTED-FAIL | /tests/dom/imptests/webapps/DOMCore/tests/approved/test_exceptions.html | Assertion count 1 is greater than expected range 0-0 assertions.
03:49:44  WARNING -  TEST-UNEXPECTED-FAIL | leakcheck | 59893011 bytes leaked (AsyncStatement, AtomImpl, BackstagePass, BodyRule, CalculateFrecencyFunction, ...)

Comment 17

[Andrew McCreight [:mccr8]]

This looks very similar to bug 858948.

Tons of these failures:
03:43:53     INFO -  [Parent 1960] WARNING: NS_ENSURE_TRUE(newRoot) failed: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/content/base/src/nsRange.cpp, line 1145

Then a bunch of failures like this:
03:45:36     INFO -  [Parent 1960] WARNING: out of memory: file e:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/obj-firefox/xpcom/build/nsDeque.cpp, line 178

Oddly, there are no GCGraphBuilder::AddNode failures here.

Comment 18

[Andrew McCreight [:mccr8]]

The test where the OOM is first happening ( dom/imptests/webapps/DOMCore/tests/approved/test_Range-surroundContents.html ) was disabled in bug 775227 on Android for OOMiness.  Looks like OOMiness is a problem on desktop too, just less often.

Comment 19

[Legacy TBPL/Treeherder Robot]

ttaubert
https://tbpl.mozilla.org/php/getParsedLog.php?id=25570042&tree=Try
Windows 7 32-bit try debug test mochitest-2 on 2013-07-22 11:28:09
revision: a00d722d8cb0
slave: t-w732-ix-056

11:31:34    ERROR - Return code: 1
11:31:34    ERROR - Return code: 1
11:44:28     INFO -  [Parent 1228] ###!!! ABORT: OOM: file e:\builds\moz2_slave\try-w32-d-00000000000000000000\build\obj-firefox\dist\include\nsDeque.h, line 83
11:44:30  WARNING -  TEST-UNEXPECTED-FAIL | /tests/dom/imptests/webapps/DOMCore/tests/approved/test_exceptions.html | Exited with code -2147483645 during test run
11:44:32  WARNING -  PROCESS-CRASH | /tests/dom/imptests/webapps/DOMCore/tests/approved/test_exceptions.html | application crashed [@ KERNELBASE.dll + 0x3381b]
11:44:33    ERROR - Return code: 1

Comment 20

[Andrew McCreight [:mccr8]]

"fixed" by disabling the test