Closed Bug 1111669 Opened 10 years ago Closed 9 years ago

Intermittent test_bug1104064.html | application timed out after 330 seconds with no output

Categories

(Core :: DOM: Workers, defect)

Product:
Core
Component:
DOM: Workers
Platform:
ARM
Android
Type:
defect
Priority:
Not set
Severity:
normal

Tracking

()

Status:
RESOLVED WORKSFORME

People

(Reporter: RyanVM, Unassigned)

References

Details

(Keywords: intermittent-failure) 

Hooray corrupted stack :(

19:32:40 INFO - 145 INFO TEST-START | /tests/dom/workers/test/test_bug1104064.html
19:32:40 INFO - 146 INFO TEST-OK | /tests/dom/workers/test/test_bug1104064.html | took 519ms
19:38:25 INFO - org.mozilla.fennec still alive after SIGABRT: waiting...
19:38:30 INFO - org.mozilla.fennec still alive after SIGABRT: waiting...
19:38:35 INFO - org.mozilla.fennec still alive after SIGABRT: waiting...
19:38:41 WARNING - TEST-UNEXPECTED-FAIL | /tests/dom/workers/test/test_bug1104064.html | application timed out after 330 seconds with no output
19:38:41 INFO - INFO | automation.py | Application ran for: 0:07:02.740100
19:38:41 INFO - INFO | zombiecheck | Reading PID log: /tmp/tmpadyFXapidlog
19:38:41 INFO - Contents of /data/anr/traces.txt:
19:38:41 INFO - ----- pid 2257 at 2014-12-14 19:38:21 -----
19:38:41 INFO - Cmd line: org.mozilla.fennec
19:38:41 INFO - DALVIK THREADS:
19:38:41 INFO - (mutexes: tll=0 tsl=0 tscl=0 ghl=0)
19:38:41 INFO - "main" prio=5 tid=1 NATIVE
19:38:41 INFO - | group="main" sCount=1 dsCount=0 obj=0x40b68460 self=0x15fa830
19:38:41 INFO - | sysTid=2257 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=1074304164
19:38:41 INFO - | schedstat=( 0 0 0 ) utm=160 stm=23 core=1
19:38:41 INFO - at android.os.MessageQueue.nativePollOnce(Native Method)
19:38:41 INFO - at android.os.MessageQueue.next(MessageQueue.java:118)
19:38:41 INFO - at android.os.Looper.loop(Looper.java:118)
19:38:41 INFO - at android.app.ActivityThread.main(ActivityThread.java:4424)
19:38:41 INFO - at java.lang.reflect.Method.invokeNative(Native Method)
19:38:41 INFO - at java.lang.reflect.Method.invoke(Method.java:511)
19:38:41 INFO - at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:784)
19:38:41 INFO - at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:551)
19:38:41 INFO - at dalvik.system.NativeStart.main(Native Method)
19:38:41 INFO - "pool-2-thread-1" prio=5 tid=20 WAIT
19:38:41 INFO - | group="main" sCount=1 dsCount=0 obj=0x41326920 self=0x19f88a0
19:38:41 INFO - | sysTid=2338 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=27151936
19:38:41 INFO - | schedstat=( 0 0 0 ) utm=3 stm=0 core=1
19:38:41 INFO - at java.lang.Object.wait(Native Method)
19:38:41 INFO - - waiting on <0x4132b058> (a java.lang.VMThread) held by tid=20 (pool-2-thread-1)
19:38:41 INFO - at java.lang.Thread.parkFor(Thread.java:1231)
19:38:41 INFO - at sun.misc.Unsafe.park(Unsafe.java:323)
19:38:41 INFO - at java.util.concurrent.locks.LockSupport.park(LockSupport.java:157)
19:38:41 INFO - at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2022)
19:38:41 INFO - at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413)
19:38:41 INFO - at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1009)
19:38:41 INFO - at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1069)
19:38:41 INFO - at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:569)
19:38:41 INFO - at java.lang.Thread.run(Thread.java:856)
19:38:41 INFO - "RefQueueWorker@org.apache.http.impl.conn.tsccm.ConnPoolByRoute@413c4068" daemon prio=5 tid=19 WAIT
19:38:41 INFO - | group="main" sCount=1 dsCount=0 obj=0x41370b78 self=0x1a8e4b0
19:38:41 INFO - | sysTid=2337 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=27151872
19:38:41 INFO - | schedstat=( 0 0 0 ) utm=0 stm=0 core=0
19:38:41 INFO - at java.lang.Object.wait(Native Method)
19:38:41 INFO - - waiting on <0x413757a0> (a java.lang.ref.ReferenceQueue)
19:38:41 INFO - at java.lang.Object.wait(Object.java:401)
19:38:41 INFO - at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:102)
19:38:41 INFO - at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:73)
19:38:41 INFO - at org.apache.http.impl.conn.tsccm.RefQueueWorker.run(RefQueueWorker.java:102)
19:38:41 INFO - at java.lang.Thread.run(Thread.java:856)
19:38:41 INFO - "Thread-99" prio=5 tid=17 NATIVE
19:38:41 INFO - | group="main" sCount=1 dsCount=0 obj=0x41389d78 self=0x19a3378
19:38:41 INFO - | sysTid=2307 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=26808632
19:38:41 INFO - | schedstat=( 0 0 0 ) utm=264 stm=76 core=1
19:38:41 INFO - at dalvik.system.NativeStart.run(Native Method)
19:38:41 INFO - "actionMode" prio=5 tid=16 WAIT
19:38:41 INFO - | group="main" sCount=1 dsCount=0 obj=0x4136d920 self=0x191cdf0
19:38:41 INFO - | sysTid=2282 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=26324880
19:38:41 INFO - | schedstat=( 0 0 0 ) utm=0 stm=0 core=0
19:38:41 INFO - at java.lang.Object.wait(Native Method)
19:38:41 INFO - - waiting on <0x4136d920> (a java.util.Timer$TimerImpl)
19:38:41 INFO - at java.lang.Object.wait(Object.java:364)
19:38:41 INFO - at java.util.Timer$TimerImpl.run(Timer.java:214)
19:38:41 INFO - "pool-3-thread-1" prio=5 tid=15 WAIT
19:38:41 INFO - | group="main" sCount=1 dsCount=0 obj=0x412dadb8 self=0x17c5a08
19:38:41 INFO - | sysTid=2278 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=24853080
19:38:41 INFO - | schedstat=( 0 0 0 ) utm=0 stm=0 core=1
19:38:41 INFO - at java.lang.Object.wait(Native Method)
19:38:41 INFO - - waiting on <0x412daf00> (a java.lang.VMThread) held by tid=15 (pool-3-thread-1)
19:38:41 INFO - at java.lang.Thread.parkFor(Thread.java:1231)
19:38:41 INFO - at sun.misc.Unsafe.park(Unsafe.java:323)
19:38:41 INFO - at java.util.concurrent.locks.LockSupport.park(LockSupport.java:157)
19:38:41 INFO - at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2022)
19:38:41 INFO - at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413)
19:38:41 INFO - at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1009)
19:38:41 INFO - at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1069)
19:38:41 INFO - at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:569)
19:38:41 INFO - at java.lang.Thread.run(Thread.java:856)
19:38:41 INFO - "GeckoANRReporter" daemon prio=5 tid=14 NATIVE
19:38:41 INFO - | group="main" sCount=1 dsCount=0 obj=0x41257978 self=0x17ba890
19:38:41 INFO - | sysTid=2276 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=24976816
19:38:41 INFO - | schedstat=( 0 0 0 ) utm=0 stm=0 core=0
19:38:41 INFO - at android.os.MessageQueue.nativePollOnce(Native Method)
19:38:41 INFO - at android.os.MessageQueue.next(MessageQueue.java:118)
19:38:41 INFO - at android.os.Looper.loop(Looper.java:118)
19:38:41 INFO - at org.mozilla.gecko.ANRReporter$1.run(ANRReporter.java:96)
19:38:41 INFO - at java.lang.Thread.run(Thread.java:856)
19:38:41 INFO - "Gecko" prio=5 tid=13 NATIVE
19:38:41 INFO - | group="main" sCount=1 dsCount=0 obj=0x4127d658 self=0x17973d8
19:38:41 INFO - | sysTid=2275 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=24976616
19:38:41 INFO - | schedstat=( 0 0 0 ) utm=2702 stm=204 core=1
19:38:41 INFO - at org.mozilla.gecko.mozglue.GeckoLoader.nativeRun(Native Method)
19:38:41 INFO - at org.mozilla.gecko.GeckoAppShell.runGecko(GeckoAppShell.java:371)
19:38:41 INFO - at org.mozilla.gecko.GeckoThread.run(GeckoThread.java:184)
19:38:41 INFO - "pool-1-thread-1" prio=5 tid=12 WAIT
19:38:41 INFO - | group="main" sCount=1 dsCount=0 obj=0x411ca730 self=0x17b2290
19:38:41 INFO - | sysTid=2274 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=24979144
19:38:41 INFO - | schedstat=( 0 0 0 ) utm=0 stm=1 core=1
19:38:41 INFO - at java.lang.Object.wait(Native Method)
19:38:41 INFO - - waiting on <0x411bf648> (a java.lang.VMThread) held by tid=12 (pool-1-thread-1)
19:38:41 INFO - at java.lang.Thread.parkFor(Thread.java:1231)
19:38:41 INFO - at sun.misc.Unsafe.park(Unsafe.java:323)
19:38:41 INFO - at java.util.concurrent.locks.LockSupport.park(LockSupport.java:157)
19:38:41 INFO - at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2022)
19:38:41 INFO - at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413)
19:38:41 INFO - at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1009)
19:38:41 INFO - at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1069)
19:38:41 INFO - at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:569)
19:38:41 INFO - at java.lang.Thread.run(Thread.java:856)
19:38:41 INFO - "GeckoBackgroundThread" daemon prio=5 tid=11 NATIVE
19:38:41 INFO - | group="main" sCount=1 dsCount=0 obj=0x41228568 self=0x17ce598
19:38:41 INFO - | sysTid=2270 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=24750288
19:38:41 INFO - | schedstat=( 0 0 0 ) utm=58 stm=17 core=1
19:38:41 INFO - at android.os.MessageQueue.nativePollOnce(Native Method)
19:38:41 INFO - at android.os.MessageQueue.next(MessageQueue.java:118)
19:38:41 INFO - at android.os.Looper.loop(Looper.java:118)
19:38:41 INFO - at org.mozilla.gecko.util.GeckoBackgroundThread.run(GeckoBackgroundThread.java:43)
19:38:41 INFO - "Binder Thread #2" prio=5 tid=10 NATIVE
19:38:41 INFO - | group="main" sCount=1 dsCount=0 obj=0x411c8f78 self=0x17c1870
19:38:41 INFO - | sysTid=2269 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=24548944
19:38:41 INFO - | schedstat=( 0 0 0 ) utm=0 stm=0 core=0
19:38:41 INFO - at dalvik.system.NativeStart.run(Native Method)
19:38:41 INFO - "Binder Thread #1" prio=5 tid=9 NATIVE
19:38:41 INFO - | group="main" sCount=1 dsCount=0 obj=0x411c37c0 self=0x17c9840
19:38:41 INFO - | sysTid=2268 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=24553800
19:38:41 INFO - | schedstat=( 0 0 0 ) utm=0 stm=0 core=1
19:38:41 INFO - at dalvik.system.NativeStart.run(Native Method)
19:38:41 INFO - "FinalizerWatchdogDaemon" daemon prio=5 tid=8 TIMED_WAIT
19:38:41 INFO - | group="main" sCount=1 dsCount=0 obj=0x411bfe70 self=0x171a130
19:38:41 INFO - | sysTid=2267 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=24555936
19:38:41 INFO - | schedstat=( 0 0 0 ) utm=0 stm=0 core=1
19:38:41 INFO - at java.lang.VMThread.sleep(Native Method)
19:38:41 INFO - at java.lang.Thread.sleep(Thread.java:1031)
19:38:41 INFO - at java.lang.Thread.sleep(Thread.java:1013)
19:38:41 INFO - at java.lang.Daemons$FinalizerWatchdogDaemon.run(Daemons.java:213)
19:38:41 INFO - at java.lang.Thread.run(Thread.java:856)
19:38:41 INFO - "FinalizerDaemon" daemon prio=5 tid=7 WAIT
19:38:41 INFO - | group="main" sCount=1 dsCount=0 obj=0x411bfd18 self=0x17b90a0
19:38:41 INFO - | sysTid=2266 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=24413168
19:38:41 INFO - | schedstat=( 0 0 0 ) utm=0 stm=1 core=1
19:38:41 INFO - at java.lang.Object.wait(Native Method)
19:38:41 INFO - - waiting on <0x40b5e5d0> (a java.lang.ref.ReferenceQueue)
19:38:41 INFO - at java.lang.Object.wait(Object.java:401)
19:38:41 INFO - at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:102)
19:38:41 INFO - at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:73)
19:38:41 INFO - at java.lang.Daemons$FinalizerDaemon.run(Daemons.java:168)
19:38:41 INFO - at java.lang.Thread.run(Thread.java:856)
19:38:41 INFO - "ReferenceQueueDaemon" daemon prio=5 tid=6 WAIT
19:38:41 INFO - | group="main" sCount=1 dsCount=0 obj=0x411bfbb0 self=0x17d28b8
19:38:41 INFO - | sysTid=2265 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=24393832
19:38:41 INFO - | schedstat=( 0 0 0 ) utm=0 stm=0 core=0
19:38:41 INFO - at java.lang.Object.wait(Native Method)
19:38:41 INFO - - waiting on <0x40b5e4f8>
19:38:41 INFO - at java.lang.Object.wait(Object.java:364)
19:38:41 INFO - at java.lang.Daemons$ReferenceQueueDaemon.run(Daemons.java:128)
19:38:41 INFO - at java.lang.Thread.run(Thread.java:856)
19:38:41 INFO - "Compiler" daemon prio=5 tid=5 VMWAIT
19:38:41 INFO - | group="system" sCount=1 dsCount=0 obj=0x411bfac0 self=0x17becc0
19:38:41 INFO - | sysTid=2264 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=24332248
19:38:41 INFO - | schedstat=( 0 0 0 ) utm=28 stm=14 core=1
19:38:41 INFO - at dalvik.system.NativeStart.run(Native Method)
19:38:41 INFO - "JDWP" daemon prio=5 tid=4 VMWAIT
19:38:41 INFO - | group="system" sCount=1 dsCount=0 obj=0x411bf9d8 self=0x1680260
19:38:41 INFO - | sysTid=2263 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=24414664
19:38:41 INFO - | schedstat=( 0 0 0 ) utm=0 stm=0 core=0
19:38:41 INFO - at dalvik.system.NativeStart.run(Native Method)
19:38:41 INFO - "Signal Catcher" daemon prio=5 tid=3 RUNNABLE
19:38:41 INFO - | group="system" sCount=0 dsCount=0 obj=0x411bf8e0 self=0x177cdc8
19:38:41 INFO - | sysTid=2261 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=24340224
19:38:41 INFO - | schedstat=( 0 0 0 ) utm=0 stm=0 core=0
19:38:41 INFO - at dalvik.system.NativeStart.run(Native Method)
19:38:41 INFO - "GC" daemon prio=5 tid=2 VMWAIT
19:38:41 INFO - | group="system" sCount=1 dsCount=0 obj=0x411bf800 self=0x179f498
19:38:41 INFO - | sysTid=2259 nice=0 sched=0/0 cgrp=[fopen-error:2] handle=24394712
19:38:41 INFO - | schedstat=( 0 0 0 ) utm=41 stm=2 core=1
19:38:41 INFO - at dalvik.system.NativeStart.run(Native Method)
19:38:41 INFO - ----- end 2257 -----
19:38:41 INFO - /data/tombstones does not exist; tombstone check skipped
19:38:42 INFO - mozcrash Downloading symbols from: https://ftp-ssl.mozilla.org/pub/mozilla.org/mobile/tinderbox-builds/mozilla-inbound-android-api-11/1418610155/fennec-37.0a1.en-US.android-arm.crashreporter-symbols.zip
19:38:44 INFO - mozcrash Saved minidump as /builds/panda-0291/test/build/blobber_upload_dir/181bce6a-5b1b-e623-6b05ec69-20c93459.dmp
19:38:44 INFO - mozcrash Saved app info as /builds/panda-0291/test/build/blobber_upload_dir/181bce6a-5b1b-e623-6b05ec69-20c93459.extra
19:38:44 WARNING - PROCESS-CRASH | /tests/dom/workers/test/test_bug1104064.html | application crashed [None]
19:38:44 INFO - Crash dump filename: /tmp/tmpfbgJ0x/181bce6a-5b1b-e623-6b05ec69-20c93459.dmp
19:38:44 INFO - stderr from minidump_stackwalk:
19:38:44 INFO - 2014-12-14 19:38:44: minidump_processor.cc:264: INFO: Processing minidump in file /tmp/tmpfbgJ0x/181bce6a-5b1b-e623-6b05ec69-20c93459.dmp
19:38:44 INFO - 2014-12-14 19:38:44: minidump.cc:3815: INFO: Minidump opened minidump /tmp/tmpfbgJ0x/181bce6a-5b1b-e623-6b05ec69-20c93459.dmp
19:38:44 INFO - 2014-12-14 19:38:44: minidump.cc:3847: ERROR: Minidump header signature mismatch: (0x0, 0x0) != 0x504d444d
19:38:44 INFO - 2014-12-14 19:38:44: minidump_processor.cc:268: ERROR: Minidump /tmp/tmpfbgJ0x/181bce6a-5b1b-e623-6b05ec69-20c93459.dmp could not be read
19:38:44 INFO - 2014-12-14 19:38:44: minidump.cc:3787: INFO: Minidump closing minidump
19:38:44 INFO - 2014-12-14 19:38:44: minidump_stackwalk.cc:529: ERROR: MinidumpProcessor::Process failed
Inactive; closing (see bug 1180138).
Status: NEW → RESOLVED
Closed: 9 years ago
Resolution: --- → WORKSFORME
You need to log in before you can comment on or make changes to this bug.