Android ANR 分析学习总结

本文详细介绍了Android ANR的三种主要类型:KeyDispatchTimeout、BroadcastTimeout和服务超时,并分析了KeyDispatchTimeout超时的原因,包括UI线程的阻塞和死锁。还通过实例解析了如何分析和定位ANR问题,包括查看trace.txt文件和识别线程状态。此外,提到了CPU饥饿导致的ANR,并给出了解决ANR的思路。

我的简书Android ANR 分析学习总结

1. 什么情况下会发生anr

(1). KeyDispatchTimeout(5 seconds) --主要类型按键或触摸事件在特定时间内无响应
(2). BroadcastTimeout(10 seconds) --BroadcastReceiver在特定时间内无法处理完成
(3). ServiceTimeout(20 seconds) --小概率类型 Service在特定的时间内无法处理完成

2. KeyDispatchTimeout超时原因

(1). 当前的事件没有机会得到处理(即UI线程正在处理前一个事件,没有及时的完成或者looper被某种原因阻塞住了)
(2). 当前的事件正在处理,但没有及时完成

首先要知道事件发生的线程,一般来说大多数可能是ui线程操作超时,那么ui线程都有哪些呢:
(1). Activity 生命周期
(2). View post 的runnable方法 、 handler(MainLooper) 的 handleMessage()
(3). Asycktask 的 onPreExecute(), onPostExecute() , onProgressUpdate()方法
(4). Broadcast 的onReceive()
(5). Service
其他原因造成的anr:
(1). 线程死锁
(2). cpu 饥饿

3. 举例

(1) 普通的,可以直接查看trace.txt (http://www.eoeandroid.com/thread-152008-1-1.html?_dsign=554288df)
获取trace.txt 文件 adb shell cat /data/anr/traces.txt > d:/traces.txt (拷贝到d盘)

----- pid 2328 at 2011-12-21 09:36:36 -----
Cmd line: system_server

DALVIK THREADS:
(mutexes: tll=0 tsl=0 tscl=0 ghl=0 hwl=0 hwll=0)
"main" prio=5 tid=1 SUSPENDED
  | group="main" sCount=1 dsCount=0 obj=0x4001f180 self=0xce48
  | sysTid=2328 nice=0 sched=0/0 cgrp=default handle=-1345006248
  | schedstat=( 2251911039 10737773873 799 )
  at java.util.ArrayList.size(ArrayList.java:~322)   这里就是案发现场
  at java.util.Collections.sort(Collections.java:1964)
  at com.android.server.IntentResolver.sortResults(IntentResolver.java:334)
  at com.android.server.IntentResolver.queryIntent(IntentResolver.java:305)
  at com.android.server.am.ActivityManagerService.broadcastIntentLocked(ActivityManagerService.java:10288)
  at com.android.server.am.ActivityManagerService.broadcastIntent(ActivityManagerService.java:10478)
  at android.app.ContextImpl.sendStickyBroadcast(ContextImpl.java:736)
  at com.android.server.TelephonyRegistry.broadcastServiceStateChanged(TelephonyRegistry.java:500)
  at com.android.server.TelephonyRegistry.notifyServiceState(TelephonyRegistry.java:267)
  at com.android.internal.telephony.ITelephonyRegistry$Stub.onTransact(ITelephonyRegistry.java:81)
  at android.os.Binder.execTransact(Binder.java:320)
  at com.android.server.SystemServer.init1(Native Method)
  at com.android.server.SystemServer.main(SystemServer.java:854)
  at java.lang.reflect.Method.invokeNative(Native Method)
  at java.lang.reflect.Method.invoke(Method.java:507)
  at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:839)
  at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:597)
  at dalvik.system.NativeStart.main(Native Method)

"Binder Thread #6" prio=5 tid=46 NATIVE
  | group="main" sCount=1 dsCount=0 obj=0x406fd210 self=0x44b398
  | sysTid=2592 nice=0 sched=0/0 cgrp=default handle=4321440
  | schedstat=( 131676112 959825670 94 )
  at dalvik.system.NativeStart.run(Native Method)

"Thread-57" prio=5 tid=47 NATIVE
  | group="main" sCount=1 dsCount=0 obj=0x406f3068 self=0xd7f80
  | sysTid=2561 nice=10 sched=0/0 cgrp=bg_non_interactive handle=3488688
  | schedstat=( 89258622 34068288100 28 )
  at android.content.res.AssetManager.getStringBlockCount(Native Method)
  at android.content.res.AssetManager.makeStringBlocks(AssetManager.java:256)
  at android.content.res.AssetManager.ensureStringBlocks(AssetManager.java:248)
  at android.content.res.Resources.<init>(Resources.java:160)
  at android.app.ActivityThread.getTopLevelResources(ActivityThread.java:1202)
  at android.app.ActivityThread.getTopLevelResources(ActivityThread.java:1229)
  at android.app.ContextImpl$ApplicationPackageManager.getResourcesForApplication(ContextImpl.java:2372)
  at android.app.ContextImpl$ApplicationPackageManager.getXml(ContextImpl.java:2597)
  at android.content.pm.PackageItemInfo.loadXmlMetaData(PackageItemInfo.java:216)
  at android.app.SearchableInfo.getActivityMetaData(SearchableInfo.java:515)
  at android.server.search.Searchables.buildSearchableList(Searchables.java:215)
  at android.server.search.SearchManagerService.getSearchables(SearchManagerService.java:66)
  at android.server.search.SearchManagerService.access$200(SearchManagerService.java:38)
  at android.server.search.SearchManagerService$BootCompletedReceiver$1.run(SearchManagerService.java:82)

