ANR errors in dev console

[Kevin]

I noticed a couple somewhat recent ANR errors but I can't really tell where exactly it hung at:

#1:

Activity com.cognitial.directvremotepro/.main
Cause ANR keyDispatchingTimedOut

DALVIK THREADS:
(mutexes: tll=0 tsl=0 tscl=0 ghl=0 hwl=0 hwll=0)
"main" prio=5 tid=1 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x402716c8 self=0x12710
| sysTid=130 nice=0 sched=0/0 cgrp=default handle=-1344727960
| schedstat=( 649559000 385274000 2015 ) utm=47 stm=17 core=1
at com.android.server.SystemServer.init1(Native Method)
at com.android.server.SystemServer.main(SystemServer.java:699)
at java.lang.reflect.Method.invokeNative(Native Method)
at java.lang.reflect.Method.invoke(Method.java:491)
at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:841)
at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:599)
at dalvik.system.NativeStart.main(Native Method)

"Thread-98" prio=5 tid=27 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x413c9638 self=0x459508
| sysTid=1254 nice=0 sched=0/0 cgrp=default handle=4561928
| schedstat=( 371000 178000 6 ) utm=0 stm=0 core=1
at android.net.wifi.WifiNative.unloadDriver(Native Method)
at android.net.wifi.WifiStateMachine$DriverUnloadingState$1.run(WifiStateMachine.java:1913)
at java.lang.Thread.run(Thread.java:1020)

"SoundPoolThread" prio=5 tid=69 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x41c47a30 self=0x47edc0
| sysTid=407 nice=0 sched=0/0 cgrp=default handle=2866624
| schedstat=( 1959000 12965000 19 ) utm=0 stm=0 core=1
at dalvik.system.NativeStart.run(Native Method)

"SoundPool" prio=5 tid=68 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x41c458e0 self=0x47de78
| sysTid=406 nice=0 sched=0/0 cgrp=default handle=4676200
| schedstat=( 11248000 15248000 221 ) utm=1 stm=0 core=0
at dalvik.system.NativeStart.run(Native Method)

"00:23:6C:9C:17:03" prio=5 tid=65 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x40b54a78 self=0x1f9ff0
| sysTid=359 nice=0 sched=0/0 cgrp=default handle=2072848
| schedstat=( 1352000 5541000 12 ) utm=0 stm=0 core=1
at android.os.MessageQueue.nativePollOnce(Native Method)
at android.os.MessageQueue.next(MessageQueue.java:119)
at android.os.Looper.loop(Looper.java:122)
at android.os.HandlerThread.run(HandlerThread.java:60)

"Binder Thread #9" prio=5 tid=62 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x40ac58d0 self=0x360df0
| sysTid=321 nice=0 sched=0/0 cgrp=default handle=3520920
| schedstat=( 454478000 379734000 1882 ) utm=33 stm=12 core=1
at dalvik.system.NativeStart.run(Native Method)

"Binder Thread #8" prio=5 tid=61 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x40baf458 self=0x357fd8
| sysTid=319 nice=0 sched=0/0 cgrp=default handle=2875680
| schedstat=( 587595000 463033000 2098 ) utm=46 stm=12 core=0
at dalvik.system.NativeStart.run(Native Method)

"pool-1-thread-1" prio=5 tid=60 WAIT
| group="main" sCount=1 dsCount=0 obj=0x40babe28 self=0x33d158
| sysTid=306 nice=0 sched=0/0 cgrp=default handle=3396216
| schedstat=( 9171000 39328000 41 ) utm=0 stm=0 core=0
at java.lang.Object.wait(Native Method)
- waiting on <0x40babfe8> (a java.lang.VMThread) tid=60
at java.lang.Thread.parkFor(Thread.java:1425)
at java.lang.LangAccessImpl.parkFor(LangAccessImpl.java:48)
at sun.misc.Unsafe.park(Unsafe.java:329)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:157)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2022)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1014)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1074)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:574)
at java.lang.Thread.run(Thread.java:1020)

"libgps" prio=5 tid=59 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x40a48e40 self=0x31e308
| sysTid=303 nice=0 sched=0/0 cgrp=default handle=3278976
| schedstat=( 607000 66624000 11 ) utm=0 stm=0 core=1
at dalvik.system.NativeStart.run(Native Method)

"Binder Thread #7" prio=5 tid=58 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x40abf588 self=0x31d4e8
| sysTid=302 nice=0 sched=0/0 cgrp=default handle=3256592
| schedstat=( 497206000 430397000 1964 ) utm=35 stm=14 core=1
at dalvik.system.NativeStart.run(Native Method)

