Closed Bug 977271 Opened 12 years ago Closed 11 years ago

Intermittent testBrowserDiscovery | GeckoEventExpecter - blockForEvent timeout: Gecko:Ready

Categories

(Firefox for Android Graveyard :: General, defect)

Product:
Firefox for Android Graveyard
Component:
General
Platform:
ARM
Android
Type:
defect
Priority:
Not set
Severity:
normal

Tracking

(Not tracked)

Status:
RESOLVED WORKSFORME

People

(Reporter: RyanVM, Unassigned)

References

Details

(Keywords: intermittent-failure)

https://tbpl.mozilla.org/php/getParsedLog.php?id=35295465&tree=Mozilla-Inbound Android 2.2 Tegra mozilla-inbound opt test robocop-2 on 2014-02-26 10:32:21 PST for push 960a7f055c95 slave: tegra-183 0 INFO SimpleTest START 1 INFO TEST-START | testBrowserDiscovery Dumping ALL the threads! Thread[JDWP,5,system] dalvik.system.NativeStart.run(Native Method) Thread[GeckoANRReporter,5,main] java.lang.Object.wait(Native Method) java.lang.Object.wait(Object.java:288) android.os.MessageQueue.next(MessageQueue.java:146) android.os.Looper.loop(Looper.java:110) org.mozilla.gecko.ANRReporter$1.run(ANRReporter.java:95) java.lang.Thread.run(Thread.java:1096) Thread[Instr: org.mozilla.gecko.FennecInstrumentationTestRunner,5,main] dalvik.system.VMStack.getThreadStackTrace(Native Method) java.lang.Thread.getStackTrace(Thread.java:788) java.lang.Thread.getAllStackTraces(Thread.java:669) org.mozilla.gecko.FennecNativeDriver.logAllStackTraces(FennecNativeDriver.java:301) org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForEvent(FennecNativeActions.java:95) org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForEvent(FennecNativeActions.java:80) org.mozilla.gecko.tests.BaseTest.blockForGeckoReady(BaseTest.java:80) org.mozilla.gecko.tests.JavascriptTest.testJavascript(JavascriptTest.java:95) java.lang.reflect.Method.invokeNative(Native Method) java.lang.reflect.Method.invoke(Method.java:521) android.test.InstrumentationTestCase.runMethod(InstrumentationTestCase.java:204) android.test.InstrumentationTestCase.runTest(InstrumentationTestCase.java:194) android.test.ActivityInstrumentationTestCase2.runTest(ActivityInstrumentationTestCase2.java:186) org.mozilla.gecko.tests.BaseTest.runTest(BaseTest.java:145) junit.framework.TestCase.runBare(TestCase.java:127) junit.framework.TestResult$1.protect(TestResult.java:106) junit.framework.TestResult.runProtected(TestResult.java:124) junit.framework.TestResult.run(TestResult.java:109) junit.framework.TestCase.run(TestCase.java:118) android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:169) android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:154) android.test.InstrumentationTestRunner.onStart(InstrumentationTestRunner.java:520) android.app.Instrumentation$InstrumentationThread.run(Instrumentation.java:1447) Thread[Compiler,5,system] dalvik.system.NativeStart.run(Native Method) Thread[GeckoBackgroundThread,5,main] java.lang.Object.wait(Native Method) java.lang.Object.wait(Object.java:288) android.os.MessageQueue.next(MessageQueue.java:146) android.os.Looper.loop(Looper.java:110) org.mozilla.gecko.util.GeckoBackgroundThread.run(GeckoBackgroundThread.java:32) Thread[HeapWorker,5,system] dalvik.system.NativeStart.run(Native Method) Thread[Binder Thread #2,5,main] dalvik.system.NativeStart.run(Native Method) Thread[pool-1-thread-1,5,main] java.lang.Object.wait(Native Method) java.lang.Thread.parkFor(Thread.java:1535) java.lang.LangAccessImpl.parkFor(LangAccessImpl.java:48) sun.misc.Unsafe.park(Unsafe.java:317) java.util.concurrent.locks.LockSupport.park(LockSupport.java:131) java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1996) java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:359) java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1001) java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1061) java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:561) java.lang.Thread.run(Thread.java:1096) Thread[Signal Catcher,5,system] dalvik.system.NativeStart.run(Native Method) Thread[main,5,main] java.lang.Object.wait(Native Method) java.lang.Object.wait(Object.java:288) android.os.MessageQueue.next(MessageQueue.java:146) android.os.Looper.loop(Looper.java:110) android.app.ActivityThread.main(ActivityThread.java:4627) java.lang.reflect.Method.invokeNative(Native Method) java.lang.reflect.Method.invoke(Method.java:521) com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:868) com.android.internal.os.ZygoteInit.main(ZygoteInit.java:626) dalvik.system.NativeStart.main(Native Method) Thread[Timer-0,5,main] java.lang.Object.wait(Native Method) java.lang.Object.wait(Object.java:326) java.util.Timer$TimerImpl.run(Timer.java:243) Thread[Binder Thread #1,5,main] dalvik.system.NativeStart.run(Native Method) 2 INFO TEST-UNEXPECTED-FAIL | testBrowserDiscovery | GeckoEventExpecter - blockForEvent timeout: Gecko:Ready Exception caught during test! junit.framework.AssertionFailedError: 2 INFO TEST-UNEXPECTED-FAIL | testBrowserDiscovery | GeckoEventExpecter - blockForEvent timeout: Gecko:Ready at junit.framework.Assert.fail(Assert.java:47) at org.mozilla.gecko.FennecMochitestAssert._logMochitestResult(FennecMochitestAssert.java:110) at org.mozilla.gecko.FennecMochitestAssert.ok(FennecMochitestAssert.java:139) at org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForEvent(FennecNativeActions.java:96) at org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForEvent(FennecNativeActions.java:80) at org.mozilla.gecko.tests.BaseTest.blockForGeckoReady(BaseTest.java:80) at org.mozilla.gecko.tests.JavascriptTest.testJavascript(JavascriptTest.java:95) at java.lang.reflect.Method.invokeNative(Native Method) at java.lang.reflect.Method.invoke(Method.java:521) at android.test.InstrumentationTestCase.runMethod(InstrumentationTestCase.java:204) at android.test.InstrumentationTestCase.runTest(InstrumentationTestCase.java:194) at android.test.ActivityInstrumentationTestCase2.runTest(ActivityInstrumentationTestCase2.java:186) at org.mozilla.gecko.tests.BaseTest.runTest(BaseTest.java:145) at junit.framework.TestCase.runBare(TestCase.java:127) at junit.framework.TestResult$1.protect(TestResult.java:106) at junit.framework.TestResult.runProtected(TestResult.java:124) at junit.framework.TestResult.run(TestResult.java:109) at junit.framework.TestCase.run(TestCase.java:118) at android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:169) at android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:154) at android.test.InstrumentationTestRunner.onStart(InstrumentationTestRunner.java:520) at android.app.Instrumentation$InstrumentationThread.run(Instrumentation.java:1447) 3 INFO TEST-UNEXPECTED-FAIL | testBrowserDiscovery | Exception caught - junit.framework.AssertionFailedError: 2 INFO TEST-UNEXPECTED-FAIL | testBrowserDiscovery | GeckoEventExpecter - blockForEvent timeout: Gecko:Ready 4 INFO TEST-END | testBrowserDiscovery | finished in 100719ms 5 INFO TEST-START | Shutdown 6 INFO Passed: 0 7 INFO Failed: 2 8 INFO Todo: 0 9 INFO SimpleTest FINISHED
We have various bugs with timeouts on Gecko:Ready now (various startup problems), and at first I thought this was just a variation, but this seems different, and related to the recent addition - bug 917942 - of testAccounts. The reported errors start with testBrowserDiscovery which comes right after testAccounts. Notice that we find anr reports at the end of testAccounts: 31 INFO TEST-END | testAccounts | finished in 7176ms 32 INFO TEST-START | Shutdown 33 INFO Passed: 17 34 INFO Failed: 0 35 INFO Todo: 0 36 INFO SimpleTest FINISHED INFO | automation.py | Application ran for: 0:00:15.728930 INFO | zombiecheck | Reading PID log: /tmp/tmp0WlgNxpidlog Contents of /data/anr/traces.txt: ----- pid 1505 at 2014-02-26 20:47:18 ----- Cmd line: com.mozilla.SUTAgentAndroid DALVIK THREADS: "main" prio=5 tid=1 WAIT | group="main" sCount=1 dsCount=0 s=N obj=0x4001d928 self=0xccd8 | sysTid=1505 nice=0 sched=0/0 cgrp=unknown handle=-1345017812 | schedstat=( 704256000 445003000 1844 ) at java.lang.Object.wait(Native Method) - waiting on <0x48503830> (a android.os.MessageQueue) ... :rnewman -- any idea what's happening?
Blocks: 917942
Flags: needinfo?(rnewman)
That test launches FxAccountsGetStartedActivity as its last action, then ends the test. If the test harness is somehow expecting that resuming the last activity will open BrowserApp, or the previous test will have ended all of its activities, it's mistaken.
Flags: needinfo?(rnewman)
Closing bugs where TBPLbot has previously commented, but have now not been modified for >3 months & do not contain the whiteboard strings for disabled/annotated tests or use the keyword leave-open. Filter on: mass-intermittent-bug-closure-2014-07
Status: NEW → RESOLVED
Closed: 11 years ago
Resolution: --- → WORKSFORME
Product: Firefox for Android → Firefox for Android Graveyard
You need to log in before you can comment on or make changes to this bug.