12:55:19 INFO - 65 INFO TEST-START | layout/forms/test/test_bug620936.html 13:01:04 INFO - org.mozilla.fennec still alive after SIGABRT: waiting... 13:01:09 INFO - org.mozilla.fennec still alive after SIGABRT: waiting... 13:01:14 INFO - org.mozilla.fennec still alive after SIGABRT: waiting... 13:01:19 WARNING - TEST-UNEXPECTED-FAIL | layout/forms/test/test_bug620936.html | application timed out after 330 seconds with no output 13:01:19 INFO - INFO | automation.py | Application ran for: 0:07:02.551551 13:01:19 INFO - INFO | zombiecheck | Reading PID log: /tmp/tmpC7pJ7bpidlog 13:01:19 INFO - Contents of /data/anr/traces.txt: 13:01:19 INFO - ----- pid 2260 at 2014-12-26 13:01:00 ----- 13:01:19 INFO - Cmd line: org.mozilla.fennec 13:01:19 INFO - DALVIK THREADS: 13:01:19 INFO - (mutexes: tll=0 tsl=0 tscl=0 ghl=0) 13:01:19 INFO - "main" prio=5 tid=1 NATIVE 13:01:19 INFO - | group="main" sCount=1 dsCount=0 obj=0x40b74460 self=0xfa830 13:01:19 INFO - | sysTid=2260 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=1074439332 13:01:19 INFO - | schedstat=( 0 0 0 ) utm=154 stm=33 core=0 13:01:19 INFO - at android.os.MessageQueue.nativePollOnce(Native Method) 13:01:19 INFO - at android.os.MessageQueue.next(MessageQueue.java:118) 13:01:19 INFO - at android.os.Looper.loop(Looper.java:118) 13:01:19 INFO - at android.app.ActivityThread.main(ActivityThread.java:4424) 13:01:19 INFO - at java.lang.reflect.Method.invokeNative(Native Method) 13:01:19 INFO - at java.lang.reflect.Method.invoke(Method.java:511) 13:01:19 INFO - at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:784) 13:01:19 INFO - at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:551) 13:01:19 INFO - at dalvik.system.NativeStart.main(Native Method) 13:01:19 INFO - "pool-1-thread-1" prio=5 tid=20 WAIT 13:01:19 INFO - | group="main" sCount=1 dsCount=0 obj=0x412d5ab8 self=0x5804b8 13:01:19 INFO - | sysTid=2337 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=4862760 13:01:19 INFO - | schedstat=( 0 0 0 ) utm=0 stm=3 core=1 13:01:19 INFO - at java.lang.Object.wait(Native Method) 13:01:19 INFO - - waiting on <0x4131ece0> (a java.lang.VMThread) held by tid=20 (pool-1-thread-1) 13:01:19 INFO - at java.lang.Thread.parkFor(Thread.java:1231) 13:01:19 INFO - at sun.misc.Unsafe.park(Unsafe.java:323) 13:01:19 INFO - at java.util.concurrent.locks.LockSupport.park(LockSupport.java:157) 13:01:19 INFO - at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2022) 13:01:19 INFO - at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413) 13:01:19 INFO - at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1009) 13:01:19 INFO - at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1069) 13:01:19 INFO - at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:569) 13:01:19 INFO - at java.lang.Thread.run(Thread.java:856) 13:01:19 INFO - "RefQueueWorker@org.apache.http.impl.conn.tsccm.ConnPoolByRoute@41336fd0" daemon prio=5 tid=19 WAIT 13:01:19 INFO - | group="main" sCount=1 dsCount=0 obj=0x412ff970 self=0x589528 13:01:19 INFO - | sysTid=2336 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=5809312 13:01:19 INFO - | schedstat=( 0 0 0 ) utm=0 stm=0 core=0 13:01:19 INFO - at java.lang.Object.wait(Native Method) 13:01:19 INFO - - waiting on <0x41346c10> (a java.lang.ref.ReferenceQueue) 13:01:19 INFO - at java.lang.Object.wait(Object.java:401) 13:01:19 INFO - at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:102) 13:01:19 INFO - at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:73) 13:01:19 INFO - at org.apache.http.impl.conn.tsccm.RefQueueWorker.run(RefQueueWorker.java:102) 13:01:19 INFO - at java.lang.Thread.run(Thread.java:856) 13:01:19 INFO - "Thread-99" prio=5 tid=17 NATIVE 13:01:19 INFO - | group="main" sCount=1 dsCount=0 obj=0x413aa608 self=0x494540 13:01:19 INFO - | sysTid=2310 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=4840096 13:01:19 INFO - | schedstat=( 0 0 0 ) utm=161 stm=57 core=0 13:01:19 INFO - at dalvik.system.NativeStart.run(Native Method) 13:01:19 INFO - "actionMode" prio=5 tid=16 WAIT 13:01:19 INFO - | group="main" sCount=1 dsCount=0 obj=0x413a02f0 self=0x40b260 13:01:19 INFO - | sysTid=2284 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=4202800 13:01:19 INFO - | schedstat=( 0 0 0 ) utm=0 stm=0 core=0 13:01:19 INFO - at java.lang.Object.wait(Native Method) 13:01:19 INFO - - waiting on <0x413a02f0> (a java.util.Timer$TimerImpl) 13:01:19 INFO - at java.lang.Object.wait(Object.java:364) 13:01:19 INFO - at java.util.Timer$TimerImpl.run(Timer.java:214) 13:01:19 INFO - "pool-3-thread-1" prio=5 tid=15 WAIT 13:01:19 INFO - | group="main" sCount=1 dsCount=0 obj=0x412e48e0 self=0x2c5a00 13:01:19 INFO - | sysTid=2281 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=2345168 13:01:19 INFO - | schedstat=( 0 0 0 ) utm=0 stm=0 core=0 13:01:19 INFO - at java.lang.Object.wait(Native Method) 13:01:19 INFO - - waiting on <0x412e4a28> (a java.lang.VMThread) held by tid=15 (pool-3-thread-1) 13:01:19 INFO - at java.lang.Thread.parkFor(Thread.java:1231) 13:01:19 INFO - at sun.misc.Unsafe.park(Unsafe.java:323) 13:01:19 INFO - at java.util.concurrent.locks.LockSupport.park(LockSupport.java:157) 13:01:19 INFO - at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2022) 13:01:19 INFO - at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413) 13:01:19 INFO - at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1009) 13:01:19 INFO - at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1069) 13:01:19 INFO - at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:569) 13:01:19 INFO - at java.lang.Thread.run(Thread.java:856) 13:01:19 INFO - "GeckoANRReporter" daemon prio=5 tid=14 NATIVE 13:01:19 INFO - | group="main" sCount=1 dsCount=0 obj=0x412755f8 self=0x2ba888 13:01:19 INFO - | sysTid=2279 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=2936632 13:01:19 INFO - | schedstat=( 0 0 0 ) utm=0 stm=0 core=0 13:01:19 INFO - at android.os.MessageQueue.nativePollOnce(Native Method) 13:01:19 INFO - at android.os.MessageQueue.next(MessageQueue.java:118) 13:01:19 INFO - at android.os.Looper.loop(Looper.java:118) 13:01:19 INFO - at org.mozilla.gecko.ANRReporter$1.run(ANRReporter.java:97) 13:01:19 INFO - at java.lang.Thread.run(Thread.java:856) 13:01:19 INFO - "Gecko" prio=5 tid=13 NATIVE 13:01:19 INFO - | group="main" sCount=1 dsCount=0 obj=0x4125acf0 self=0x2b2288 13:01:19 INFO - | sysTid=2278 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=2663456 13:01:19 INFO - | schedstat=( 0 0 0 ) utm=1989 stm=142 core=1 13:01:19 INFO - at org.mozilla.gecko.mozglue.GeckoLoader.nativeRun(Native Method) 13:01:19 INFO - at org.mozilla.gecko.GeckoAppShell.runGecko(GeckoAppShell.java:371) 13:01:19 INFO - at org.mozilla.gecko.GeckoThread.run(GeckoThread.java:184) 13:01:19 INFO - "pool-2-thread-1" prio=5 tid=12 WAIT 13:01:19 INFO - | group="main" sCount=1 dsCount=0 obj=0x4128ad10 self=0x2ce590 13:01:19 INFO - | sysTid=2277 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=2254440 13:01:19 INFO - | schedstat=( 0 0 0 ) utm=0 stm=1 core=1 13:01:19 INFO - at java.lang.Object.wait(Native Method) 13:01:19 INFO - - waiting on <0x4128ae58> (a java.lang.VMThread) held by tid=12 (pool-2-thread-1) 13:01:19 INFO - at java.lang.Thread.parkFor(Thread.java:1231) 13:01:19 INFO - at sun.misc.Unsafe.park(Unsafe.java:323) 13:01:19 INFO - at java.util.concurrent.locks.LockSupport.park(LockSupport.java:157) 13:01:19 INFO - at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2022) 13:01:19 INFO - at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413) 13:01:19 INFO - at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1009) 13:01:19 INFO - at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1069) 13:01:19 INFO - at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:569) 13:01:19 INFO - at java.lang.Thread.run(Thread.java:856) 13:01:19 INFO - "GeckoBackgroundThread" daemon prio=5 tid=11 NATIVE 13:01:19 INFO - | group="main" sCount=1 dsCount=0 obj=0x411d64c8 self=0x2973d0 13:01:19 INFO - | sysTid=2273 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=2641800 13:01:19 INFO - | schedstat=( 0 0 0 ) utm=48 stm=9 core=0 13:01:19 INFO - at android.os.MessageQueue.nativePollOnce(Native Method) 13:01:19 INFO - at android.os.MessageQueue.next(MessageQueue.java:118) 13:01:19 INFO - at android.os.Looper.loop(Looper.java:118) 13:01:19 INFO - at org.mozilla.gecko.util.GeckoBackgroundThread.run(GeckoBackgroundThread.java:43) 13:01:19 INFO - "Binder Thread #2" prio=5 tid=10 NATIVE 13:01:19 INFO - | group="main" sCount=1 dsCount=0 obj=0x411cf980 self=0x2c1868 13:01:19 INFO - | sysTid=2270 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=2528840 13:01:19 INFO - | schedstat=( 0 0 0 ) utm=0 stm=0 core=0 13:01:19 INFO - at dalvik.system.NativeStart.run(Native Method) 13:01:19 INFO - "Binder Thread #1" prio=5 tid=9 NATIVE 13:01:19 INFO - | group="main" sCount=1 dsCount=0 obj=0x411cf828 self=0x2c9838 13:01:19 INFO - | sysTid=2268 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=2533696 13:01:19 INFO - | schedstat=( 0 0 0 ) utm=0 stm=0 core=0 13:01:19 INFO - at dalvik.system.NativeStart.run(Native Method) 13:01:19 INFO - "FinalizerWatchdogDaemon" daemon prio=5 tid=8 TIMED_WAIT 13:01:19 INFO - | group="main" sCount=1 dsCount=0 obj=0x411cbed8 self=0x21a128 13:01:19 INFO - | sysTid=2267 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=2535832 13:01:19 INFO - | schedstat=( 0 0 0 ) utm=0 stm=0 core=1 13:01:19 INFO - at java.lang.VMThread.sleep(Native Method) 13:01:19 INFO - at java.lang.Thread.sleep(Thread.java:1031) 13:01:19 INFO - at java.lang.Thread.sleep(Thread.java:1013) 13:01:19 INFO - at java.lang.Daemons$FinalizerWatchdogDaemon.run(Daemons.java:213) 13:01:19 INFO - at java.lang.Thread.run(Thread.java:856) 13:01:19 INFO - "FinalizerDaemon" daemon prio=5 tid=7 WAIT 13:01:19 INFO - | group="main" sCount=1 dsCount=0 obj=0x411cbd80 self=0x2b9098 13:01:19 INFO - | sysTid=2266 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=2393064 13:01:19 INFO - | schedstat=( 0 0 0 ) utm=1 stm=0 core=0 13:01:19 INFO - at java.lang.Object.wait(Native Method) 13:01:19 INFO - - waiting on <0x40b6a5d0> (a java.lang.ref.ReferenceQueue) 13:01:19 INFO - at java.lang.Object.wait(Object.java:401) 13:01:19 INFO - at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:102) 13:01:19 INFO - at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:73) 13:01:19 INFO - at java.lang.Daemons$FinalizerDaemon.run(Daemons.java:168) 13:01:19 INFO - at java.lang.Thread.run(Thread.java:856) 13:01:19 INFO - "ReferenceQueueDaemon" daemon prio=5 tid=6 WAIT 13:01:19 INFO - | group="main" sCount=1 dsCount=0 obj=0x411cbc18 self=0x2d28b0 13:01:19 INFO - | sysTid=2265 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=2373728 13:01:19 INFO - | schedstat=( 0 0 0 ) utm=0 stm=0 core=0 13:01:19 INFO - at java.lang.Object.wait(Native Method) 13:01:19 INFO - - waiting on <0x40b6a4f8> 13:01:19 INFO - at java.lang.Object.wait(Object.java:364) 13:01:19 INFO - at java.lang.Daemons$ReferenceQueueDaemon.run(Daemons.java:128) 13:01:19 INFO - at java.lang.Thread.run(Thread.java:856) 13:01:19 INFO - "Compiler" daemon prio=5 tid=5 VMWAIT 13:01:19 INFO - | group="system" sCount=1 dsCount=0 obj=0x411cbb28 self=0x2becb8 13:01:19 INFO - | sysTid=2264 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=2312144 13:01:19 INFO - | schedstat=( 0 0 0 ) utm=32 stm=11 core=0 13:01:19 INFO - at dalvik.system.NativeStart.run(Native Method) 13:01:19 INFO - "JDWP" daemon prio=5 tid=4 VMWAIT 13:01:19 INFO - | group="system" sCount=1 dsCount=0 obj=0x411cba40 self=0x180260 13:01:19 INFO - | sysTid=2263 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=2394560 13:01:19 INFO - | schedstat=( 0 0 0 ) utm=0 stm=0 core=0 13:01:19 INFO - at dalvik.system.NativeStart.run(Native Method) 13:01:19 INFO - "Signal Catcher" daemon prio=5 tid=3 RUNNABLE 13:01:19 INFO - | group="system" sCount=0 dsCount=0 obj=0x411cb948 self=0x27cdc0 13:01:19 INFO - | sysTid=2262 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=2320120 13:01:19 INFO - | schedstat=( 0 0 0 ) utm=0 stm=0 core=0 13:01:19 INFO - at dalvik.system.NativeStart.run(Native Method) 13:01:19 INFO - "GC" daemon prio=5 tid=2 VMWAIT 13:01:19 INFO - | group="system" sCount=1 dsCount=0 obj=0x411cb868 self=0x29f490 13:01:19 INFO - | sysTid=2261 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=2374608 13:01:19 INFO - | schedstat=( 0 0 0 ) utm=35 stm=0 core=0 13:01:19 INFO - at dalvik.system.NativeStart.run(Native Method) 13:01:19 INFO - ----- end 2260 ----- 13:01:20 INFO - /data/tombstones does not exist; tombstone check skipped 13:01:21 INFO - mozcrash Downloading symbols from: https://ftp-ssl.mozilla.org/pub/mozilla.org/mobile/tinderbox-builds/mozilla-central-android-api-11/1419622893/fennec-37.0a1.en-US.android-arm.crashreporter-symbols.zip 13:01:22 INFO - mozcrash Saved minidump as /builds/panda-0178/test/build/blobber_upload_dir/30b12518-23fb-3a4d-1d400b14-3a30585e.dmp 13:01:22 INFO - mozcrash Saved app info as /builds/panda-0178/test/build/blobber_upload_dir/30b12518-23fb-3a4d-1d400b14-3a30585e.extra 13:01:22 WARNING - PROCESS-CRASH | layout/forms/test/test_bug620936.html | application crashed [None] 13:01:22 INFO - Crash dump filename: /tmp/tmpMlrj2r/30b12518-23fb-3a4d-1d400b14-3a30585e.dmp 13:01:22 INFO - stderr from minidump_stackwalk: 13:01:22 INFO - 2014-12-26 13:01:22: minidump_processor.cc:264: INFO: Processing minidump in file /tmp/tmpMlrj2r/30b12518-23fb-3a4d-1d400b14-3a30585e.dmp 13:01:22 INFO - 2014-12-26 13:01:22: minidump.cc:3815: INFO: Minidump opened minidump /tmp/tmpMlrj2r/30b12518-23fb-3a4d-1d400b14-3a30585e.dmp 13:01:22 INFO - 2014-12-26 13:01:22: minidump.cc:3847: ERROR: Minidump header signature mismatch: (0x0, 0x0) != 0x504d444d 13:01:22 INFO - 2014-12-26 13:01:22: minidump_processor.cc:268: ERROR: Minidump /tmp/tmpMlrj2r/30b12518-23fb-3a4d-1d400b14-3a30585e.dmp could not be read 13:01:22 INFO - 2014-12-26 13:01:22: minidump.cc:3787: INFO: Minidump closing minidump 13:01:22 INFO - 2014-12-26 13:01:22: minidump_stackwalk.cc:529: ERROR: MinidumpProcessor::Process failed 13:01:22 INFO - Stopping web server 13:01:22 INFO - Stopping web socket server