921173 - Intermittent browser_thumbnails_background_crash.js | application timed out after 330 seconds with no output

Status: RESOLVED WORKSFORME

(Toolkit :: General, defect)

Description

[Ryan VanderMeulen [:RyanVM]]

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

Rev4 MacOSX Snow Leopard 10.6 mozilla-inbound debug test mochitest-browser-chrome on 2013-09-26 08:54:10 PDT for push 3055b4a78245
slave: talos-r4-snow-079

10:29:18     INFO -  [Child 972] WARNING: NS_ENSURE_TRUE(compMgr) failed: file ../../../xpcom/glue/nsComponentManagerUtils.cpp, line 58
10:29:18     INFO -  nsStringStats
10:29:18     INFO -   => mAllocCount:          14268
10:29:18     INFO -   => mReallocCount:          738
10:29:18     INFO -   => mFreeCount:           14268
10:29:18     INFO -   => mShareCount:          22557
10:29:18     INFO -   => mAdoptCount:           1313
10:29:18     INFO -   => mAdoptFreeCount:       1313
10:29:27     INFO -  [Parent 882] WARNING: parent WaitForMessage() failed: 0x10004003 (ipc/rcv) timed out: file ../../../ipc/glue/GeckoChildProcessHost.cpp, line 670
10:29:27     INFO -  [Parent 882] WARNING: waitpid failed pid:906 errno:10: file ../../../ipc/chromium/src/base/process_util_posix.cc, line 251
10:29:35     INFO -  System JS : ERROR (null):0
10:29:35     INFO -                       uncaught exception: 2147746065
10:34:59  WARNING -  TEST-UNEXPECTED-FAIL | chrome://mochitests/content/browser/toolkit/components/thumbnails/test/browser_thumbnails_background_crash.js | application timed out after 330 seconds with no output
10:35:07     INFO -  SCREENSHOT: <see log>
10:35:07     INFO -  Can't trigger Breakpad, just killing process
10:35:07     INFO -  Failed to kill process 882: [Errno 3] No such process
10:35:07     INFO -  INFO | runtests.py | exit 9
10:35:07     INFO -  INFO | runtests.py | Application ran for: 1:38:06.448981
10:35:07     INFO -  INFO | zombiecheck | Reading PID log: /var/folders/H0/H0uJAwizEwqe43TH00w9xU+++-k/-Tmp-/tmpRIIqhtpidlog
10:35:08     INFO -  == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 972
10:35:08     INFO -       |<----------------Class--------------->|<-----Bytes------>|<----------------Objects---------------->|<--------------References-------------->|
10:35:08     INFO -                                                Per-Inst   Leaked    Total      Rem      Mean       StdDev     Total      Rem      Mean       StdDev
10:35:08     INFO -     0 TOTAL                                          24        0   269592        0 ( 2276.52 +/-  2214.06)   277986        0 ( 1222.10 +/-  2733.66)
10:35:08     INFO -  nsTraceRefcntImpl::DumpStatistics: 556 entries
10:35:08     INFO -  TEST-PASS | leakcheck | no leaks detected!
10:35:08     INFO -  ==> process 893 will purposefully crash
10:35:08     INFO -  TEST-INFO | leakcheck | plugin process: deliberate crash and thus no leak log
10:35:08     INFO -  ==> process 897 will purposefully crash
10:35:08     INFO -  TEST-INFO | leakcheck | plugin process: deliberate crash and thus no leak log
10:35:08     INFO -  ==> process 898 will purposefully crash
10:35:08     INFO -  TEST-INFO | leakcheck | plugin process: deliberate crash and thus no leak log
10:35:08     INFO -  runtests.py | Running tests: end.
10:37:06    ERROR - Return code: 2304

Comment 1

[Drew Willcoxon :adw]

So...

* A "normal" (!abnormal) ipc:content-shutdown notification was broadcasted
  as soon as the test started.
* A bunch of DOM windows are closed immediately afterward.  Their URLs are
  of pages opened by previous tests.
* Immediately after that, it looks like the plugin-container process quits.
* The test doesn't get past the first capture.  The capture doesn't even
  time out.

Did the parent process shut down?

Comment 2

[Mark Hammond [:markh] [:mhammond]]

