What do these stack traces mean?

Monkey Targets Forums/Android/What do these stack traces mean?

matty

(Posted 2011) [#1]

Hi all,

My app has had a couple of errors which were reported to my Android Developer account. Only problem is I don't know what they mean. Is anyone able to shed some light on how to read these stack traces, and/or what they mean? I'm a bit in the dark when it comes to this. It looks to me like it is something to do with a sound??

Activity com.soniq.lockdownnecrosis1/.MonkeyGame
--------------------------------------------------------------------------------

Cause ANR keyDispatchingTimedOut
--------------------------------------------------------------------------------


v1.03Oct 2, 2011 4:58:56 PMOTHER
--------------------------------------------------------------------------------

DALVIK THREADS:
"main" prio=5 tid=1 MONITOR
  | group="main" sCount=1 dsCount=0 s=N obj=0x40020990 self=0xcde8
  | sysTid=22222 nice=0 sched=0/0 cgrp=default handle=-1345017744
  | schedstat=( 3338256832 1937805137 9227 )
  at com.soniq.lockdownnecrosis1.gxtkApp.InvokeOnUpdate(MonkeyGame.java:~469)
  - waiting to lock <0x45846118> (a com.soniq.lockdownnecrosis1.bb_app_AppDevice) held by threadid=9 (GLThread 10)
  at com.soniq.lockdownnecrosis1.gxtkTimer.run(MonkeyGame.java:398)
  at android.os.Handler.handleCallback(Handler.java:587)
  at android.os.Handler.dispatchMessage(Handler.java:92)
  at android.os.Looper.loop(Looper.java:143)
  at android.app.ActivityThread.main(ActivityThread.java:4701)
  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)

"SoundPool" prio=5 tid=11 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x4582b970 self=0x121db0
  | sysTid=22230 nice=0 sched=0/0 cgrp=default handle=1247840
  | schedstat=( 122070 11596680 1 )
  at dalvik.system.NativeStart.run(Native Method)

"SoundPoolThread" prio=5 tid=10 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x4582b8e8 self=0x125fa8
  | sysTid=22231 nice=0 sched=0/0 cgrp=default handle=1247568
  | schedstat=( 14160157 15411382 78 )
  at dalvik.system.NativeStart.run(Native Method)

"GLThread 10" prio=5 tid=9 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x45833910 self=0x12c2e0
  | sysTid=22232 nice=0 sched=0/0 cgrp=default handle=1252120
  | schedstat=( 34851165580 10859039524 29477 )
  at com.google.android.gles_jni.GLImpl.glDrawArrays(Native Method)
  at com.soniq.lockdownnecrosis1.gxtkGraphics.Flush(MonkeyGame.java:728)
  at com.soniq.lockdownnecrosis1.gxtkGraphics.SetMatrix(MonkeyGame.java:806)
  at com.soniq.lockdownnecrosis1.bb_graphics.bb_ValidateMatrix(MonkeyGame.java:6368)
  at com.soniq.lockdownnecrosis1.bb_graphics.bb_DrawImage(MonkeyGame.java:6384)
  at com.soniq.lockdownnecrosis1.bb__MyApp.bbm_OnRender(MonkeyGame.java:3954)
  at com.soniq.lockdownnecrosis1.bb_app_AppDevice.OnRender(MonkeyGame.java:4598)
  at com.soniq.lockdownnecrosis1.gxtkApp.InvokeOnRender(MonkeyGame.java:527)
  at com.soniq.lockdownnecrosis1.gxtkApp.onDrawFrame(MonkeyGame.java:455)
  at android.opengl.GLSurfaceView$GLThread.guardedRun(GLSurfaceView.java:1332)
  at android.opengl.GLSurfaceView$GLThread.run(GLSurfaceView.java:1116)

"android.hardware.SensorManager$SensorThread" prio=5 tid=8 NATIVE
  | group="main" sCount=1 dsCount=0 s=Y obj=0x45827810 self=0x1317f8
  | sysTid=22229 nice=-8 sched=0/0 cgrp=default handle=1261848
  | schedstat=( 351928660 330932594 1664 )
  at android.hardware.SensorManager.sensors_data_poll(Native Method)
  at android.hardware.SensorManager$SensorThread$SensorThreadRunnable.run(SensorManager.java:399)
  at java.lang.Thread.run(Thread.java:1096)

"Binder Thread #2" prio=5 tid=7 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x4582f090 self=0x11dca8
  | sysTid=22228 nice=0 sched=0/0 cgrp=default handle=1193864
  | schedstat=( 52673339 74615485 207 )
  at dalvik.system.NativeStart.run(Native Method)