"Binder Thread #6" prio=5 tid=57 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x40b65318 self=0x2fec38
| sysTid=299 nice=0 sched=0/0 cgrp=default handle=3140600
| schedstat=( 690037000 525422000 2173 ) utm=49 stm=20 core=1
at dalvik.system.NativeStart.run(Native Method)

"Binder Thread #5" prio=5 tid=56 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x40a5cd38 self=0x2b7d58
| sysTid=288 nice=0 sched=0/0 cgrp=default handle=2847320
| schedstat=( 470759000 393285000 1960 ) utm=37 stm=10 core=0
at dalvik.system.NativeStart.run(Native Method)

"android.hardware.SensorManager$SensorThread" prio=5 tid=55 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x40a6cf98 self=0x291768
| sysTid=287 nice=-8 sched=0/0 cgrp=default handle=2693256
| schedstat=( 13203000 10796000 105 ) utm=0 stm=1 core=1
at android.hardware.SensorManager.sensors_data_poll(Native Method)
at android.hardware.SensorManager$SensorThread$SensorThreadRunnable.run(SensorManager.java:498)
at java.lang.Thread.run(Thread.java:1020)

"Binder Thread #4" prio=5 tid=54 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x40a5cbd8 self=0x2b65f0
| sysTid=283 nice=0 sched=0/0 cgrp=default handle=2294208
| schedstat=( 590548000 648525000 2080 ) utm=44 stm=15 core=0
at dalvik.system.NativeStart.run(Native Method)

"RefQueueWorker@org.apache.http.impl.conn.tsccm.ConnPoolByRoute@40a64300" daemon prio=5 tid=53 WAIT
| group="main" sCount=1 dsCount=0 obj=0x40ad8058 self=0x292d50
| sysTid=260 nice=0 sched=0/0 cgrp=default handle=2054912
| schedstat=( 134000 19153000 4 ) utm=0 stm=0 core=1
at java.lang.Object.wait(Native Method)
- waiting on <0x40a2eea8> (a java.lang.ref.ReferenceQueue)
at java.lang.Object.wait(Object.java:398)
at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:107)
at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:80)
at org.apache.http.impl.conn.tsccm.RefQueueWorker.run(RefQueueWorker.java:102)
at java.lang.Thread.run(Thread.java:1020)

"GpsLocationProvider" prio=5 tid=52 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x408ab2a8 self=0x1f1008
| sysTid=243 nice=10 sched=0/0 cgrp=bg_non_interactive handle=2036984
| schedstat=( 5259000 171037000 41 ) utm=0 stm=0 core=1
at android.os.MessageQueue.nativePollOnce(Native Method)
at android.os.MessageQueue.next(MessageQueue.java:119)
at android.os.Looper.loop(Looper.java:122)
at com.android.server.location.GpsLocationProvider$GpsLocationProviderThread.run(GpsLocationProvider.java:1558)

"Binder Thread #3" prio=5 tid=51 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x40b0f168 self=0x29ee58
| sysTid=244 nice=0 sched=0/0 cgrp=default handle=2038184
| schedstat=( 481855000 416543000 1969 ) utm=35 stm=13 core=0
at dalvik.system.NativeStart.run(Native Method)

"NetworkTimeUpdateService" prio=5 tid=50 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x40b49ff0 self=0x2a9338
| sysTid=207 nice=0 sched=0/0 cgrp=default handle=2747328
| schedstat=( 3661000 4550000 26 ) utm=0 stm=0 core=1
at android.os.MessageQueue.nativePollOnce(Native Method)
at android.os.MessageQueue.next(MessageQueue.java:119)
at android.os.Looper.loop(Looper.java:122)
at android.os.HandlerThread.run(HandlerThread.java:60)

"ThrottleService" prio=5 tid=49 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x40b569f0 self=0x29f130
| sysTid=206 nice=0 sched=0/0 cgrp=default handle=2560232
| schedstat=( 32335000 37366000 94 ) utm=2 stm=1 core=1
at android.os.MessageQueue.nativePollOnce(Native Method)
at android.os.MessageQueue.next(MessageQueue.java:119)
at android.os.Looper.loop(Looper.java:122)
at android.os.HandlerThread.run(HandlerThread.java:60)

