Closed Bug 1091955 Opened 10 years ago Closed 10 years ago

Intermittent testGeckoRequest | GeckoEventExpecter - blockForEvent timeout: Gecko:Ready

Categories

(Firefox for Android Graveyard :: General, defect)

x86_64
Windows 8.1
defect
Not set
normal

Tracking

(Not tracked)

RESOLVED WORKSFORME

People

(Reporter: KWierso, Assigned: gbrown)

References

Details

(Keywords: intermittent-failure)

14:11:12 INFO - TEST-START | testGeckoRequest 14:11:12 INFO - TEST-PASS | testGeckoRequest | Robocop tests need the test device screen to be powered on. - 14:11:12 INFO - Dumping ALL the threads! 14:11:12 INFO - Thread[GeckoANRReporter,5,main] 14:11:12 INFO - android.os.MessageQueue.nativePollOnce(Native Method) 14:11:12 INFO - android.os.MessageQueue.next(MessageQueue.java:125) 14:11:12 INFO - android.os.Looper.loop(Looper.java:124) 14:11:12 INFO - org.mozilla.gecko.ANRReporter$1.run(ANRReporter.java:96) 14:11:12 INFO - java.lang.Thread.run(Thread.java:856) 14:11:12 INFO - Thread[Thread-212,5,main] 14:11:12 INFO - dalvik.system.NativeStart.run(Native Method) 14:11:12 INFO - Thread[FinalizerWatchdogDaemon,5,system] 14:11:12 INFO - java.lang.Object.wait(Native Method) 14:11:12 INFO - java.lang.Object.wait(Object.java:364) 14:11:12 INFO - java.lang.Daemons$FinalizerWatchdogDaemon.waitForObject(Daemons.java:230) 14:11:12 INFO - java.lang.Daemons$FinalizerWatchdogDaemon.run(Daemons.java:207) 14:11:12 INFO - java.lang.Thread.run(Thread.java:856) 14:11:12 INFO - Thread[Signal Catcher,5,system] 14:11:12 INFO - dalvik.system.NativeStart.run(Native Method) 14:11:12 INFO - Thread[Picasso-Idle,5,main] 14:11:12 INFO - java.lang.Object.wait(Native Method) 14:11:12 INFO - java.lang.Thread.parkFor(Thread.java:1231) 14:11:12 INFO - sun.misc.Unsafe.park(Unsafe.java:323) 14:11:12 INFO - java.util.concurrent.locks.LockSupport.park(LockSupport.java:159) 14:11:12 INFO - java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019) 14:11:12 INFO - java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413) 14:11:12 INFO - java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013) 14:11:12 INFO - java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073) 14:11:12 INFO - java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573) 14:11:12 INFO - java.lang.Thread.run(Thread.java:856) 14:11:12 INFO - com.squareup.picasso.Utils$PicassoThread.run(Utils.java:294) 14:11:12 INFO - Thread[ReferenceQueueDaemon,5,system] 14:11:12 INFO - java.lang.Object.wait(Native Method) 14:11:12 INFO - java.lang.Object.wait(Object.java:364) 14:11:12 INFO - java.lang.Daemons$ReferenceQueueDaemon.run(Daemons.java:130) 14:11:12 INFO - java.lang.Thread.run(Thread.java:856) 14:11:12 INFO - Thread[ModernAsyncTask #2,5,main] 14:11:12 INFO - java.lang.Object.wait(Native Method) 14:11:12 INFO - java.lang.Thread.parkFor(Thread.java:1231) 14:11:12 INFO - sun.misc.Unsafe.park(Unsafe.java:323) 14:11:12 INFO - java.util.concurrent.locks.LockSupport.park(LockSupport.java:159) 14:11:12 INFO - java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019) 14:11:12 INFO - java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413) 14:11:12 INFO - java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013) 14:11:12 INFO - java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073) 14:11:12 INFO - java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573) 14:11:12 INFO - java.lang.Thread.run(Thread.java:856) 14:11:12 INFO - Thread[Picasso-Dispatcher,5,main] 14:11:12 INFO - android.os.MessageQueue.nativePollOnce(Native Method) 14:11:12 INFO - android.os.MessageQueue.next(MessageQueue.java:125) 14:11:12 INFO - android.os.Looper.loop(Looper.java:124) 14:11:12 INFO - android.os.HandlerThread.run(HandlerThread.java:60) 14:11:12 INFO - Thread[Binder_2,5,main] 14:11:12 INFO - dalvik.system.NativeStart.run(Native Method) 14:11:12 INFO - Thread[Picasso-Idle,5,main] 14:11:12 INFO - java.lang.Object.wait(Native Method) 14:11:12 INFO - java.lang.Thread.parkFor(Thread.java:1231) 14:11:12 INFO - sun.misc.Unsafe.park(Unsafe.java:323) 14:11:12 INFO - java.util.concurrent.locks.LockSupport.park(LockSupport.java:159) 14:11:12 INFO - java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019) 14:11:12 INFO - java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413) 14:11:12 INFO - java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013) 14:11:12 INFO - java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073) 14:11:12 INFO - java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573) 14:11:12 INFO - java.lang.Thread.run(Thread.java:856) 14:11:12 INFO - com.squareup.picasso.Utils$PicassoThread.run(Utils.java:294) 14:11:12 INFO - Thread[FinalizerDaemon,5,system] 14:11:12 INFO - java.lang.Object.wait(Native Method) 14:11:12 INFO - java.lang.Object.wait(Object.java:401) 14:11:12 INFO - java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:102) 14:11:12 INFO - java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:73) 14:11:12 INFO - java.lang.Daemons$FinalizerDaemon.run(Daemons.java:170) 14:11:12 INFO - java.lang.Thread.run(Thread.java:856) 14:11:12 INFO - Thread[Binder_1,5,main] 14:11:12 INFO - dalvik.system.NativeStart.run(Native Method) 14:11:12 INFO - Thread[Picasso-Stats,5,main] 14:11:12 INFO - android.os.MessageQueue.nativePollOnce(Native Method) 14:11:12 INFO - android.os.MessageQueue.next(MessageQueue.java:125) 14:11:12 INFO - android.os.Looper.loop(Looper.java:124) 14:11:12 INFO - android.os.HandlerThread.run(HandlerThread.java:60) 14:11:12 INFO - Thread[Gecko,5,main] 14:11:12 INFO - org.mozilla.gecko.mozglue.GeckoLoader.nativeRun(Native Method) 14:11:12 INFO - org.mozilla.gecko.GeckoAppShell.runGecko(GeckoAppShell.java:369) 14:11:12 INFO - org.mozilla.gecko.GeckoThread.run(GeckoThread.java:190) 14:11:12 INFO - Thread[JDWP,5,system] 14:11:12 INFO - dalvik.system.NativeStart.run(Native Method) 14:11:12 INFO - Thread[actionMode,5,main] 14:11:12 INFO - java.lang.Object.wait(Native Method) 14:11:12 INFO - java.lang.Object.wait(Object.java:364) 14:11:12 INFO - java.util.Timer$TimerImpl.run(Timer.java:214) 14:11:12 INFO - Thread[GC,5,system] 14:11:12 INFO - dalvik.system.NativeStart.run(Native Method) 14:11:12 INFO - Thread[Picasso-refQueue,5,main] 14:11:12 INFO - java.lang.Object.wait(Native Method) 14:11:12 INFO - java.lang.Object.wait(Object.java:401) 14:11:12 INFO - java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:102) 14:11:12 INFO - java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:73) 14:11:12 INFO - com.squareup.picasso.Picasso$CleanupThread.run(Picasso.java:352) 14:11:12 INFO - Thread[Instr: org.mozilla.gecko.FennecInstrumentationTestRunner,5,main] 14:11:12 INFO - dalvik.system.VMStack.getThreadStackTrace(Native Method) 14:11:12 INFO - java.lang.Thread.getStackTrace(Thread.java:591) 14:11:12 INFO - java.lang.Thread.getAllStackTraces(Thread.java:528) 14:11:12 INFO - org.mozilla.gecko.FennecNativeDriver.logAllStackTraces(FennecNativeDriver.java:315) 14:11:12 INFO - org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForEvent(FennecNativeActions.java:95) 14:11:12 INFO - org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForEvent(FennecNativeActions.java:80) 14:11:12 INFO - org.mozilla.gecko.tests.helpers.GeckoHelper.blockForEvent(GeckoHelper.java:46) 14:11:12 INFO - org.mozilla.gecko.tests.helpers.GeckoHelper.blockForReady(GeckoHelper.java:30) 14:11:12 INFO - org.mozilla.gecko.tests.testGeckoRequest.testGeckoRequest(testGeckoRequest.java:40) 14:11:12 INFO - java.lang.reflect.Method.invokeNative(Native Method) 14:11:12 INFO - java.lang.reflect.Method.invoke(Method.java:511) 14:11:12 INFO - android.test.InstrumentationTestCase.runMethod(InstrumentationTestCase.java:214) 14:11:12 INFO - android.test.InstrumentationTestCase.runTest(InstrumentationTestCase.java:199) 14:11:12 INFO - android.test.ActivityInstrumentationTestCase2.runTest(ActivityInstrumentationTestCase2.java:192) 14:11:12 INFO - junit.framework.TestCase.runBare(TestCase.java:134) 14:11:12 INFO - junit.framework.TestResult$1.protect(TestResult.java:115) 14:11:12 INFO - junit.framework.TestResult.runProtected(TestResult.java:133) 14:11:12 INFO - junit.framework.TestResult.run(TestResult.java:118) 14:11:12 INFO - junit.framework.TestCase.run(TestCase.java:124) 14:11:12 INFO - android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:190) 14:11:12 INFO - android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:175) 14:11:12 INFO - android.test.InstrumentationTestRunner.onStart(InstrumentationTestRunner.java:555) 14:11:12 INFO - android.app.Instrumentation$InstrumentationThread.run(Instrumentation.java:1661) 14:11:12 INFO - Thread[ModernAsyncTask #1,5,main] 14:11:12 INFO - java.lang.Object.wait(Native Method) 14:11:12 INFO - java.lang.Thread.parkFor(Thread.java:1231) 14:11:12 INFO - sun.misc.Unsafe.park(Unsafe.java:323) 14:11:12 INFO - java.util.concurrent.locks.LockSupport.park(LockSupport.java:159) 14:11:12 INFO - java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019) 14:11:12 INFO - java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413) 14:11:12 INFO - java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013) 14:11:12 INFO - java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073) 14:11:12 INFO - java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573) 14:11:12 INFO - java.lang.Thread.run(Thread.java:856) 14:11:12 INFO - Thread[main,5,main] 14:11:12 INFO - android.os.MessageQueue.nativePollOnce(Native Method) 14:11:12 INFO - android.os.MessageQueue.next(MessageQueue.java:125) 14:11:12 INFO - android.os.Looper.loop(Looper.java:124) 14:11:12 INFO - android.app.ActivityThread.main(ActivityThread.java:5039) 14:11:12 INFO - java.lang.reflect.Method.invokeNative(Native Method) 14:11:12 INFO - java.lang.reflect.Method.invoke(Method.java:511) 14:11:12 INFO - com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:793) 14:11:12 INFO - com.android.internal.os.ZygoteInit.main(ZygoteInit.java:560) 14:11:12 INFO - dalvik.system.NativeStart.main(Native Method) 14:11:12 INFO - Thread[pool-2-thread-1,5,main] 14:11:12 INFO - java.lang.Object.wait(Native Method) 14:11:12 INFO - java.lang.Thread.parkFor(Thread.java:1231) 14:11:12 INFO - sun.misc.Unsafe.park(Unsafe.java:323) 14:11:12 INFO - java.util.concurrent.locks.LockSupport.park(LockSupport.java:159) 14:11:12 INFO - java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019) 14:11:12 INFO - java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413) 14:11:12 INFO - java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013) 14:11:12 INFO - java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073) 14:11:12 INFO - java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573) 14:11:12 INFO - java.lang.Thread.run(Thread.java:856) 14:11:12 INFO - Thread[GeckoBackgroundThread,5,main] 14:11:12 INFO - android.os.MessageQueue.nativePollOnce(Native Method) 14:11:12 INFO - android.os.MessageQueue.next(MessageQueue.java:125) 14:11:12 INFO - android.os.Looper.loop(Looper.java:124) 14:11:12 INFO - org.mozilla.gecko.util.GeckoBackgroundThread.run(GeckoBackgroundThread.java:43) 14:11:12 INFO - Thread[Timer-0,5,main] 14:11:12 INFO - java.lang.Object.wait(Native Method) 14:11:12 INFO - java.lang.Object.wait(Object.java:401) 14:11:12 INFO - java.util.Timer$TimerImpl.run(Timer.java:238) 14:11:12 INFO - Thread[pool-3-thread-1,5,main] 14:11:12 INFO - java.lang.Object.wait(Native Method) 14:11:12 INFO - java.lang.Thread.parkFor(Thread.java:1231) 14:11:12 INFO - sun.misc.Unsafe.park(Unsafe.java:323) 14:11:12 INFO - java.util.concurrent.locks.LockSupport.park(LockSupport.java:159) 14:11:12 INFO - java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019) 14:11:12 INFO - java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413) 14:11:12 INFO - java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013) 14:11:12 INFO - java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073) 14:11:12 INFO - java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573) 14:11:12 INFO - java.lang.Thread.run(Thread.java:856) 14:11:12 WARNING - TEST-UNEXPECTED-FAIL | testGeckoRequest | GeckoEventExpecter - blockForEvent timeout: Gecko:Ready 14:11:12 INFO - EventExpecter: no longer listening for Robocop:JS 14:11:12 INFO - TEST-OK | testGeckoRequest | took 91981ms 14:11:12 INFO - TEST-START | Shutdown 14:11:12 INFO - Passed: 1 14:11:12 INFO - Failed: 1 14:11:12 INFO - Todo: 0 14:11:12 INFO - SimpleTest FINISHED 14:11:12 INFO - INFO | automation.py | Application ran for: 0:01:33.945498 14:11:12 INFO - INFO | zombiecheck | Reading PID log: /tmp/tmpSMfWyQpidlog 14:11:12 INFO - Contents of /data/anr/traces.txt: 14:11:12 INFO - 14:11:12 INFO -
More "ExchangeService" occurring in the vicinity of this test failure too.
Flags: needinfo?(gbrown)
Filed bug 1092192 to disable ExchangeService.
Flags: needinfo?(gbrown)
All of the recent failures here are timeouts on Robocop:JS (not Gecko:Ready) -- bug 1094649. The original issue was only ever observed on x86; we no longer run robocop on x86.
Assignee: nobody → gbrown
Status: NEW → RESOLVED
Closed: 10 years ago
Resolution: --- → WORKSFORME
See Also: → 1094649
Product: Firefox for Android → Firefox for Android Graveyard
You need to log in before you can comment on or make changes to this bug.