"Binder Thread #1" prio=5 tid=6 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x4582d7a8 self=0x120668
  | sysTid=22227 nice=0 sched=0/0 cgrp=default handle=1161848
  | schedstat=( 53771992 74615466 210 )
  at dalvik.system.NativeStart.run(Native Method)

"Compiler" daemon prio=5 tid=5 VMWAIT
  | group="system" sCount=1 dsCount=0 s=N obj=0x45827348 self=0x11b7a0
  | sysTid=22226 nice=0 sched=0/0 cgrp=default handle=1257544
  | schedstat=( 809143059 843780527 2336 )
  at dalvik.system.NativeStart.run(Native Method)

"JDWP" daemon prio=5 tid=4 VMWAIT
  | group="system" sCount=1 dsCount=0 s=N obj=0x458272a0 self=0x132e28
  | sysTid=22225 nice=0 sched=0/0 cgrp=default handle=1256936
  | schedstat=( 4272464 10375975 55 )
  at dalvik.system.NativeStart.run(Native Method)

"Signal Catcher" daemon prio=5 tid=3 RUNNABLE
  | group="system" sCount=0 dsCount=0 s=N obj=0x458271e8 self=0x125d70
  | sysTid=22224 nice=0 sched=0/0 cgrp=default handle=1203504
  | schedstat=( 19317625 10131835 43 )
  at dalvik.system.NativeStart.run(Native Method)

"HeapWorker" daemon prio=5 tid=2 VMWAIT
  | group="system" sCount=1 dsCount=0 s=N obj=0x43b32880 self=0x125bb8
  | sysTid=22223 nice=0 sched=0/0 cgrp=default handle=1248040
  | schedstat=( 275909419 162200930 267 )
  at dalvik.system.NativeStart.run(Native Method)

v1.03Oct 2, 2011 4:57:02 PMOTHER
--------------------------------------------------------------------------------

DALVIK THREADS:
"main" prio=5 tid=1 MONITOR
  | group="main" sCount=1 dsCount=0 s=N obj=0x40020990 self=0xcde8
  | sysTid=22004 nice=0 sched=0/0 cgrp=default handle=-1345017744
  | schedstat=( 1125244163 1596191383 3666 )
  at com.soniq.lockdownnecrosis1.gxtkApp.InvokeOnUpdate(MonkeyGame.java:~469)
  - waiting to lock <0x4592c118> (a com.soniq.lockdownnecrosis1.bb_app_AppDevice) held by threadid=10 (GLThread 10)
  at com.soniq.lockdownnecrosis1.gxtkTimer.run(MonkeyGame.java:398)
  at android.os.Handler.handleCallback(Handler.java:587)
  at android.os.Handler.dispatchMessage(Handler.java:92)
  at android.os.Looper.loop(Looper.java:143)
  at android.app.ActivityThread.main(ActivityThread.java:4701)
  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)

"SoundPool" prio=5 tid=11 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x45919c88 self=0x126ec8
  | sysTid=22012 nice=0 sched=0/0 cgrp=default handle=1214224
  | schedstat=( 457764 8239746 2 )
  at dalvik.system.NativeStart.run(Native Method)

"GLThread 10" prio=5 tid=10 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x45919910 self=0x130920
  | sysTid=22014 nice=0 sched=0/0 cgrp=default handle=1180456
  | schedstat=( 12948974622 8007598861 14595 )
  at com.google.android.gles_jni.GLImpl.glDrawArrays(Native Method)
  at com.soniq.lockdownnecrosis1.gxtkGraphics.Flush(MonkeyGame.java:728)
  at com.soniq.lockdownnecrosis1.gxtkGraphics.SetMatrix(MonkeyGame.java:806)
  at com.soniq.lockdownnecrosis1.bb_graphics.bb_ValidateMatrix(MonkeyGame.java:6368)
  at com.soniq.lockdownnecrosis1.bb_graphics.bb_DrawImage2(MonkeyGame.java:6412)
  at com.soniq.lockdownnecrosis1.bb__Unit.bbm_Draw(MonkeyGame.java:5833)
  at com.soniq.lockdownnecrosis1.bb__MyApp.bbm_OnRender(MonkeyGame.java:4370)
  at com.soniq.lockdownnecrosis1.bb_app_AppDevice.OnRender(MonkeyGame.java:4598)
  at com.soniq.lockdownnecrosis1.gxtkApp.InvokeOnRender(MonkeyGame.java:527)
  at com.soniq.lockdownnecrosis1.gxtkApp.onDrawFrame(MonkeyGame.java:455)
  at android.opengl.GLSurfaceView$GLThread.guardedRun(GLSurfaceView.java:1332)
  at android.opengl.GLSurfaceView$GLThread.run(GLSurfaceView.java:1116)

