Closed Bug 1036578 Opened 10 years ago Closed 9 years ago

Intermittent testAccounts | GeckoEventExpecter - blockForEvent timeout: Robocop:JS

Categories

(Firefox for Android Graveyard :: General, defect)

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

Tracking

(Not tracked)

Status:
RESOLVED WORKSFORME

People

(Reporter: RyanVM, Unassigned)

Details

(Keywords: intermittent-failure) 

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

Android 4.2 x86 Emulator mozilla-inbound opt test androidx86-set-4 on 2014-07-09 01:38:33 PDT for push 8d6c8b4d34c5
slave: talos-linux64-ix-076

02:14:18     INFO -  0 INFO SimpleTest START
02:14:18     INFO -  1 INFO TEST-START | testAccounts
02:14:18     INFO -  2 INFO TEST-PASS | testAccounts | Given message occurred for registered event: {"type":"Gecko:Ready"} - Gecko:Ready should equal Gecko:Ready
02:14:18     INFO -  EventExpecter: no longer listening for Gecko:Ready
02:14:18     INFO -  Registered listener for Robocop:JS
02:14:18     INFO -  Loading JavaScript test from http://mochi.test:8888/tests/robocop/robocop_javascript.html?path=testAccounts.js
02:14:18     INFO -  Dumping ALL the threads!
02:14:18     INFO -  Thread[Signal Catcher,5,system]
02:14:18     INFO -  dalvik.system.NativeStart.run(Native Method)
02:14:18     INFO -  
02:14:18     INFO -  Thread[main,5,main]
02:14:18     INFO -  android.os.MessageQueue.nativePollOnce(Native Method)
02:14:18     INFO -  android.os.MessageQueue.next(MessageQueue.java:125)
02:14:18     INFO -  android.os.Looper.loop(Looper.java:124)
02:14:18     INFO -  android.app.ActivityThread.main(ActivityThread.java:5039)
02:14:18     INFO -  java.lang.reflect.Method.invokeNative(Native Method)
02:14:18     INFO -  java.lang.reflect.Method.invoke(Method.java:511)
02:14:18     INFO -  com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:793)
02:14:18     INFO -  com.android.internal.os.ZygoteInit.main(ZygoteInit.java:560)
02:14:18     INFO -  dalvik.system.NativeStart.main(Native Method)
02:14:18     INFO -  
02:14:18     INFO -  Thread[Thread-115,5,main]
02:14:18     INFO -  dalvik.system.NativeStart.run(Native Method)
02:14:18     INFO -  
02:14:18     INFO -  Thread[Binder_1,5,main]
02:14:18     INFO -  dalvik.system.NativeStart.run(Native Method)
02:14:18     INFO -  
02:14:18     INFO -  Thread[ModernAsyncTask #2,5,main]
02:14:18     INFO -  java.lang.Object.wait(Native Method)
02:14:18     INFO -  java.lang.Thread.parkFor(Thread.java:1231)
02:14:18     INFO -  sun.misc.Unsafe.park(Unsafe.java:323)
02:14:18     INFO -  java.util.concurrent.locks.LockSupport.park(LockSupport.java:159)
02:14:18     INFO -  java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019)
02:14:18     INFO -  java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413)
02:14:18     INFO -  java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013)
02:14:18     INFO -  java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073)
02:14:18     INFO -  java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573)
02:14:18     INFO -  java.lang.Thread.run(Thread.java:856)
02:14:18     INFO -  
02:14:18     INFO -  Thread[Picasso-Idle,5,main]
02:14:18     INFO -  java.lang.Object.wait(Native Method)
02:14:18     INFO -  java.lang.Thread.parkFor(Thread.java:1231)
02:14:18     INFO -  sun.misc.Unsafe.park(Unsafe.java:323)
02:14:18     INFO -  java.util.concurrent.locks.LockSupport.park(LockSupport.java:159)
02:14:18     INFO -  java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019)
02:14:18     INFO -  java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413)
02:14:18     INFO -  java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013)
02:14:18     INFO -  java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073)
02:14:18     INFO -  java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573)
02:14:18     INFO -  java.lang.Thread.run(Thread.java:856)
02:14:18     INFO -  com.squareup.picasso.Utils$PicassoThread.run(Utils.java:294)
02:14:18     INFO -  
02:14:18     INFO -  Thread[ModernAsyncTask #1,5,main]
02:14:18     INFO -  java.lang.Object.wait(Native Method)
02:14:18     INFO -  java.lang.Thread.parkFor(Thread.java:1231)
02:14:18     INFO -  sun.misc.Unsafe.park(Unsafe.java:323)
02:14:18     INFO -  java.util.concurrent.locks.LockSupport.park(LockSupport.java:159)
02:14:18     INFO -  java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019)
02:14:18     INFO -  java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413)
02:14:18     INFO -  java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013)
02:14:18     INFO -  java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073)
02:14:18     INFO -  java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573)
02:14:18     INFO -  java.lang.Thread.run(Thread.java:856)
02:14:18     INFO -  
02:14:18     INFO -  Thread[Gecko,5,main]
02:14:18     INFO -  org.mozilla.gecko.mozglue.GeckoLoader.nativeRun(Native Method)
02:14:18     INFO -  org.mozilla.gecko.GeckoAppShell.runGecko(GeckoAppShell.java:358)
02:14:18     INFO -  org.mozilla.gecko.GeckoThread.run(GeckoThread.java:176)
02:14:18     INFO -  
02:14:18     INFO -  Thread[Binder_2,5,main]
02:14:18     INFO -  dalvik.system.NativeStart.run(Native Method)
02:14:18     INFO -  
02:14:18     INFO -  Thread[FinalizerWatchdogDaemon,5,system]
02:14:18     INFO -  java.lang.Object.wait(Native Method)
02:14:18     INFO -  java.lang.Object.wait(Object.java:364)
02:14:18     INFO -  java.lang.Daemons$FinalizerWatchdogDaemon.waitForObject(Daemons.java:230)
02:14:18     INFO -  java.lang.Daemons$FinalizerWatchdogDaemon.run(Daemons.java:207)
02:14:18     INFO -  java.lang.Thread.run(Thread.java:856)
02:14:18     INFO -  
02:14:18     INFO -  Thread[Timer-0,5,main]
02:14:18     INFO -  java.lang.Object.wait(Native Method)
02:14:18     INFO -  java.lang.Object.wait(Object.java:401)
02:14:18     INFO -  java.util.Timer$TimerImpl.run(Timer.java:238)
02:14:18     INFO -  
02:14:18     INFO -  Thread[Picasso-refQueue,5,main]
02:14:18     INFO -  java.lang.Object.wait(Native Method)
02:14:18     INFO -  java.lang.Object.wait(Object.java:401)
02:14:18     INFO -  java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:102)
02:14:18     INFO -  java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:73)
02:14:18     INFO -  com.squareup.picasso.Picasso$CleanupThread.run(Picasso.java:352)
02:14:18     INFO -  
02:14:18     INFO -  Thread[GeckoANRReporter,5,main]
02:14:18     INFO -  android.os.MessageQueue.nativePollOnce(Native Method)
02:14:18     INFO -  android.os.MessageQueue.next(MessageQueue.java:125)
02:14:18     INFO -  android.os.Looper.loop(Looper.java:124)
02:14:18     INFO -  org.mozilla.gecko.ANRReporter$1.run(ANRReporter.java:95)
02:14:18     INFO -  java.lang.Thread.run(Thread.java:856)
02:14:18     INFO -  
02:14:18     INFO -  Thread[Picasso-Dispatcher,5,main]
02:14:18     INFO -  android.os.MessageQueue.nativePollOnce(Native Method)
02:14:18     INFO -  android.os.MessageQueue.next(MessageQueue.java:125)
02:14:18     INFO -  android.os.Looper.loop(Looper.java:124)
02:14:18     INFO -  android.os.HandlerThread.run(HandlerThread.java:60)
02:14:18     INFO -  
02:14:18     INFO -  Thread[JDWP,5,system]
02:14:18     INFO -  dalvik.system.NativeStart.run(Native Method)
02:14:18     INFO -  
02:14:18     INFO -  Thread[GC,5,system]
02:14:18     INFO -  dalvik.system.NativeStart.run(Native Method)
02:14:18     INFO -  
02:14:18     INFO -  Thread[GeckoBackgroundThread,5,main]
02:14:18     INFO -  android.os.MessageQueue.nativePollOnce(Native Method)
02:14:18     INFO -  android.os.MessageQueue.next(MessageQueue.java:125)
02:14:18     INFO -  android.os.Looper.loop(Looper.java:124)
02:14:18     INFO -  org.mozilla.gecko.util.GeckoBackgroundThread.run(GeckoBackgroundThread.java:32)
02:14:18     INFO -  
02:14:18     INFO -  Thread[Instr: org.mozilla.gecko.FennecInstrumentationTestRunner,5,main]
02:14:18     INFO -  dalvik.system.VMStack.getThreadStackTrace(Native Method)
02:14:18     INFO -  java.lang.Thread.getStackTrace(Thread.java:591)
02:14:18     INFO -  java.lang.Thread.getAllStackTraces(Thread.java:528)
02:14:18     INFO -  org.mozilla.gecko.FennecNativeDriver.logAllStackTraces(FennecNativeDriver.java:301)
02:14:18     INFO -  org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForEvent(FennecNativeActions.java:95)
02:14:18     INFO -  org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForEvent(FennecNativeActions.java:80)
02:14:18     INFO -  org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForEventData(FennecNativeActions.java:144)
02:14:18     INFO -  org.mozilla.gecko.tests.JavascriptTest.testJavascript(JavascriptTest.java:47)
02:14:18     INFO -  org.mozilla.gecko.tests.testAccounts.testJavascript(testAccounts.java:15)
02:14:18     INFO -  java.lang.reflect.Method.invokeNative(Native Method)
02:14:18     INFO -  java.lang.reflect.Method.invoke(Method.java:511)
02:14:18     INFO -  android.test.InstrumentationTestCase.runMethod(InstrumentationTestCase.java:214)
02:14:18     INFO -  android.test.InstrumentationTestCase.runTest(InstrumentationTestCase.java:199)
02:14:18     INFO -  android.test.ActivityInstrumentationTestCase2.runTest(ActivityInstrumentationTestCase2.java:192)
02:14:18     INFO -  org.mozilla.gecko.tests.BaseTest.runTest(BaseTest.java:144)
02:14:18     INFO -  junit.framework.TestCase.runBare(TestCase.java:134)
02:14:18     INFO -  junit.framework.TestResult$1.protect(TestResult.java:115)
02:14:18     INFO -  junit.framework.TestResult.runProtected(TestResult.java:133)
02:14:18     INFO -  junit.framework.TestResult.run(TestResult.java:118)
02:14:18     INFO -  junit.framework.TestCase.run(TestCase.java:124)
02:14:18     INFO -  android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:190)
02:14:18     INFO -  android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:175)
02:14:18     INFO -  android.test.InstrumentationTestRunner.onStart(InstrumentationTestRunner.java:555)
02:14:18     INFO -  android.app.Instrumentation$InstrumentationThread.run(Instrumentation.java:1661)
02:14:18     INFO -  
02:14:18     INFO -  Thread[Picasso-Idle,5,main]
02:14:18     INFO -  java.lang.Object.wait(Native Method)
02:14:18     INFO -  java.lang.Thread.parkFor(Thread.java:1231)
02:14:18     INFO -  sun.misc.Unsafe.park(Unsafe.java:323)
02:14:18     INFO -  java.util.concurrent.locks.LockSupport.park(LockSupport.java:159)
02:14:18     INFO -  java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019)
02:14:18     INFO -  java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413)
02:14:18     INFO -  java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013)
02:14:18     INFO -  java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073)
02:14:18     INFO -  java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573)
02:14:18     INFO -  java.lang.Thread.run(Thread.java:856)
02:14:18     INFO -  com.squareup.picasso.Utils$PicassoThread.run(Utils.java:294)
02:14:18     INFO -  
02:14:18     INFO -  Thread[Binder_3,5,main]
02:14:18     INFO -  dalvik.system.NativeStart.run(Native Method)
02:14:18     INFO -  
02:14:18     INFO -  Thread[Picasso-Stats,5,main]
02:14:18     INFO -  android.os.MessageQueue.nativePollOnce(Native Method)
02:14:18     INFO -  android.os.MessageQueue.next(MessageQueue.java:125)
02:14:18     INFO -  android.os.Looper.loop(Looper.java:124)
02:14:18     INFO -  android.os.HandlerThread.run(HandlerThread.java:60)
02:14:18     INFO -  
02:14:18     INFO -  Thread[FinalizerDaemon,5,system]
02:14:18     INFO -  java.lang.Object.wait(Native Method)
02:14:18     INFO -  java.lang.Object.wait(Object.java:401)
02:14:18     INFO -  java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:102)
02:14:18     INFO -  java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:73)
02:14:18     INFO -  java.lang.Daemons$FinalizerDaemon.run(Daemons.java:170)
02:14:18     INFO -  java.lang.Thread.run(Thread.java:856)
02:14:18     INFO -  
02:14:18     INFO -  Thread[ReferenceQueueDaemon,5,system]
02:14:18     INFO -  java.lang.Object.wait(Native Method)
02:14:18     INFO -  java.lang.Object.wait(Object.java:364)
02:14:18     INFO -  java.lang.Daemons$ReferenceQueueDaemon.run(Daemons.java:130)
02:14:18     INFO -  java.lang.Thread.run(Thread.java:856)
02:14:18     INFO -  
02:14:18     INFO -  Thread[actionMode,5,main]
02:14:18     INFO -  java.lang.Object.wait(Native Method)
02:14:18     INFO -  java.lang.Object.wait(Object.java:364)
02:14:18     INFO -  java.util.Timer$TimerImpl.run(Timer.java:214)
02:14:18     INFO -  
02:14:18     INFO -  Thread[pool-1-thread-1,5,main]
02:14:18     INFO -  java.lang.Object.wait(Native Method)
02:14:18     INFO -  java.lang.Thread.parkFor(Thread.java:1231)
02:14:18     INFO -  sun.misc.Unsafe.park(Unsafe.java:323)
02:14:18     INFO -  java.util.concurrent.locks.LockSupport.park(LockSupport.java:159)
02:14:18     INFO -  java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019)
02:14:18     INFO -  java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413)
02:14:18     INFO -  java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013)
02:14:18     INFO -  java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073)
02:14:18     INFO -  java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573)
02:14:18     INFO -  java.lang.Thread.run(Thread.java:856)
02:14:18     INFO -  
02:14:18     INFO -  
02:14:18     INFO -  3 INFO TEST-UNEXPECTED-FAIL | testAccounts | GeckoEventExpecter - blockForEvent timeout: Robocop:JS
02:14:18     INFO -  EventExpecter: no longer listening for Robocop:JS
02:14:18     INFO -  Unregistered listener for Robocop:JS
02:14:18     INFO -  Exception caught during test!
02:14:18     INFO -  junit.framework.AssertionFailedError: 3 INFO TEST-UNEXPECTED-FAIL | testAccounts | GeckoEventExpecter - blockForEvent timeout: Robocop:JS
02:14:18     INFO -  	at junit.framework.Assert.fail(Assert.java:50)
02:14:18     INFO -  	at org.mozilla.gecko.FennecMochitestAssert._logMochitestResult(FennecMochitestAssert.java:111)
02:14:18     INFO -  	at org.mozilla.gecko.FennecMochitestAssert.ok(FennecMochitestAssert.java:140)
02:14:18     INFO -  	at org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForEvent(FennecNativeActions.java:96)
02:14:18     INFO -  	at org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForEvent(FennecNativeActions.java:80)
02:14:18     INFO -  	at org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForEventData(FennecNativeActions.java:144)
02:14:18     INFO -  	at org.mozilla.gecko.tests.JavascriptTest.testJavascript(JavascriptTest.java:47)
02:14:18     INFO -  	at org.mozilla.gecko.tests.testAccounts.testJavascript(testAccounts.java:15)
02:14:18     INFO -  	at java.lang.reflect.Method.invokeNative(Native Method)
02:14:18     INFO -  	at java.lang.reflect.Method.invoke(Method.java:511)
02:14:18     INFO -  	at android.test.InstrumentationTestCase.runMethod(InstrumentationTestCase.java:214)
02:14:18     INFO -  	at android.test.InstrumentationTestCase.runTest(InstrumentationTestCase.java:199)
02:14:18     INFO -  	at android.test.ActivityInstrumentationTestCase2.runTest(ActivityInstrumentationTestCase2.java:192)
02:14:18     INFO -  	at org.mozilla.gecko.tests.BaseTest.runTest(BaseTest.java:144)
02:14:18     INFO -  	at junit.framework.TestCase.runBare(TestCase.java:134)
02:14:18     INFO -  	at junit.framework.TestResult$1.protect(TestResult.java:115)
02:14:18     INFO -  	at junit.framework.TestResult.runProtected(TestResult.java:133)
02:14:18     INFO -  	at junit.framework.TestResult.run(TestResult.java:118)
02:14:18     INFO -  	at junit.framework.TestCase.run(TestCase.java:124)
02:14:18     INFO -  	at android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:190)
02:14:18     INFO -  	at android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:175)
02:14:18     INFO -  	at android.test.InstrumentationTestRunner.onStart(InstrumentationTestRunner.java:555)
02:14:18     INFO -  	at android.app.Instrumentation$InstrumentationThread.run(Instrumentation.java:1661)
02:14:18     INFO -  4 INFO TEST-UNEXPECTED-FAIL | testAccounts | Exception caught - junit.framework.AssertionFailedError: 3 INFO TEST-UNEXPECTED-FAIL | testAccounts | GeckoEventExpecter - blockForEvent timeout: Robocop:JS
02:14:18     INFO -  5 INFO TEST-END | testAccounts | finished in 122590ms
02:14:18     INFO -  6 INFO TEST-START | Shutdown
02:14:18     INFO -  7 INFO Passed: 1
02:14:18  WARNING -  8 INFO Failed: 2
02:14:18  WARNING -  One or more unittests failed.
02:14:18     INFO -  9 INFO Todo: 0
02:14:18     INFO -  10 INFO SimpleTest FINISHED
Inactive; closing (see bug 1180138).
Status: NEW → RESOLVED
Closed: 9 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.