"CountryDetectorService" prio=5 tid=48 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x40b20e00 self=0x262c40
| sysTid=199 nice=10 sched=0/0 cgrp=bg_non_interactive handle=2697208
| schedstat=( 939000 51663000 16 ) utm=0 stm=0 core=0
at android.os.MessageQueue.nativePollOnce(Native Method)
at android.os.MessageQueue.next(MessageQueue.java:119)
at android.os.Looper.loop(Looper.java:122)
at com.android.server.CountryDetectorService.run(CountryDetectorService.java:188)
at java.lang.Thread.run(Thread.java:1020)

"LocationManagerService" prio=5 tid=47 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x40a56410 self=0x291b98
| sysTid=198 nice=10 sched=0/0 cgrp=bg_non_interactive handle=2694328
| schedstat=( 16984000 1700454000 114 ) utm=1 stm=0 core=0
at android.os.MessageQueue.nativePollOnce(Native Method)
at android.os.MessageQueue.next(MessageQueue.java:119)
at android.os.Looper.loop(Looper.java:122)
at com.android.server.LocationManagerService.run(LocationManagerService.java:559)
at java.lang.Thread.run(Thread.java:1020)

"watchdog" prio=5 tid=45 TIMED_WAIT
| group="main" sCount=1 dsCount=0 obj=0x4089cea8 self=0x205fc0
| sysTid=185 nice=0 sched=0/0 cgrp=default handle=2121952
| schedstat=( 11747000 13381000 56 ) utm=0 stm=1 core=1
at java.lang.Object.wait(Native Method)
- waiting on <0x4089cea8> (a com.android.server.Watchdog)
at java.lang.Object.wait(Object.java:398)
at com.android.server.Watchdog.run(Watchdog.java:400)

"UsbService host thread" prio=5 tid=46 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x40b455a8 self=0x1bb4e0
| sysTid=183 nice=0 sched=0/0 cgrp=default handle=1816304
| schedstat=( 6772000 15346000 21 ) utm=0 stm=0 core=0
at com.android.server.usb.UsbService.monitorUsbHostBus(Native Method)
at com.android.server.usb.UsbService.access$900(UsbService.java:61)
at com.android.server.usb.UsbService$3.run(UsbService.java:415)
at java.lang.Thread.run(Thread.java:1020)

"LuxFilterService" prio=5 tid=44 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x40b11720 self=0x201328
| sysTid=180 nice=0 sched=0/0 cgrp=default handle=1114544
| schedstat=( 1650229000 443277000 7236 ) utm=117 stm=48 core=1
at android.os.MessageQueue.nativePollOn...

#2:

Activity
Cause ANR Executing service com.cognitial.directvremotepro/.gdservice

DALVIK THREADS:
(mutexes: tll=0 tsl=0 tscl=0 ghl=0 hwl=0 hwll=0)
"main" prio=5 tid=1 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x40161600 self=0x12620
| sysTid=18620 nice=0 sched=0/0 cgrp=default handle=-1343912856
| schedstat=( 6922605000 1014385000 4540 ) utm=553 stm=139 core=1
at android.os.MessageQueue.nativePollOnce(Native Method)
at android.os.MessageQueue.next(MessageQueue.java:119)
at android.os.Looper.loop(Looper.java:122)
at android.app.ActivityThread.main(ActivityThread.java:4123)
at java.lang.reflect.Method.invokeNative(Native Method)
at java.lang.reflect.Method.invoke(Method.java:491)
at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:841)
at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:599)
at dalvik.system.NativeStart.main(Native Method)

"RefQueueWorker@org.apache.http.impl.conn.tsccm.ConnPoolByRoute@40845af8" daemon prio=5 tid=11 WAIT
| group="main" sCount=1 dsCount=0 obj=0x40819d20 self=0x23cc10
| sysTid=18773 nice=0 sched=0/0 cgrp=default handle=2001328
| schedstat=( 82000 0 2 ) utm=0 stm=0 core=0
at java.lang.Object.wait(Native Method)
- waiting on <0x40898f10> (a java.lang.ref.ReferenceQueue)
at java.lang.Object.wait(Object.java:398)
at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:107)
at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:80)
at org.apache.http.impl.conn.tsccm.RefQueueWorker.run(RefQueueWorker.java:102)
at java.lang.Thread.run(Thread.java:1020)

"Binder Thread #3" prio=5 tid=10 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x408282d0 self=0x1e5b50
| sysTid=18771 nice=0 sched=0/0 cgrp=default handle=1998640
| schedstat=( 530000 734000 7 ) utm=0 stm=0 core=0
at dalvik.system.NativeStart.run(Native Method)

