1495113 - Intermittent testEventDispatcher | GeckoEventExpecter - blockForEvent timeout: Robocop:Java

Status: RESOLVED INCOMPLETE

(Firefox for Android Graveyard :: Testing, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: rgurzau [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=202281562&repo=autoland

https://queue.taskcluster.net/v1/task/Pgmp8AecS_KREa78fT3IIQ/runs/0/artifacts/public/logs/live_backing.log

[task 2018-09-28T19:28:34.312Z] 19:28:34     INFO -  TEST-START | testEventDispatcher
[task 2018-09-28T19:29:16.389Z] 19:29:16     INFO -  GECKO | EventExpecter: no longer listening for Content:DOMContentLoaded
[task 2018-09-28T19:29:16.390Z] 19:29:16     INFO -  GECKO | EventExpecter: no longer listening for Content:DOMTitleChanged
[task 2018-09-28T19:32:26.144Z] 19:32:26     INFO -  GECKO | Dumping ALL the threads!
[task 2018-09-28T19:32:26.144Z] 19:32:26     INFO -  GECKO | Thread[Thread-81,5,main]
[task 2018-09-28T19:32:26.144Z] 19:32:26     INFO -  GECKO | dalvik.system.NativeStart.run(Native Method)
[task 2018-09-28T19:32:26.144Z] 19:32:26     INFO -  GECKO | Thread[ReferenceQueueDaemon,5,system]
[task 2018-09-28T19:32:26.144Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Native Method)
[task 2018-09-28T19:32:26.144Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Object.java:364)
[task 2018-09-28T19:32:26.144Z] 19:32:26     INFO -  GECKO | java.lang.Daemons$ReferenceQueueDaemon.run(Daemons.java:130)
[task 2018-09-28T19:32:26.144Z] 19:32:26     INFO -  GECKO | java.lang.Thread.run(Thread.java:841)
[task 2018-09-28T19:32:26.144Z] 19:32:26     INFO -  GECKO | Thread[JDWP,5,system]
[task 2018-09-28T19:32:26.144Z] 19:32:26     INFO -  GECKO | dalvik.system.NativeStart.run(Native Method)
[task 2018-09-28T19:32:26.144Z] 19:32:26     INFO -  GECKO | Thread[AsyncTask #2,5,main]
[task 2018-09-28T19:32:26.145Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Native Method)
[task 2018-09-28T19:32:26.145Z] 19:32:26     INFO -  GECKO | java.lang.Thread.parkFor(Thread.java:1205)
[task 2018-09-28T19:32:26.145Z] 19:32:26     INFO -  GECKO | sun.misc.Unsafe.park(Unsafe.java:325)
[task 2018-09-28T19:32:26.146Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.LockSupport.park(LockSupport.java:159)
[task 2018-09-28T19:32:26.146Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019)
[task 2018-09-28T19:32:26.147Z] 19:32:26     INFO -  GECKO | java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413)
[task 2018-09-28T19:32:26.147Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013)
[task 2018-09-28T19:32:26.148Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073)
[task 2018-09-28T19:32:26.148Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573)
[task 2018-09-28T19:32:26.149Z] 19:32:26     INFO -  GECKO | java.lang.Thread.run(Thread.java:841)
[task 2018-09-28T19:32:26.149Z] 19:32:26     INFO -  GECKO | Thread[Binder_3,5,main]
[task 2018-09-28T19:32:26.149Z] 19:32:26     INFO -  GECKO | dalvik.system.NativeStart.run(Native Method)
[task 2018-09-28T19:32:26.150Z] 19:32:26     INFO -  GECKO | Thread[Timer-0,5,main]
[task 2018-09-28T19:32:26.150Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Native Method)
[task 2018-09-28T19:32:26.151Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Object.java:364)
[task 2018-09-28T19:32:26.151Z] 19:32:26     INFO -  GECKO | java.util.Timer$TimerImpl.run(Timer.java:214)
[task 2018-09-28T19:32:26.151Z] 19:32:26     INFO -  GECKO | Thread[activityMonitorThread,5,main]
[task 2018-09-28T19:32:26.152Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Native Method)
[task 2018-09-28T19:32:26.152Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Object.java:401)
[task 2018-09-28T19:32:26.153Z] 19:32:26     INFO -  GECKO | android.app.Instrumentation$ActivityMonitor.waitForActivityWithTimeout(Instrumentation.java:550)
[task 2018-09-28T19:32:26.153Z] 19:32:26     INFO -  GECKO | com.robotium.solo.ActivityUtils$1.run(ActivityUtils.java:152)
[task 2018-09-28T19:32:26.154Z] 19:32:26     INFO -  GECKO | java.lang.Thread.run(Thread.java:841)
[task 2018-09-28T19:32:26.154Z] 19:32:26     INFO -  GECKO | Thread[FinalizerDaemon,5,system]
[task 2018-09-28T19:32:26.154Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Native Method)
[task 2018-09-28T19:32:26.155Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Object.java:401)
[task 2018-09-28T19:32:26.155Z] 19:32:26     INFO -  GECKO | java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:102)
[task 2018-09-28T19:32:26.156Z] 19:32:26     INFO -  GECKO | java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:73)
[task 2018-09-28T19:32:26.157Z] 19:32:26     INFO -  GECKO | java.lang.Daemons$FinalizerDaemon.run(Daemons.java:170)
[task 2018-09-28T19:32:26.157Z] 19:32:26     INFO -  GECKO | java.lang.Thread.run(Thread.java:841)
[task 2018-09-28T19:32:26.158Z] 19:32:26     INFO -  GECKO | Thread[actionMode,5,main]
[task 2018-09-28T19:32:26.158Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Native Method)
[task 2018-09-28T19:32:26.159Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Object.java:364)
[task 2018-09-28T19:32:26.159Z] 19:32:26     INFO -  GECKO | java.util.Timer$TimerImpl.run(Timer.java:214)
[task 2018-09-28T19:32:26.160Z] 19:32:26     INFO -  GECKO | Thread[ModernAsyncTask #3,5,main]
[task 2018-09-28T19:32:26.160Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Native Method)
[task 2018-09-28T19:32:26.161Z] 19:32:26     INFO -  GECKO | java.lang.Thread.parkFor(Thread.java:1205)
[task 2018-09-28T19:32:26.161Z] 19:32:26     INFO -  GECKO | sun.misc.Unsafe.park(Unsafe.java:325)
[task 2018-09-28T19:32:26.162Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.LockSupport.park(LockSupport.java:159)
[task 2018-09-28T19:32:26.162Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019)
[task 2018-09-28T19:32:26.163Z] 19:32:26     INFO -  GECKO | java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413)
[task 2018-09-28T19:32:26.163Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013)
[task 2018-09-28T19:32:26.164Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073)
[task 2018-09-28T19:32:26.164Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573)
[task 2018-09-28T19:32:26.165Z] 19:32:26     INFO -  GECKO | java.lang.Thread.run(Thread.java:841)
[task 2018-09-28T19:32:26.165Z] 19:32:26     INFO -  GECKO | Thread[ModernAsyncTask #4,5,main]
[task 2018-09-28T19:32:26.166Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Native Method)
[task 2018-09-28T19:32:26.166Z] 19:32:26     INFO -  GECKO | java.lang.Thread.parkFor(Thread.java:1205)
[task 2018-09-28T19:32:26.167Z] 19:32:26     INFO -  GECKO | sun.misc.Unsafe.park(Unsafe.java:325)
[task 2018-09-28T19:32:26.168Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.LockSupport.park(LockSupport.java:159)
[task 2018-09-28T19:32:26.169Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019)
[task 2018-09-28T19:32:26.170Z] 19:32:26     INFO -  GECKO | java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413)
[task 2018-09-28T19:32:26.171Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013)
[task 2018-09-28T19:32:26.172Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073)
[task 2018-09-28T19:32:26.173Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573)
[task 2018-09-28T19:32:26.174Z] 19:32:26     INFO -  GECKO | java.lang.Thread.run(Thread.java:841)
[task 2018-09-28T19:32:26.175Z] 19:32:26     INFO -  GECKO | Thread[FinalizerWatchdogDaemon,5,system]
[task 2018-09-28T19:32:26.175Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Native Method)
[task 2018-09-28T19:32:26.176Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Object.java:364)
[task 2018-09-28T19:32:26.177Z] 19:32:26     INFO -  GECKO | java.lang.Daemons$FinalizerWatchdogDaemon.waitForObject(Daemons.java:230)
[task 2018-09-28T19:32:26.178Z] 19:32:26     INFO -  GECKO | java.lang.Daemons$FinalizerWatchdogDaemon.run(Daemons.java:207)
[task 2018-09-28T19:32:26.179Z] 19:32:26     INFO -  GECKO | java.lang.Thread.run(Thread.java:841)
[task 2018-09-28T19:32:26.180Z] 19:32:26     INFO -  GECKO | Thread[Signal Catcher,5,system]
[task 2018-09-28T19:32:26.181Z] 19:32:26     INFO -  GECKO | dalvik.system.NativeStart.run(Native Method)
[task 2018-09-28T19:32:26.182Z] 19:32:26     INFO -  GECKO | Thread[AsyncTask #5,5,main]
[task 2018-09-28T19:32:26.183Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Native Method)
[task 2018-09-28T19:32:26.184Z] 19:32:26     INFO -  GECKO | java.lang.Thread.parkFor(Thread.java:1205)
[task 2018-09-28T19:32:26.184Z] 19:32:26     INFO -  GECKO | sun.misc.Unsafe.park(Unsafe.java:325)
[task 2018-09-28T19:32:26.185Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.LockSupport.park(LockSupport.java:159)
[task 2018-09-28T19:32:26.186Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019)
[task 2018-09-28T19:32:26.187Z] 19:32:26     INFO -  GECKO | java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413)
[task 2018-09-28T19:32:26.188Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013)
[task 2018-09-28T19:32:26.188Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073)
[task 2018-09-28T19:32:26.189Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573)
[task 2018-09-28T19:32:26.190Z] 19:32:26     INFO -  GECKO | java.lang.Thread.run(Thread.java:841)
[task 2018-09-28T19:32:26.190Z] 19:32:26     INFO -  GECKO | Thread[GeckoBackgroundThread,5,main]
[task 2018-09-28T19:32:26.191Z] 19:32:26     INFO -  GECKO | android.os.MessageQueue.nativePollOnce(Native Method)
[task 2018-09-28T19:32:26.191Z] 19:32:26     INFO -  GECKO | android.os.MessageQueue.next(MessageQueue.java:132)
[task 2018-09-28T19:32:26.192Z] 19:32:26     INFO -  GECKO | android.os.Looper.loop(Looper.java:124)
[task 2018-09-28T19:32:26.192Z] 19:32:26     INFO -  GECKO | org.mozilla.gecko.util.GeckoBackgroundThread.run(GeckoBackgroundThread.java:43)
[task 2018-09-28T19:32:26.193Z] 19:32:26     INFO -  GECKO | Thread[ModernAsyncTask #2,5,main]
[task 2018-09-28T19:32:26.193Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Native Method)
[task 2018-09-28T19:32:26.194Z] 19:32:26     INFO -  GECKO | java.lang.Thread.parkFor(Thread.java:1205)
[task 2018-09-28T19:32:26.194Z] 19:32:26     INFO -  GECKO | sun.misc.Unsafe.park(Unsafe.java:325)
[task 2018-09-28T19:32:26.195Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.LockSupport.park(LockSupport.java:159)
[task 2018-09-28T19:32:26.195Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019)
[task 2018-09-28T19:32:26.196Z] 19:32:26     INFO -  GECKO | java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413)
[task 2018-09-28T19:32:26.196Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013)
[task 2018-09-28T19:32:26.197Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073)
[task 2018-09-28T19:32:26.198Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573)
[task 2018-09-28T19:32:26.198Z] 19:32:26     INFO -  GECKO | java.lang.Thread.run(Thread.java:841)
[task 2018-09-28T19:32:26.199Z] 19:32:26     INFO -  GECKO | Thread[AsyncTask #3,5,main]
[task 2018-09-28T19:32:26.199Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Native Method)
[task 2018-09-28T19:32:26.200Z] 19:32:26     INFO -  GECKO | java.lang.Thread.parkFor(Thread.java:1205)
[task 2018-09-28T19:32:26.200Z] 19:32:26     INFO -  GECKO | sun.misc.Unsafe.park(Unsafe.java:325)
[task 2018-09-28T19:32:26.201Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.LockSupport.park(LockSupport.java:159)
[task 2018-09-28T19:32:26.202Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019)
[task 2018-09-28T19:32:26.203Z] 19:32:26     INFO -  GECKO | java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413)
[task 2018-09-28T19:32:26.203Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013)
[task 2018-09-28T19:32:26.204Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073)
[task 2018-09-28T19:32:26.205Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573)
[task 2018-09-28T19:32:26.206Z] 19:32:26     INFO -  GECKO | java.lang.Thread.run(Thread.java:841)
[task 2018-09-28T19:32:26.206Z] 19:32:26     INFO -  GECKO | Thread[GeckoIconTask,5,main]
[task 2018-09-28T19:32:26.207Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Native Method)
[task 2018-09-28T19:32:26.208Z] 19:32:26     INFO -  GECKO | java.lang.Thread.parkFor(Thread.java:1205)
[task 2018-09-28T19:32:26.208Z] 19:32:26     INFO -  GECKO | sun.misc.Unsafe.park(Unsafe.java:325)
[task 2018-09-28T19:32:26.209Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.LockSupport.park(LockSupport.java:159)
[task 2018-09-28T19:32:26.209Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019)
[task 2018-09-28T19:32:26.210Z] 19:32:26     INFO -  GECKO | java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413)
[task 2018-09-28T19:32:26.211Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013)
[task 2018-09-28T19:32:26.211Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073)
[task 2018-09-28T19:32:26.212Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573)
[task 2018-09-28T19:32:26.213Z] 19:32:26     INFO -  GECKO | java.lang.Thread.run(Thread.java:841)
[task 2018-09-28T19:32:26.213Z] 19:32:26     INFO -  GECKO | Thread[Compiler,5,system]
[task 2018-09-28T19:32:26.214Z] 19:32:26     INFO -  GECKO | dalvik.system.NativeStart.run(Native Method)
[task 2018-09-28T19:32:26.214Z] 19:32:26     INFO -  GECKO | Thread[Picasso-refQueue,5,main]
[task 2018-09-28T19:32:26.215Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Native Method)
[task 2018-09-28T19:32:26.215Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Object.java:401)
[task 2018-09-28T19:32:26.216Z] 19:32:26     INFO -  GECKO | java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:102)
[task 2018-09-28T19:32:26.216Z] 19:32:26     INFO -  GECKO | java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:73)
[task 2018-09-28T19:32:26.217Z] 19:32:26     INFO -  GECKO | com.squareup.picasso.Picasso$CleanupThread.run(Picasso.java:352)
[task 2018-09-28T19:32:26.217Z] 19:32:26     INFO -  GECKO | Thread[GC,5,system]
[task 2018-09-28T19:32:26.218Z] 19:32:26     INFO -  GECKO | dalvik.system.NativeStart.run(Native Method)
[task 2018-09-28T19:32:26.218Z] 19:32:26     INFO -  GECKO | Thread[Instr: org.mozilla.gecko.FennecInstrumentationTestRunner,5,main]
[task 2018-09-28T19:32:26.219Z] 19:32:26     INFO -  GECKO | dalvik.system.VMStack.getThreadStackTrace(Native Method)
[task 2018-09-28T19:32:26.219Z] 19:32:26     INFO -  GECKO | java.lang.Thread.getStackTrace(Thread.java:579)
[task 2018-09-28T19:32:26.220Z] 19:32:26     INFO -  GECKO | java.lang.Thread.getAllStackTraces(Thread.java:521)
[task 2018-09-28T19:32:26.220Z] 19:32:26     INFO -  GECKO | org.mozilla.gecko.FennecNativeDriver.logAllStackTraces(FennecNativeDriver.java:415)
[task 2018-09-28T19:32:26.221Z] 19:32:26     INFO -  GECKO | org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForEvent(FennecNativeActions.java:112)
[task 2018-09-28T19:32:26.221Z] 19:32:26     INFO -  GECKO | org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForEvent(FennecNativeActions.java:97)
[task 2018-09-28T19:32:26.222Z] 19:32:26     INFO -  GECKO | org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForBundle(FennecNativeActions.java:171)
[task 2018-09-28T19:32:26.223Z] 19:32:26     INFO -  GECKO | org.mozilla.gecko.tests.helpers.JavascriptBridge.processPendingMessage(JavascriptBridge.java:186)
[task 2018-09-28T19:32:26.223Z] 19:32:26     INFO -  GECKO | org.mozilla.gecko.tests.helpers.JavascriptBridge.ensureJavaBridgeLoaded(JavascriptBridge.java:222)
[task 2018-09-28T19:32:26.224Z] 19:32:26     INFO -  GECKO | org.mozilla.gecko.tests.helpers.JavascriptBridge.sendMessage(JavascriptBridge.java:227)
[task 2018-09-28T19:32:26.224Z] 19:32:26     INFO -  GECKO | org.mozilla.gecko.tests.helpers.JavascriptBridge.syncCall(JavascriptBridge.java:138)
[task 2018-09-28T19:32:26.225Z] 19:32:26     INFO -  GECKO | org.mozilla.gecko.tests.testEventDispatcher.testThreadEvents(testEventDispatcher.java:141)
[task 2018-09-28T19:32:26.225Z] 19:32:26     INFO -  GECKO | org.mozilla.gecko.tests.testEventDispatcher.testScope(testEventDispatcher.java:84)
[task 2018-09-28T19:32:26.226Z] 19:32:26     INFO -  GECKO | org.mozilla.gecko.tests.testEventDispatcher.testEventDispatcher(testEventDispatcher.java:60)
[task 2018-09-28T19:32:26.227Z] 19:32:26     INFO -  GECKO | java.lang.reflect.Method.invokeNative(Native Method)
[task 2018-09-28T19:32:26.227Z] 19:32:26     INFO -  GECKO | java.lang.reflect.Method.invoke(Method.java:525)
[task 2018-09-28T19:32:26.228Z] 19:32:26     INFO -  GECKO | android.test.InstrumentationTestCase.runMethod(InstrumentationTestCase.java:214)
[task 2018-09-28T19:32:26.229Z] 19:32:26     INFO -  GECKO | android.test.InstrumentationTestCase.runTest(InstrumentationTestCase.java:199)
[task 2018-09-28T19:32:26.229Z] 19:32:26     INFO -  GECKO | android.test.ActivityInstrumentationTestCase2.runTest(ActivityInstrumentationTestCase2.java:192)
[task 2018-09-28T19:32:26.229Z] 19:32:26     INFO -  GECKO | org.mozilla.gecko.tests.BaseRobocopTest.runTest(BaseRobocopTest.java:188)
[task 2018-09-28T19:32:26.230Z] 19:32:26     INFO -  GECKO | junit.framework.TestCase.runBare(TestCase.java:134)
[task 2018-09-28T19:32:26.230Z] 19:32:26     INFO -  GECKO | junit.framework.TestResult$1.protect(TestResult.java:115)
[task 2018-09-28T19:32:26.230Z] 19:32:26     INFO -  GECKO | junit.framework.TestResult.runProtected(TestResult.java:133)
[task 2018-09-28T19:32:26.231Z] 19:32:26     INFO -  GECKO | junit.framework.TestResult.run(TestResult.java:118)
[task 2018-09-28T19:32:26.231Z] 19:32:26     INFO -  GECKO | junit.framework.TestCase.run(TestCase.java:124)
[task 2018-09-28T19:32:26.231Z] 19:32:26     INFO -  GECKO | android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:191)
[task 2018-09-28T19:32:26.232Z] 19:32:26     INFO -  GECKO | android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:176)
[task 2018-09-28T19:32:26.232Z] 19:32:26     INFO -  GECKO | android.test.InstrumentationTestRunner.onStart(InstrumentationTestRunner.java:554)
[task 2018-09-28T19:32:26.232Z] 19:32:26     INFO -  GECKO | org.mozilla.gecko.FennecInstrumentationTestRunner.onStart(FennecInstrumentationTestRunner.java:66)
[task 2018-09-28T19:32:26.233Z] 19:32:26     INFO -  GECKO | android.app.Instrumentation$InstrumentationThread.run(Instrumentation.java:1701)
[task 2018-09-28T19:32:26.233Z] 19:32:26     INFO -  GECKO | Thread[pool-1-thread-1,5,main]
[task 2018-09-28T19:32:26.233Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Native Method)
[task 2018-09-28T19:32:26.234Z] 19:32:26     INFO -  GECKO | java.lang.Thread.parkFor(Thread.java:1205)
[task 2018-09-28T19:32:26.234Z] 19:32:26     INFO -  GECKO | sun.misc.Unsafe.park(Unsafe.java:325)
[task 2018-09-28T19:32:26.235Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.LockSupport.park(LockSupport.java:159)
[task 2018-09-28T19:32:26.235Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019)
[task 2018-09-28T19:32:26.235Z] 19:32:26     INFO -  GECKO | java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413)
[task 2018-09-28T19:32:26.236Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013)
[task 2018-09-28T19:32:26.236Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073)
[task 2018-09-28T19:32:26.237Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573)
[task 2018-09-28T19:32:26.237Z] 19:32:26     INFO -  GECKO | java.lang.Thread.run(Thread.java:841)
[task 2018-09-28T19:32:26.237Z] 19:32:26     INFO -  GECKO | Thread[main,5,main]
[task 2018-09-28T19:32:26.238Z] 19:32:26     INFO -  GECKO | android.view.DisplayEventReceiver.nativeScheduleVsync(Native Method)
[task 2018-09-28T19:32:26.238Z] 19:32:26     INFO -  GECKO | android.view.DisplayEventReceiver.scheduleVsync(DisplayEventReceiver.java:132)
[task 2018-09-28T19:32:26.238Z] 19:32:26     INFO -  GECKO | android.view.Choreographer.scheduleVsyncLocked(Choreographer.java:596)
[task 2018-09-28T19:32:26.239Z] 19:32:26     INFO -  GECKO | android.view.Choreographer.scheduleFrameLocked(Choreographer.java:472)
[task 2018-09-28T19:32:26.239Z] 19:32:26     INFO -  GECKO | android.view.Choreographer.postCallbackDelayedInternal(Choreographer.java:316)
[task 2018-09-28T19:32:26.240Z] 19:32:26     INFO -  GECKO | android.view.Choreographer.postCallbackDelayed(Choreographer.java:299)
[task 2018-09-28T19:32:26.240Z] 19:32:26     INFO -  GECKO | android.view.Choreographer.postCallback(Choreographer.java:273)
[task 2018-09-28T19:32:26.240Z] 19:32:26     INFO -  GECKO | android.animation.ValueAnimator$AnimationHandler.scheduleAnimation(ValueAnimator.java:651)
[task 2018-09-28T19:32:26.241Z] 19:32:26     INFO -  GECKO | android.animation.ValueAnimator$AnimationHandler.doAnimationFrame(ValueAnimator.java:638)
[task 2018-09-28T19:32:26.241Z] 19:32:26     INFO -  GECKO | android.animation.ValueAnimator$AnimationHandler.run(ValueAnimator.java:646)
[task 2018-09-28T19:32:26.241Z] 19:32:26     INFO -  GECKO | android.view.Choreographer$CallbackRecord.run(Choreographer.java:749)
[task 2018-09-28T19:32:26.242Z] 19:32:26     INFO -  GECKO | android.view.Choreographer.doCallbacks(Choreographer.java:562)
[task 2018-09-28T19:32:26.242Z] 19:32:26     INFO -  GECKO | android.view.Choreographer.doFrame(Choreographer.java:531)
[task 2018-09-28T19:32:26.242Z] 19:32:26     INFO -  GECKO | android.view.Choreographer$FrameDisplayEventReceiver.run(Choreographer.java:735)
[task 2018-09-28T19:32:26.243Z] 19:32:26     INFO -  GECKO | android.os.Handler.handleCallback(Handler.java:730)
[task 2018-09-28T19:32:26.243Z] 19:32:26     INFO -  GECKO | android.os.Handler.dispatchMessage(Handler.java:92)
[task 2018-09-28T19:32:26.243Z] 19:32:26     INFO -  GECKO | android.os.Looper.loop(Looper.java:137)
[task 2018-09-28T19:32:26.244Z] 19:32:26     INFO -  GECKO | android.app.ActivityThread.main(ActivityThread.java:5103)
[task 2018-09-28T19:32:26.244Z] 19:32:26     INFO -  GECKO | java.lang.reflect.Method.invokeNative(Native Method)
[task 2018-09-28T19:32:26.244Z] 19:32:26     INFO -  GECKO | java.lang.reflect.Method.invoke(Method.java:525)
[task 2018-09-28T19:32:26.245Z] 19:32:26     INFO -  GECKO | com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:737)
[task 2018-09-28T19:32:26.245Z] 19:32:26     INFO -  GECKO | com.android.internal.os.ZygoteInit.main(ZygoteInit.java:553)
[task 2018-09-28T19:32:26.245Z] 19:32:26     INFO -  GECKO | dalvik.system.NativeStart.main(Native Method)
[task 2018-09-28T19:32:26.246Z] 19:32:26     INFO -  GECKO | Thread[Binder_2,5,main]
[task 2018-09-28T19:32:26.246Z] 19:32:26     INFO -  GECKO | dalvik.system.NativeStart.run(Native Method)
[task 2018-09-28T19:32:26.246Z] 19:32:26     INFO -  GECKO | Thread[Binder_1,5,main]
[task 2018-09-28T19:32:26.246Z] 19:32:26     INFO -  GECKO | dalvik.system.NativeStart.run(Native Method)
[task 2018-09-28T19:32:26.247Z] 19:32:26     INFO -  GECKO | Thread[ModernAsyncTask #5,5,main]
[task 2018-09-28T19:32:26.247Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Native Method)
[task 2018-09-28T19:32:26.247Z] 19:32:26     INFO -  GECKO | java.lang.Thread.parkFor(Thread.java:1205)
[task 2018-09-28T19:32:26.248Z] 19:32:26     INFO -  GECKO | sun.misc.Unsafe.park(Unsafe.java:325)
[task 2018-09-28T19:32:26.248Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.LockSupport.park(LockSupport.java:159)
[task 2018-09-28T19:32:26.249Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019)
[task 2018-09-28T19:32:26.249Z] 19:32:26     INFO -  GECKO | java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413)
[task 2018-09-28T19:32:26.250Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013)
[task 2018-09-28T19:32:26.250Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073)
[task 2018-09-28T19:32:26.250Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573)
[task 2018-09-28T19:32:26.251Z] 19:32:26     INFO -  GECKO | java.lang.Thread.run(Thread.java:841)
[task 2018-09-28T19:32:26.251Z] 19:32:26     INFO -  GECKO | Thread[AsyncTask #1,5,main]
[task 2018-09-28T19:32:26.251Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Native Method)
[task 2018-09-28T19:32:26.252Z] 19:32:26     INFO -  GECKO | java.lang.Thread.parkFor(Thread.java:1205)
[task 2018-09-28T19:32:26.252Z] 19:32:26     INFO -  GECKO | sun.misc.Unsafe.park(Unsafe.java:325)
[task 2018-09-28T19:32:26.253Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.LockSupport.park(LockSupport.java:159)
[task 2018-09-28T19:32:26.253Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019)
[task 2018-09-28T19:32:26.254Z] 19:32:26     INFO -  GECKO | java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413)
[task 2018-09-28T19:32:26.254Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013)
[task 2018-09-28T19:32:26.254Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073)
[task 2018-09-28T19:32:26.254Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573)
[task 2018-09-28T19:32:26.255Z] 19:32:26     INFO -  GECKO | java.lang.Thread.run(Thread.java:841)
[task 2018-09-28T19:32:26.255Z] 19:32:26     INFO -  GECKO | Thread[AsyncTask #4,5,main]
[task 2018-09-28T19:32:26.255Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Native Method)
[task 2018-09-28T19:32:26.256Z] 19:32:26     INFO -  GECKO | java.lang.Thread.parkFor(Thread.java:1205)
[task 2018-09-28T19:32:26.256Z] 19:32:26     INFO -  GECKO | sun.misc.Unsafe.park(Unsafe.java:325)
[task 2018-09-28T19:32:26.256Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.LockSupport.park(LockSupport.java:159)
[task 2018-09-28T19:32:26.257Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019)
[task 2018-09-28T19:32:26.257Z] 19:32:26     INFO -  GECKO | java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413)
[task 2018-09-28T19:32:26.258Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013)
[task 2018-09-28T19:32:26.258Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073)
[task 2018-09-28T19:32:26.259Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573)
[task 2018-09-28T19:32:26.259Z] 19:32:26     INFO -  GECKO | java.lang.Thread.run(Thread.java:841)
[task 2018-09-28T19:32:26.259Z] 19:32:26     INFO -  GECKO | Thread[Gecko,5,main]
[task 2018-09-28T19:32:26.260Z] 19:32:26     INFO -  GECKO | org.mozilla.gecko.mozglue.GeckoLoader.nativeRun(Native Method)
[task 2018-09-28T19:32:26.260Z] 19:32:26     INFO -  GECKO | org.mozilla.gecko.GeckoThread.run(GeckoThread.java:499)
[task 2018-09-28T19:32:26.261Z] 19:32:26     INFO -  GECKO | Thread[ModernAsyncTask #1,5,main]
[task 2018-09-28T19:32:26.261Z] 19:32:26     INFO -  GECKO | java.lang.Object.wait(Native Method)
[task 2018-09-28T19:32:26.261Z] 19:32:26     INFO -  GECKO | java.lang.Thread.parkFor(Thread.java:1205)
[task 2018-09-28T19:32:26.262Z] 19:32:26     INFO -  GECKO | sun.misc.Unsafe.park(Unsafe.java:325)
[task 2018-09-28T19:32:26.262Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.LockSupport.park(LockSupport.java:159)
[task 2018-09-28T19:32:26.262Z] 19:32:26     INFO -  GECKO | java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019)
[task 2018-09-28T19:32:26.263Z] 19:32:26     INFO -  GECKO | java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413)
[task 2018-09-28T19:32:26.263Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013)
[task 2018-09-28T19:32:26.263Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073)
[task 2018-09-28T19:32:26.264Z] 19:32:26     INFO -  GECKO | java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573)
[task 2018-09-28T19:32:26.264Z] 19:32:26     INFO -  GECKO | java.lang.Thread.run(Thread.java:841)
[task 2018-09-28T19:32:26.265Z] 19:32:26     INFO -  GECKO | Thread[Picasso-Stats,5,main]
[task 2018-09-28T19:32:26.265Z] 19:32:26     INFO -  GECKO | android.os.MessageQueue.nativePollOnce(Native Method)
[task 2018-09-28T19:32:26.265Z] 19:32:26     INFO -  GECKO | android.os.MessageQueue.next(MessageQueue.java:132)
[task 2018-09-28T19:32:26.266Z] 19:32:26     INFO -  GECKO | android.os.Looper.loop(Looper.java:124)
[task 2018-09-28T19:32:26.266Z] 19:32:26     INFO -  GECKO | android.os.HandlerThread.run(HandlerThread.java:61)
[task 2018-09-28T19:32:26.266Z] 19:32:26     INFO -  GECKO | Thread[Picasso-Dispatcher,5,main]
[task 2018-09-28T19:32:26.266Z] 19:32:26     INFO -  GECKO | android.os.MessageQueue.nativePollOnce(Native Method)
[task 2018-09-28T19:32:26.267Z] 19:32:26     INFO -  GECKO | android.os.MessageQueue.next(MessageQueue.java:132)
[task 2018-09-28T19:32:26.267Z] 19:32:26     INFO -  GECKO | android.os.Looper.loop(Looper.java:124)
[task 2018-09-28T19:32:26.267Z] 19:32:26     INFO -  GECKO | android.os.HandlerThread.run(HandlerThread.java:61)
[task 2018-09-28T19:32:26.268Z] 19:32:26     INFO -  Buffered messages logged at 19:28:33
[task 2018-09-28T19:32:26.272Z] 19:32:26     INFO -  TEST-PASS | testEventDispatcher | Robocop tests need the test device screen to be powered on. -
[task 2018-09-28T19:32:26.273Z] 19:32:26     INFO -  TEST-PASS | testEventDispatcher | url is not null - /robocop/robocop_javascript.html?slug=1538162913408&path=testEventDispatcher.js should not equal null
[task 2018-09-28T19:32:26.274Z] 19:32:26     INFO -  TEST-PASS | testEventDispatcher | url is not null - /robocop/robocop_javascript.html?slug=1538162913408&path=testEventDispatcher.js should not equal null
[task 2018-09-28T19:32:26.274Z] 19:32:26     INFO -  Buffered messages logged at 19:28:34
[task 2018-09-28T19:32:26.275Z] 19:32:26     INFO -  TEST-PASS | testEventDispatcher | The toolbar is not in the editing state -
[task 2018-09-28T19:32:26.276Z] 19:32:26     INFO -  Buffered messages logged at 19:28:38
[task 2018-09-28T19:32:26.276Z] 19:32:26     INFO -  TEST-PASS | testEventDispatcher | Waiting for Toolbar to enter editing mode. -
[task 2018-09-28T19:32:26.276Z] 19:32:26     INFO -  Buffered messages logged at 19:28:40
[task 2018-09-28T19:32:26.277Z] 19:32:26     INFO -  TEST-PASS | testEventDispatcher | Waiting for UrlEditText to be input method target. -
[task 2018-09-28T19:32:26.277Z] 19:32:26     INFO -  TEST-PASS | testEventDispatcher | url is not null - http://mochi.test:8888/tests/robocop/robocop_javascript.html?slug=1538162913408&path=testEventDispatcher.js should not equal null
[task 2018-09-28T19:32:26.277Z] 19:32:26     INFO -  Buffered messages logged at 19:28:42
[task 2018-09-28T19:32:26.278Z] 19:32:26     INFO -  TEST-PASS | testEventDispatcher | The toolbar is in the editing state -
[task 2018-09-28T19:32:26.278Z] 19:32:26     INFO -  Buffered messages logged at 19:28:43
[task 2018-09-28T19:32:26.278Z] 19:32:26     INFO -  TEST-PASS | testEventDispatcher | The UrlEditText is the input method target -
[task 2018-09-28T19:32:26.279Z] 19:32:26     INFO -  Buffered messages logged at 19:28:57
[task 2018-09-28T19:32:26.279Z] 19:32:26     INFO -  TEST-PASS | testEventDispatcher | The toolbar is in the editing state -
[task 2018-09-28T19:32:26.279Z] 19:32:26     INFO -  TEST-PASS | testEventDispatcher | initiatingAction is not null - org.mozilla.gecko.tests.components.ToolbarComponent$2@41f69b80 should not equal null
[task 2018-09-28T19:32:26.280Z] 19:32:26     INFO -  Buffered messages logged at 19:28:58
[task 2018-09-28T19:32:26.280Z] 19:32:26     INFO -  WaitHelper$ToolbarTitleTextChangeVerifier: stored title, "".
[task 2018-09-28T19:32:26.280Z] 19:32:26     INFO -  Buffered messages logged at 19:29:07
[task 2018-09-28T19:32:26.281Z] 19:32:26     INFO -  TEST-PASS | testEventDispatcher | Given message occurred for registered event: {type=null, tabID=0, title=Nightly Home} - Content:DOMTitleChanged should equal Content:DOMTitleChanged
[task 2018-09-28T19:32:26.281Z] 19:32:26     INFO -  TEST-PASS | testEventDispatcher | Given message occurred for registered event: {metadata=null, type=null, tabID=0, errorType=} - Content:DOMContentLoaded should equal Content:DOMContentLoaded
[task 2018-09-28T19:32:26.281Z] 19:32:26     INFO -  Buffered messages logged at 19:29:23
[task 2018-09-28T19:32:26.282Z] 19:32:26     INFO -  WaitHelper$ToolbarTitleTextChangeVerifier: timed out.
[task 2018-09-28T19:32:26.282Z] 19:32:26     INFO -  Buffered messages logged at 19:29:24
[task 2018-09-28T19:32:26.282Z] 19:32:26     INFO -  TEST-PASS | testEventDispatcher | Waiting for Toolbar to exit editing mode. -
[task 2018-09-28T19:32:26.283Z] 19:32:26     INFO -  Buffered messages finished
[task 2018-09-28T19:32:26.283Z] 19:32:26  WARNING -  TEST-UNEXPECTED-FAIL | testEventDispatcher | GeckoEventExpecter - blockForEvent timeout: Robocop:Java
[task 2018-09-28T19:32:36.447Z] 19:32:36     INFO -  0 ERROR Exception caught during test! - junit.framework.AssertionFailedError: TEST-UNEXPECTED-FAIL | testEventDispatcher | GeckoEventExpecter - blockForEvent timeout: Robocop:Java
[task 2018-09-28T19:32:36.448Z] 19:32:36     INFO -  	at junit.framework.Assert.fail(Assert.java:50)
[task 2018-09-28T19:32:36.449Z] 19:32:36     INFO -  	at org.mozilla.gecko.FennecMochitestAssert._logMochitestResult(FennecMochitestAssert.java:124)
[task 2018-09-28T19:32:36.449Z] 19:32:36     INFO -  	at org.mozilla.gecko.FennecMochitestAssert.ok(FennecMochitestAssert.java:145)
[task 2018-09-28T19:32:36.450Z] 19:32:36     INFO -  	at org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForEvent(FennecNativeActions.java:113)
[task 2018-09-28T19:32:36.450Z] 19:32:36     INFO -  	at org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForEvent(FennecNativeActions.java:97)
[task 2018-09-28T19:32:36.450Z] 19:32:36     INFO -  	at org.mozilla.gecko.FennecNativeActions$GeckoEventExpecter.blockForBundle(FennecNativeActions.java:171)
[task 2018-09-28T19:32:36.451Z] 19:32:36     INFO -  	at org.mozilla.gecko.tests.helpers.JavascriptBridge.processPendingMessage(JavascriptBridge.java:186)
[task 2018-09-28T19:32:36.451Z] 19:32:36     INFO -  	at org.mozilla.gecko.tests.helpers.JavascriptBridge.ensureJavaBridgeLoaded(JavascriptBridge.java:222)
[task 2018-09-28T19:32:36.451Z] 19:32:36     INFO -  	at org.mozilla.gecko.tests.helpers.JavascriptBridge.sendMessage(JavascriptBridge.java:227)
[task 2018-09-28T19:32:36.452Z] 19:32:36     INFO -  	at org.mozilla.gecko.tests.helpers.JavascriptBridge.syncCall(JavascriptBridge.java:138)
[task 2018-09-28T19:32:36.452Z] 19:32:36     INFO -  	at org.mozilla.gecko.tests.testEventDispatcher.testThreadEvents(testEventDispatcher.java:141)
[task 2018-09-28T19:32:36.452Z] 19:32:36     INFO -  	at org.mozilla.gecko.tests.testEventDispatcher.testScope(testEventDispatcher.java:84)
[task 2018-09-28T19:32:36.453Z] 19:32:36     INFO -  	at org.mozilla.gecko.tests.testEventDispatcher.testEventDispatcher(testEventDispatcher.java:60)
[task 2018-09-28T19:32:36.453Z] 19:32:36     INFO -  	at java.lang.reflect.Method.invokeNative(Native Method)
[task 2018-09-28T19:32:36.453Z] 19:32:36     INFO -  	at java.lang.reflect.Method.invoke(Method.java:525)
[task 2018-09-28T19:32:36.454Z] 19:32:36     INFO -  	at android.test.InstrumentationTestCase.runMethod(InstrumentationTestCase.java:214)
[task 2018-09-28T19:32:36.454Z] 19:32:36     INFO -  	at android.test.InstrumentationTestCase.runTest(InstrumentationTestCase.java:199)
[task 2018-09-28T19:32:36.454Z] 19:32:36     INFO -  	at android.test.ActivityInstrumentationTestCase2.runTest(ActivityInstrumentationTestCase2.java:192)
[task 2018-09-28T19:32:36.455Z] 19:32:36     INFO -  	at org.mozilla.gecko.tests.BaseRobocopTest.runTest(BaseRobocopTest.java:188)
[task 2018-09-28T19:32:36.455Z] 19:32:36     INFO -  	at junit.framework.TestCase.runBare(TestCase.java:134)
[task 2018-09-28T19:32:36.455Z] 19:32:36     INFO -  	at junit.framework.TestResult$1.protect(TestResult.java:115)
[task 2018-09-28T19:32:36.455Z] 19:32:36     INFO -  	at junit.framework.TestResult.runProtected(TestResult.java:133)
[task 2018-09-28T19:32:36.456Z] 19:32:36     INFO -  	at junit.framework.TestResult.run(TestResult.java:118)
[task 2018-09-28T19:32:36.456Z] 19:32:36     INFO -  	at junit.framework.TestCase.run(TestCase.java:124)
[task 2018-09-28T19:32:36.456Z] 19:32:36     INFO -  	at android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:191)
[task 2018-09-28T19:32:36.457Z] 19:32:36     INFO -  	at android.test.AndroidTestRunner.runTest(AndroidTestRunner.java:176)
[task 2018-09-28T19:32:36.457Z] 19:32:36     INFO -  	at android.test.InstrumentationTestRunner.onStart(InstrumentationTestRunner.java:554)
[task 2018-09-28T19:32:36.457Z] 19:32:36     INFO -  	at org.mozilla.gecko.FennecInstrumentationTestRunner.onStart(FennecInstrumentationTestRunner.java:66)
[task 2018-09-28T19:32:36.458Z] 19:32:36     INFO -  	at android.app.Instrumentation$InstrumentationThread.run(Instrumentation.java:1701)
[task 2018-09-28T19:32:36.458Z] 19:32:36  WARNING -  TEST-UNEXPECTED-FAIL | testEventDispatcher | Exception caught - junit.framework.AssertionFailedError: TEST-UNEXPECTED-FAIL | testEventDispatcher | GeckoEventExpecter - blockForEvent timeout: Robocop:Java
[task 2018-09-28T19:32:36.458Z] 19:32:36     INFO -  GECKO | EventExpecter: no longer listening for Robocop:Java
[task 2018-09-28T19:32:36.459Z] 19:32:36     INFO -  TEST-OK | testEventDispatcher | took 242966ms
[task 2018-09-28T19:32:36.459Z] 19:32:36     INFO -  TEST-START | Shutdown

Comment 1

[Intermittent Failures Robot]

1 failures in 2773 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* autoland: 1

Platform breakdown:
* android-em-4-3-armv7-api16: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1495113&startday=2018-09-24&endday=2018-09-30&tree=all

Comment 2

[Intermittent Failures Robot]

3 failures in 2708 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* autoland: 1
* mozilla-inbound: 1
* mozilla-central: 1

Platform breakdown:
* android-em-4-3-armv7-api16: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1495113&startday=2018-10-01&endday=2018-10-07&tree=all

Comment 3

[Intermittent Failures Robot]

2 failures in 2759 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* autoland: 2

Platform breakdown:
* android-em-4-3-armv7-api16: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1495113&startday=2018-10-15&endday=2018-10-21&tree=all

Comment 4

[Intermittent Failures Robot]

1 failures in 2978 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* mozilla-central: 1

Platform breakdown:
* android-em-4-3-armv7-api16-ccov: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1495113&startday=2018-10-29&endday=2018-11-04&tree=all

Comment 5

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Comment 6

[Stefan Hindli [:stefan_hindli]]

New failure: https://treeherder.mozilla.org/#/jobs?repo=mozilla-inbound&resultStatus=testfailed%2Cbusted%2Cexception&classifiedState=unclassified&selectedJob=245111258

Comment 7

[Intermittent Failures Robot]

1 failures in 4705 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-inbound: 1

Platform breakdown:

• android-em-4-3-armv7-api16: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1495113&startday=2019-05-06&endday=2019-05-12&tree=all

Comment 8

[Intermittent Failures Robot]

4 failures in 4658 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 3
• mozilla-central: 1

Platform breakdown:

• android-em-4-3-armv7-api16: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1495113&startday=2019-05-13&endday=2019-05-19&tree=all

Comment 9

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Comment 10

[Cristian Brindusan [:cbrindusan]]

New occurrence:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=256372551&repo=mozilla-central&lineNumber=1708

Comment 11

[Intermittent Failures Robot]

17 failures in 4710 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 17

Platform breakdown:

• android-em-4-3-armv7-api16: 15
• windows10-64-shippable: 1
• windows10-64-shippable-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1495113&startday=2019-07-08&endday=2019-07-14&tree=all

Comment 12

[Bogdan Tara[:bogdan_tara | bogdant]]

In the last 7 days, there have been 31 occurrences on android-em-4-3-armv7-api16, but no new occurrence after July the 18th.
Seems to be fixed by bug 1557096.

Comment 13

[Intermittent Failures Robot]

31 failures in 4298 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• mozilla-central: 30
• try: 1

Platform breakdown:

• android-em-4-3-armv7-api16: 31

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1495113&startday=2019-07-15&endday=2019-07-21&tree=all

Comment 14

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup