636665 - Android Java Freeze: ANR keyDispatchingTimedOut

Status: RESOLVED WONTFIX

(Firefox for Android Graveyard :: General, defect)

Description

[Stuart Parmenter]

DALVIK THREADS:
"main" prio=5 tid=1 WAIT
  | group="main" sCount=1 dsCount=0 s=N obj=0x40025ad8 self=0xcd58
  | sysTid=10109 nice=0 sched=0/0 cgrp=bg_non_interactive handle=-1345017808
  | schedstat=( 644195541 487762463 1721 )
  at java.lang.Object.wait(Native Method)
  - waiting on <0x40025b70> (a java.lang.VMThread)
  at java.lang.Thread.parkFor(Thread.java:1547)
  at java.lang.LangAccessImpl.parkFor(LangAccessImpl.java:48)
  at sun.misc.Unsafe.park(Unsafe.java:317)
  at java.util.concurrent.locks.LockSupport.park(LockSupport.java:131)
  at java.util.concurrent.locks.AbstractQueuedSynchronizer.parkAndCheckInterrupt(AbstractQueuedSynchronizer.java:790)
  at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireQueued(AbstractQueuedSynchronizer.java:823)
  at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquire(AbstractQueuedSynchronizer.java:1153)
  at java.util.concurrent.locks.ReentrantLock$NonfairSync.lock(ReentrantLock.java:185)
  at java.util.concurrent.locks.ReentrantLock.lock(ReentrantLock.java:261)
  at android.view.SurfaceView.updateWindow(SurfaceView.java:485)
  at android.view.SurfaceView.onWindowVisibilityChanged(SurfaceView.java:206)
  at android.view.View.dispatchWindowVisibilityChanged(View.java:3890)
  at android.view.ViewGroup.dispatchWindowVisibilityChanged(ViewGroup.java:727)
  at android.view.ViewGroup.dispatchWindowVisibilityChanged(ViewGroup.java:727)
  at android.view.ViewGroup.dispatchWindowVisibilityChanged(ViewGroup.java:727)
  at android.view.ViewRoot.performTraversals(ViewRoot.java:748)
  at android.view.ViewRoot.handleMessage(ViewRoot.java:1744)
  at android.os.Handler.dispatchMessage(Handler.java:99)
  at android.os.Looper.loop(Looper.java:144)
  at android.app.ActivityThread.main(ActivityThread.java:4937)
  at java.lang.reflect.Method.invokeNative(Native Method)
  at java.lang.reflect.Method.invoke(Method.java:521)
  at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:868)
  at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:626)
  at dalvik.system.NativeStart.main(Native Method)

"Binder Thread #4" prio=5 tid=11 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x46126260 self=0xa5070
  | sysTid=10279 nice=0 sched=0/0 cgrp=bg_non_interactive handle=2736800
  | schedstat=( 1403808 30639648 7 )
  at dalvik.system.NativeStart.run(Native Method)

"Binder Thread #3" prio=5 tid=9 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x46128e00 self=0x135818
  | sysTid=10272 nice=0 sched=0/0 cgrp=bg_non_interactive handle=2735920
  | schedstat=( 2471923 202209473 20 )
  at dalvik.system.NativeStart.run(Native Method)

"Thread-11" prio=5 tid=10 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x46017898 self=0x279d00
  | sysTid=10261 nice=0 sched=0/0 cgrp=bg_non_interactive handle=1346616
  | schedstat=( 4956115686 3262603758 8517 )
  at org.mozilla.gecko.GeckoAppShell.nativeRun(Native Method)
  at org.mozilla.gecko.GeckoAppShell.runGecko(GeckoAppShell.java:324)
  at org.mozilla.gecko.GeckoApp$2.run(GeckoApp.java:165)

"Timer-0" prio=5 tid=8 WAIT
  | group="main" sCount=1 dsCount=0 s=N obj=0x4602be70 self=0x277a58
  | sysTid=10259 nice=0 sched=0/0 cgrp=bg_non_interactive handle=1269416
  | schedstat=( 1098635 6195067 15 )
  at java.lang.Object.wait(Native Method)
  - waiting on <0x4602be70> (a java.util.Timer$TimerImpl)
  at java.lang.Object.wait(Object.java:288)
  at java.util.Timer$TimerImpl.run(Timer.java:219)

"Binder Thread #2" prio=5 tid=7 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x45f8a870 self=0x136138
  | sysTid=10115 nice=0 sched=0/0 cgrp=bg_non_interactive handle=1304136
  | schedstat=( 10131833 203155516 52 )
  at dalvik.system.NativeStart.run(Native Method)

"Binder Thread #1" prio=5 tid=6 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x45f84cf8 self=0x137310
  | sysTid=10114 nice=0 sched=0/0 cgrp=bg_non_interactive handle=1356816
  | schedstat=( 7812500 275451662 49 )
  at dalvik.system.NativeStart.run(Native Method)

"Compiler" daemon prio=5 tid=5 VMWAIT
  | group="system" sCount=1 dsCount=0 s=N obj=0x45f83358 self=0x14b220
  | sysTid=10113 nice=0 sched=0/0 cgrp=bg_non_interactive handle=1256904
  | schedstat=( 114349375 154022206 525 )
  at dalvik.system.NativeStart.run(Native Method)

"JDWP" daemon prio=5 tid=4 VMWAIT
  | group="system" sCount=1 dsCount=0 s=N obj=0x45f832a8 self=0x148d10
  | sysTid=10112 nice=0 sched=0/0 cgrp=bg_non_interactive handle=1365456
  | schedstat=( 13275156 401397697 180 )
  at dalvik.system.NativeStart.run(Native Method)

"Signal Catcher" daemon prio=5 tid=3 RUNNABLE
  | group="system" sCount=0 dsCount=0 s=N obj=0x45f831e8 self=0x13f728
  | sysTid=10111 nice=0 sched=0/0 cgrp=bg_non_interactive handle=1267608
  | schedstat=( 2319336 457765 8 )
  at dalvik.system.NativeStart.run(Native Method)

"HeapWorker" daemon prio=5 tid=2 VMWAIT
  | group="system" sCount=1 dsCount=0 s=N obj=0x44920278 self=0x13e468
  | sysTid=10110 nice=0 sched=0/0 cgrp=bg_non_interactive handle=1294240
  | schedstat=( 59387206 33905029 65 )
  at dalvik.system.NativeStart.run(Native Method)

Comment 1

[Doug Turner (:dougt)]

ANR = Application Not Responding.

basically our app stopped responding and android killed it.  From the stack, it isn't clear what happened.

Comment 2

[Stuart Parmenter]

We are seeing a very high number of these happening -- need to dig in to and fix.

Comment 3

[Doug Turner (:dougt)]

str needed.

Comment 4

[Mark Finkle (:mfinkle) (use needinfo?)]

Happening in v5.0 too. Same stack. I was hoping we fixed with one of the crash fixes:

v5.0 Jun 25, 2011 1:22:05 PM
--------------------------------------------------------------------------------

DALVIK THREADS:
"main" prio=5 tid=1 WAIT
  | group="main" sCount=1 dsCount=0 s=N obj=0x2aac88b8 self=0xcd58
  | sysTid=2720 nice=0 sched=0/0 cgrp=unknown handle=1876207704
  at java.lang.Object.wait(Native Method)
  - waiting on <0x2aac8948> (a java.lang.VMThread)
  at java.lang.Thread.parkFor(Thread.java:1535)
  at java.lang.LangAccessImpl.parkFor(LangAccessImpl.java:48)
  at sun.misc.Unsafe.park(Unsafe.java:317)
  at java.util.concurrent.locks.LockSupport.park(LockSupport.java:131)
  at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:422)
  at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:323)
  at java.util.concurrent.SynchronousQueue.take(SynchronousQueue.java:857)
  at org.mozilla.gecko.GeckoSurfaceView.surfaceChanged(GeckoSurfaceView.java:187)
  at android.view.SurfaceView.updateWindow(SurfaceView.java:538)
  at android.view.SurfaceView.dispatchDraw(SurfaceView.java:339)
  at android.view.ViewGroup.drawChild(ViewGroup.java:1638)
  at android.view.ViewGroup.dispatchDraw(ViewGroup.java:1367)
  at android.view.ViewGroup.drawChild(ViewGroup.java:1638)
  at android.view.ViewGroup.dispatchDraw(ViewGroup.java:1367)
  at android.view.View.draw(View.java:6755)
  at android.widget.FrameLayout.draw(FrameLayout.java:352)
  at android.view.ViewGroup.drawChild(ViewGroup.java:1640)
  at android.view.ViewGroup.dispatchDraw(ViewGroup.java:1367)
  at android.view.View.draw(View.java:6755)
  at android.widget.FrameLayout.draw(FrameLayout.java:352)
  at com.android.internal.policy.impl.PhoneWindow$DecorView.draw(PhoneWindow.java:2243)
  at android.view.ViewRoot.draw(ViewRoot.java:1407)
  at android.view.ViewRoot.performTraversals(ViewRoot.java:1163)
  at android.view.ViewRoot.handleMessage(ViewRoot.java:1727)
  at android.os.Handler.dispatchMessage(Handler.java:99)
  at android.os.Looper.loop(Looper.java:123)
  at android.app.ActivityThread.main(ActivityThread.java:4627)
  at java.lang.reflect.Method.invokeNative(Native Method)
  at java.lang.reflect.Method.invoke(Method.java:521)
  at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:878)
  at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:636)
  at dalvik.system.NativeStart.main(Native Method)