"pool-2-thread-2" daemon prio=5 tid=9 TIMED_WAIT
| group="main" sCount=1 dsCount=0 obj=0x4080e500 self=0x18c118
| sysTid=18770 nice=0 sched=0/0 cgrp=default handle=1321016
| schedstat=( 28271000 3756000 52 ) utm=2 stm=0 core=1
at java.lang.Object.wait(Native Method)
- waiting on <0x407a1538> (a java.lang.VMThread) tid=9
at java.lang.Thread.parkFor(Thread.java:1425)
at java.lang.LangAccessImpl.parkFor(LangAccessImpl.java:48)
at sun.misc.Unsafe.park(Unsafe.java:329)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:197)
at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:421)
at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:320)
at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:875)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1014)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1074)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:574)
at java.lang.Thread.run(Thread.java:1020)

"RefQueueWorker@org.apache.http.impl.conn.tsccm.ConnPoolByRoute@407cf900" daemon prio=5 tid=8 WAIT
| group="main" sCount=1 dsCount=0 obj=0x407d2798 self=0x136bb0
| sysTid=18627 nice=0 sched=0/0 cgrp=default handle=758872
| schedstat=( 170000 1892000 3 ) utm=0 stm=0 core=1
at java.lang.Object.wait(Native Method)
- waiting on <0x407d17d8> (a java.lang.ref.ReferenceQueue)
at java.lang.Object.wait(Object.java:398)
at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:107)
at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:80)
at org.apache.http.impl.conn.tsccm.RefQueueWorker.run(RefQueueWorker.java:102)
at java.lang.Thread.run(Thread.java:1020)

"Binder Thread #2" prio=5 tid=7 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x40795840 self=0x1380d0
| sysTid=18626 nice=0 sched=0/0 cgrp=default handle=1302200
| schedstat=( 2446000 7307000 17 ) utm=0 stm=0 core=0
at dalvik.system.NativeStart.run(Native Method)

"Binder Thread #1" prio=5 tid=6 NATIVE
| group="main" sCount=1 dsCount=0 obj=0x40795738 self=0x137eb8
| sysTid=18625 nice=0 sched=0/0 cgrp=default handle=760024
| schedstat=( 3565000 3451000 22 ) utm=0 stm=0 core=0
at dalvik.system.NativeStart.run(Native Method)

"Compiler" daemon prio=5 tid=5 VMWAIT
| group="system" sCount=1 dsCount=0 obj=0x40794190 self=0x138ce0
| sysTid=18624 nice=0 sched=0/0 cgrp=default handle=759768
| schedstat=( 416439000 141042000 1819 ) utm=20 stm=21 core=1
at dalvik.system.NativeStart.run(Native Method)

"Signal Catcher" daemon prio=5 tid=4 RUNNABLE
| group="system" sCount=0 dsCount=0 obj=0x407940b0 self=0x138e98
| sysTid=18623 nice=0 sched=0/0 cgrp=default handle=1277432
| schedstat=( 2860000 92000 3 ) utm=0 stm=0 core=0
at dalvik.system.NativeStart.run(Native Method)

"GC" daemon prio=5 tid=3 VMWAIT
| group="system" sCount=1 dsCount=0 obj=0x40793fe8 self=0x13e8d0
| sysTid=18622 nice=0 sched=0/0 cgrp=default handle=1313208
| schedstat=( 248788000 87053000 136 ) utm=22 stm=2 core=1
at dalvik.system.NativeStart.run(Native Method)

"HeapWorker" daemon prio=5 tid=2 VMWAIT
| group="system" sCount=1 dsCount=0 obj=0x40793f10 self=0xba8b8
| sysTid=18621 nice=0 sched=0/0 cgrp=default handle=1311416
| schedstat=( 732070000 39255000 346 ) utm=69 stm=4 core=0
at dalvik.system.NativeStart.run(Native Method)

Any ideas on what hung the app?

 

[Erel]

Nothing in these stack trace points to your code. So it is impossible to say where is the problem.

Are you doing some lengthy operation?

 

[Kevin]

I didn't think it pointed to anything either. At least you've confirmed that.


I wouldn't say my app does any lengthy operations, at least none that should cause an ANR. The only times I have ever seen an ANR myself is when my app first starts and only on an AVD, which I chalk up to a slow emulator.

It's possible that a slow phone or tablet could maybe choke when my app first starts. There are around 50 buttons and they all use hi-res images, so it is a bit taxing on the device when it first starts up.

Since I couldn't figure out a cause from that log and you agree that it tells nothing, then I'll just have to let it go for now.

 

[Erel]

Make sure that the images are not (significantly) larger than they need to be.