"SoundPoolThread" prio=5 tid=9 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x45919a58 self=0x1317f8
  | sysTid=22013 nice=0 sched=0/0 cgrp=default handle=1213952
  | schedstat=( 13519284 8056645 83 )
  at dalvik.system.NativeStart.run(Native Method)

"android.hardware.SensorManager$SensorThread" prio=5 tid=8 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x4590d810 self=0x1201e8
  | sysTid=22011 nice=-8 sched=0/0 cgrp=default handle=1194184
  | schedstat=( 455627442 420928945 1762 )
  at android.hardware.SensorManager.sensors_data_poll(Native Method)
  at android.hardware.SensorManager$SensorThread$SensorThreadRunnable.run(SensorManager.java:399)
  at java.lang.Thread.run(Thread.java:1096)

"Binder Thread #2" prio=5 tid=7 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x45914468 self=0x123418
  | sysTid=22010 nice=0 sched=0/0 cgrp=default handle=1257600
  | schedstat=( 16052247 22918702 40 )
  at dalvik.system.NativeStart.run(Native Method)

"Binder Thread #1" prio=5 tid=6 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x4590f488 self=0x132d00
  | sysTid=22009 nice=0 sched=0/0 cgrp=default handle=1203816
  | schedstat=( 20385738 47851565 48 )
  at dalvik.system.NativeStart.run(Native Method)

"Compiler" daemon prio=5 tid=5 VMWAIT
  | group="system" sCount=1 dsCount=0 s=N obj=0x4590d348 self=0x125bb8
  | sysTid=22008 nice=0 sched=0/0 cgrp=default handle=1248016
  | schedstat=( 663909906 546112069 1920 )
  at dalvik.system.NativeStart.run(Native Method)

"JDWP" daemon prio=5 tid=4 VMWAIT
  | group="system" sCount=1 dsCount=0 s=N obj=0x4590d2a0 self=0x123c30
  | sysTid=22007 nice=0 sched=0/0 cgrp=default handle=1194992
  | schedstat=( 4180910 16448973 45 )
  at dalvik.system.NativeStart.run(Native Method)

"Signal Catcher" daemon prio=5 tid=3 RUNNABLE
  | group="system" sCount=0 dsCount=0 s=N obj=0x4590d1e8 self=0x11ba08
  | sysTid=22006 nice=0 sched=0/0 cgrp=default handle=1161672
  | schedstat=( 16662599 20751954 39 )
  at dalvik.system.NativeStart.run(Native Method)

"HeapWorker" daemon prio=5 tid=2 VMWAIT
  | group="system" sCount=1 dsCount=0 s=N obj=0x43b32860 self=0x11b7a0
  | sysTid=22005 nice=0 sched=0/0 cgrp=default handle=1251872
  | schedstat=( 240447993 214569087 234 )
  at dalvik.system.NativeStart.run(Native Method)

muddy_shoes

(Posted 2011) [#2]

The meat is here:

at com.soniq.lockdownnecrosis1.gxtkApp.InvokeOnUpdate(MonkeyGame.java:~469)
  - waiting to lock <0x45846118> (a com.soniq.lockdownnecrosis1.bb_app_AppDevice) held by threadid=9 (GLThread 10)

Looks like there's an issue with contention between the main thread and the rendering thread.

matty

(Posted 2011) [#3]

Thanks muddy_shoes, unfortunately though I still don't really know what that means "contention between main thread and rendering thread"?

Still game works on most people's phones....

muddy_shoes

(Posted 2011) [#4]

I'm not very knowledgeable about multi-threading but I assume that the GL thread is holding onto a lock on the AppDevice instance and causing a deadlock in the app as a whole. There's nothing in the trace that tells you explicitly what is causing the problem beyond the fact that the Main thread is waiting for the lock and the GL thread is the drawArray call as part of the overall Flush operation.

Presumably, the traces are from the same person just a few minutes apart, so it seems to be repeatable. Did they say if there were certain actions that caused the problem or is it just happening as soon as they load or start a level? It could be a bug in the GL driver on particular phones, I guess. Then again it could be a mojo issue that is only apparent in certain situations.

Sorry, but I can't be of much more help. Perhaps someone else more familiar with these sorts of problems can chime in, but all I can suggest is trying to gather more data about the issue.

matty

(Posted 2011) [#5]

Thanks muddy_shoes - I'm not able to contact the user who this happened with - I'm not given that information by google. Over 600 users installed the app in the last two days and I've only had the one error report so I'm guessing it is not too widespread...hopefully. The error report is automatically generated.

Thanks for your help though.

Matt