"Binder Thread #3" prio=5 tid=11 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x30860888 self=0x2086f0
  | sysTid=2788 nice=0 sched=0/0 cgrp=unknown handle=2130760
  at dalvik.system.NativeStart.run(Native Method)

"java.lang.ProcessManager" daemon prio=5 tid=9 WAIT
  | group="main" sCount=1 dsCount=0 s=N obj=0x3087a960 self=0x224e30
  | sysTid=2733 nice=0 sched=0/0 cgrp=unknown handle=1237376
  at java.lang.Object.wait(Native Method)
  - waiting on <0x3087a7d8> (a java.util.HashMap)
  at java.lang.Object.wait(Object.java:288)
  at java.lang.ProcessManager.onExit(ProcessManager.java:141)
  at java.lang.ProcessManager.watchChildren(Native Method)
  at java.lang.ProcessManager$1.run(ProcessManager.java:87)

"Thread-11" prio=5 tid=10 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x30749228 self=0x20f0f8
  | sysTid=2730 nice=0 sched=0/0 cgrp=unknown handle=1200336
  at org.mozilla.gecko.GeckoAppShell.nativeRun(Native Method)
  at org.mozilla.gecko.GeckoAppShell.runGecko(GeckoAppShell.java:386)
  at org.mozilla.gecko.GeckoApp$2.run(GeckoApp.java:167)

"Timer-0" prio=5 tid=8 WAIT
  | group="main" sCount=1 dsCount=0 s=N obj=0x30785c98 self=0x20bc30
  | sysTid=2728 nice=0 sched=0/0 cgrp=unknown handle=1285440
  at java.lang.Object.wait(Native Method)
  - waiting on <0x30785c98> (a java.util.Timer$TimerImpl)
  at java.lang.Object.wait(Object.java:288)
  at java.util.Timer$TimerImpl.run(Timer.java:219)

"Binder Thread #2" prio=5 tid=7 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x3068cd38 self=0x148ca0
  | sysTid=2726 nice=0 sched=0/0 cgrp=unknown handle=1203976
  at dalvik.system.NativeStart.run(Native Method)

"Binder Thread #1" prio=5 tid=6 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x30689100 self=0x125888
  | sysTid=2725 nice=0 sched=0/0 cgrp=unknown handle=1207704
  at dalvik.system.NativeStart.run(Native Method)

"Compiler" daemon prio=5 tid=5 VMWAIT
  | group="system" sCount=1 dsCount=0 s=N obj=0x30686348 self=0x125d18
  | sysTid=2724 nice=0 sched=0/0 cgrp=unknown handle=1208536
  at dalvik.system.NativeStart.run(Native Method)

"JDWP" daemon prio=5 tid=4 VMWAIT
  | group="system" sCount=1 dsCount=0 s=N obj=0x306862a0 self=0x126ef0
  | sysTid=2723 nice=0 sched=0/0 cgrp=unknown handle=1280736
  at dalvik.system.NativeStart.run(Native Method)

"Signal Catcher" daemon prio=5 tid=3 RUNNABLE
  | group="system" sCount=0 dsCount=0 s=N obj=0x306861e8 self=0x12a520
  | sysTid=2722 nice=0 sched=0/0 cgrp=unknown handle=1290216
  at dalvik.system.NativeStart.run(Native Method)

"HeapWorker" daemon prio=5 tid=2 VMWAIT
  | group="system" sCount=1 dsCount=0 s=N obj=0x2e6ed148 self=0x1388f0
  | sysTid=2721 nice=0 sched=0/0 cgrp=unknown handle=1289920
  at dalvik.system.NativeStart.run(Native Method)

Comment 5

[Mark Finkle (:mfinkle) (use needinfo?)]

Just to give an idea of the frequency of occurrences:

ANR keyDispatchingTimedOut in App  1,824 reports (102 reports/week)

Comment 6

[BMO Automation]

Closing all opened bug in a graveyard component