(In reply to Drew Willcoxon :adw from comment #1)
> So...
> 
> * A "normal" (!abnormal) ipc:content-shutdown notification was broadcasted
>   as soon as the test started.

Yeah, this is somewhat expected - the process used by the previous test can cause this.

> * A bunch of DOM windows are closed immediately afterward.  Their URLs are
>   of pages opened by previous tests.
> * Immediately after that, it looks like the plugin-container process quits.
> * The test doesn't get past the first capture.  The capture doesn't even
>   time out.
> 
> Did the parent process shut down?

Yes, it looks like it did.  TBH I don't think this will turn out to be directly related to this test but a more general e10s desktop problem :(

Comment 3

[Mark Hammond [:markh] [:mhammond]]

I also noticed in the #e10s channel that people are reporting the "System JS : ERROR (null):0...uncaught exception: 2147746065" - which we see in this report - are coming up in other contexts too, so I suspect some other issue is biting us here too.

Comment 4

[Ryan VanderMeulen [:RyanVM]]

Yes, that appears in all debug logs. Haven't had a chance to file it, though.

Comment 5

[Legacy TBPL/Treeherder Robot]

vporof
https://tbpl.mozilla.org/php/getParsedLog.php?id=29356364&tree=Fx-Team
Rev4 MacOSX Snow Leopard 10.6 fx-team debug test mochitest-browser-chrome on 2013-10-18 23:32:30
revision: 321e817596d2
slave: talos-r4-snow-079

TypeError: this.markup is undefined: InspectorPanel_onNewRoot/</<@resource://gre/modules/commonjs/toolkit/loader.js -> resource:///modules/devtools/inspector/inspector-panel.js:321
TEST-UNEXPECTED-FAIL | chrome://mochitests/content/browser/toolkit/components/thumbnails/test/browser_thumbnails_background_crash.js | application timed out after 330 seconds with no output
PROCESS-CRASH | chrome://mochitests/content/browser/toolkit/components/thumbnails/test/browser_thumbnails_background_crash.js | application crashed [@ js::ctypes::ConvertToJS]
Return code: 256

Comment 6

[Legacy TBPL/Treeherder Robot]

philor
https://tbpl.mozilla.org/php/getParsedLog.php?id=29507909&tree=Mozilla-Aurora
Ubuntu VM 12.04 x64 mozilla-aurora pgo test mochitest-browser-chrome on 2013-10-22 11:39:02
revision: f958ee30973d
slave: tst-linux64-ec2-012

TEST-UNEXPECTED-FAIL | chrome://mochitests/content/browser/toolkit/components/thumbnails/test/browser_thumbnails_background_crash.js | Test timed out
TEST-UNEXPECTED-FAIL | chrome://mochitests/content/browser/toolkit/mozapps/extensions/test/browser-window/browser_bug562797.js | uncaught exception - ReferenceError: is is not defined at chrome://mochitests/content/browser/toolkit/components/thumbnails/test/browser_thumbnails_background_crash.js:126
PROCESS-CRASH | Main app process exited normally | application crashed [Unknown top frame]
Return code: 256

Comment 7

[Legacy TBPL/Treeherder Robot]

philor
https://tbpl.mozilla.org/php/getParsedLog.php?id=30055810&tree=Mozilla-Inbound
Rev3 Fedora 12 mozilla-inbound debug test mochitest-browser-chrome on 2013-11-03 14:48:13
revision: f747e2b7a784
slave: talos-r3-fed-057

TypeError: this.markup is undefined: InspectorPanel_onNewRoot/</<@resource://gre/modules/commonjs/toolkit/loader.js -> resource:///modules/devtools/inspector/inspector-panel.js:350
TEST-UNEXPECTED-FAIL | chrome://mochitests/content/browser/toolkit/components/thumbnails/test/browser_thumbnails_background_crash.js | Test timed out
TEST-UNEXPECTED-FAIL | chrome://mochitests/content/browser/toolkit/components/thumbnails/test/browser_thumbnails_bug727765.js | uncaught exception - ReferenceError: executeSoon is not defined at chrome://mochitests/content/browser/toolkit/components/thumbnails/test/browser_thumbnails_background_crash.js:77
TEST-UNEXPECTED-FAIL | chrome://mochitests/content/browser/toolkit/components/thumbnails/test/browser_thumbnails_bug727765.js | uncaught exception - ReferenceError: is is not defined at chrome://mochitests/content/browser/toolkit/components/thumbnails/test/browser_thumbnails_background_crash.js:126
TEST-UNEXPECTED-FAIL | chrome://mochitests/content/browser/toolkit/components/thumbnails/test/browser_thumbnails_bug727765.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort.
PROCESS-CRASH | Main app process exited normally | application crashed [@ js::ctypes::ConvertToJS]
Return code: 256

Comment 8

[:Gavin Sharp [email: [e-mail]]]

Haven't seen this in a while!