(2) 通过Android trace文件分析死锁ANR
ps 另外的例子http://www.eoeandroid.com/thread-94087-1-1.html?_dsign=94256d94

DALVIK THREADS:
"main" prio=5 tid=1 TIMED_WAIT
| group="main" sCount=1 dsCount=0 s=N obj=0x400208b8 self=0xcdf0
| sysTid=1732 nice=0 sched=0/0 cgrp=unknown handle=-1345026000
at java.lang.Object.wait(Native Method)
- waiting on <0x4a5ba4b0> (a com.gameloft.robin.DeadLockTraceActivity)  forceWait等待 <0x4a5ba4b0> 
at java.lang.Object.wait(Object.java:326) 
at com.gameloft.robin.DeadLockTraceActivity.forceWait(DeadLockTraceActivity.java:24)
at com.gameloft.robin.DeadLockTraceActivity$1.run(DeadLockTraceActivity.java:18)  匿名内部类1at android.os.Handler.handleCallback(Handler.java:587)
at android.os.Handler.dispatchMessage(Handler.java:92)
at android.os.Looper.loop(Looper.java:123)
at android.app.ActivityThread.main(ActivityThread.java:4633)
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:858)
at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:616)
at dalvik.system.NativeStart.main(Native Method)

这里列举下,线程的几种状态
ThreadState (defined at “dalvik/vm/thread.h “)
THREAD_UNDEFINED = -1, / makes enum compatible with int32_t /
THREAD_ZOMBIE = 0, / TERMINATED /
THREAD_RUNNING = 1, / RUNNABLE or running now /
THREAD_TIMED_WAIT = 2, / TIMED_WAITING in Object.wait() /
THREAD_MONITOR = 3, / BLOCKED on a monitor /
THREAD_WAIT = 4, / WAITING in Object.wait() /
THREAD_INITIALIZING= 5, / allocated, not yet running /
THREAD_STARTING = 6, / started, not yet on thread list /
THREAD_NATIVE = 7, / off in a JNI native method /
THREAD_VMWAIT = 8, / waiting on a VM resource /
THREAD_SUSPENDED = 9, / suspended, usually by GC or debugger /
上述处于TIMED_WAIT 。

http://fanli7.net/a/bianchengyuyan/C__/20130616/371042.html

"PowerManagerService" prio=5 tid=24 MONITOR 在tid为24中
  | group="main" sCount=1 dsCount=0 obj=0x41dd0eb0 self=0x5241b218
  | sysTid=567 nice=0 sched=0/0 cgrp=apps handle=1380038664
  | state=S schedstat=( 6682116007 11324451214 33313 ) utm=450 stm=219 core=1
  at com.android.server.am.ActivityManagerService.broadcastIntent(ActivityManagerService.java:~13045)
  - waiting to lock <0x41a874a0> (a com.android.server.am.ActivityManagerService) held by tid=12 (android.server.ServerThread)  tid 为12的家伙持有了0x41a874a0资源
  at android.app.ContextImpl.sendBroadcast(ContextImpl.java:1144)
  at com.android.server.power.PowerManagerService$DisplayBlankerImpl.unblankAllDisplays(PowerManagerService.java:3442)
  at com.android.server.power.DisplayPowerState$PhotonicModulator$1.run(DisplayPowerState.java:456)
  at android.os.Handler.handleCallback(Handler.java:800)
  at android.os.Handler.dispatchMessage(Handler.java:100)
  at android.os.Looper.loop(Looper.java:194)
  at android.os.HandlerThread.run(HandlerThread.java:60)

  "Binder_B" prio=5 tid=85 MONITOR 在tid 为85中
  | group="main" sCount=1 dsCount=0 obj=0x42744770 self=0x58329e88
  | sysTid=3700 nice=-20 sched=0/0 cgrp=apps handle=1471424616
  | state=S schedstat=( 1663727513 2044643318 6806 ) utm=132 stm=34 core=1  
  at com.android.server.power.PowerManagerService$DisplayBlankerImpl.toString(PowerManagerService.java:~3449) 
  - waiting to lock <0x41a7e420> (a com.android.server.power.PowerManagerService$DisplayBlankerImpl) held by tid=24 (PowerManagerService)  tid 为24的家伙持有了0x41a7e420资源
  at java.lang.StringBuilder.append(StringBuilder.java:202)
  at com.android.server.power.PowerManagerService.dump(PowerManagerService.