文档章节

ANR分析解决(二)

k
 kim366
发布于 2016/05/13 19:13
字数 2122
阅读 3
收藏 0
点赞 2
评论 0

一:什么是ANR

ANR:Application Not Responding,即应用无响应

二:ANR的类型

ANR一般有三种类型:

1. KeyDispatchTimeout(5 seconds) --主要类型按键或触摸事件在特定时间内无响应

2. BroadcastTimeout(10 seconds) --BroadcastReceiver在特定时间内无法处理完成

3. ServiceTimeout(20 seconds) --小概率类型 Service在特定的时间内无法处理完成

三:KeyDispatchTimeout

Akey or touch event was not dispatched within the specified time(按键或触摸事件在特定时间内无响应)

具体的超时时间的定义在framework下的ActivityManagerService.java

//How long we wait until we timeout on key dispatching.

staticfinal int KEY_DISPATCHING_TIMEOUT = 5*1000

四:为什么会超时呢?

超时时间的计数一般是从按键分发给app开始。超时的原因一般有两种:

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

(2)当前的事件正在处理,但没有及时完成

五:如何避免KeyDispatchTimeout

1. UI线程尽量只做跟UI相关的工作

2. 耗时的工作(比如数据库操作,I/O,连接网络或者别的有可能阻碍UI线程的操作)把它放入单独的线程处理

3. 尽量用Handler来处理UIthread和别的thread之间的交互

六:UI线程

说了那么多的UI线程,那么哪些属于UI线程呢?

UI线程主要包括如下:

1. Activity:onCreate(), onResume(), onDestroy(), onKeyDown(), onClick(),etc

2. AsyncTask: onPreExecute(), onProgressUpdate(), onPostExecute(), onCancel,etc

3. Mainthread handler: handleMessage(), post*(runnable r), etc

4. other

七:如何去分析ANR

先看个LOG:   

1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
04-01 13:12:11.572 I/InputDispatcher( 220): Application is not responding:Window{2b263310com.android.email/com.android.email.activity.SplitScreenActivitypaused=false}.
     5009.8ms since event, 5009.5ms since waitstarted
     04-0113:12:11.572 I/WindowManager( 220): Input event
     dispatching timedout sending
     tocom.android.email/com.android.email.activity.SplitScreenActivity
 
     04-01 13:12:14.123 I/Process( 220): Sending signal. PID: 21404 SIG: 3---发生ANR的时间和生成trace.txt的时间
     04-01 13:12:14.123 I/dalvikvm(21404):threadid=4: reacting to
     signal 3
     ……
     04-0113:12:15.872 E/ActivityManager( 220): ANR in
     com.android.email(com.android.email/.activity.SplitScreenActivity)
     04-0113:12:15.872 E/ActivityManager( 220):
     Reason:keyDispatchingTimedOut
     04-0113:12:15.872 E/ActivityManager( 220): Load: 8.68 / 8.37 / 8.53
     04-0113:12:15.872 E/ActivityManager( 220): CPUusage from 4361ms to 699ms ago ----CPU在ANR发生前的使用情况
 
     04-0113:12:15.872 E/ActivityManager( 220): 5.5%21404/com.android.email: 1.3% user + 4.1% kernel / faults:
     10 minor
     04-0113:12:15.872 E/ActivityManager( 220): 4.3%220/system_server: 2.7% user + 1.5% kernel / faults: 11
     minor 2 major
     04-0113:12:15.872 E/ActivityManager( 220): 0.9%52/spi_qsd.0: 0% user + 0.9% kernel
     04-0113:12:15.872 E/ActivityManager( 220): 0.5%65/irq/170-cyttsp-: 0% user + 0.5% kernel
     04-0113:12:15.872 E/ActivityManager( 220): 0.5%296/com.android.systemui: 0.5% user + 0% kernel
     04-0113:12:15.872 E/ActivityManager( 220): 100%TOTAL: 4.8% user + 7.6% kernel + 87% iowait
     04-0113:12:15.872 E/ActivityManager( 220): CPUusage from 3697ms to 4223ms later:-- ANR后CPU的使用量
     04-0113:12:15.872 E/ActivityManager( 220): 25%21404/com.android.email: 25% user + 0% kernel / faults: 191 minor
     04-0113:12:15.872 E/ActivityManager( 220): 16% 21603/__eas(par.hakan: 16% user + 0% kernel
     04-0113:12:15.872 E/ActivityManager( 220): 7.2% 21406/GC: 7.2% user + 0% kernel
     04-0113:12:15.872 E/ActivityManager( 220): 1.8% 21409/Compiler: 1.8% user + 0% kernel
     04-0113:12:15.872 E/ActivityManager( 220): 5.5%220/system_server: 0% user + 5.5% kernel / faults: 1 minor
     04-0113:12:15.872 E/ActivityManager( 220): 5.5% 263/InputDispatcher: 0% user + 5.5% kernel
     04-0113:12:15.872 E/ActivityManager( 220): 32%TOTAL: 28% user + 3.7% kernel
 
    

从LOG可以看出ANR的类型,CPU的使用情况,如果CPU使用量接近100%,说明当前设备很忙,有可能是CPU饥饿导致了ANR

如果CPU使用量很少,说明主线程被BLOCK了

如果IOwait很高,说明ANR有可能是主线程在进行I/O操作造成的

除了看LOG,解决ANR还得需要trace.txt文件,

如何获取呢?可以用如下命令获取

$chmod 777 /data/anr

$rm /data/anr/traces.txt

$ps

$kill -3 PID

adbpull data/anr/traces.txt ./mytraces.txt 
    从trace.txt文件,看到最多的是如下的信息:

1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
-----pid  21404  at  2011 - 04 - 01  13 : 12 : 14  -----
   Cmdline: com.android.email
 
   DALVIK THREADS:
   (mutexes: tll=0tsl= 0  tscl= 0  ghl= 0  hwl= 0  hwll= 0 )
   "main"  prio= 5  tid=1NATIVE
   | group= "main"  sCount= 1  dsCount=0obj= 0x2aad2248  self= 0xcf70
   | sysTid= 21404  nice= 0  sched= 0 /0cgrp=[fopen-error: 2 ]
   handle= 1876218976
   atandroid.os.MessageQueue.nativePollOnce(Native Method)
   atandroid.os.MessageQueue.next(MessageQueue.java: 119 )
   atandroid.os.Looper.loop(Looper.java: 110 )
   at android.app.ActivityThread.main(ActivityThread.java: 3688 )
   at java.lang.reflect.Method.invokeNative(Native Method)
   atjava.lang.reflect.Method.invoke(Method.java: 507 )
 
   atcom.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java: 866 )
   at
   com.android.internal.os.ZygoteInit.main(ZygoteInit.java: 624 )
   at dalvik.system.NativeStart.main(Native Method)


    说明主线程在等待下条消息进入消息队列

八:Thread状态

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 */

九:如何调查并解决ANR

1. 首先分析log

2. 从trace.txt文件查看调用stack.

3. 看代码

4. 仔细查看ANR的成因(iowait?block?memoryleak?)

十:案例

案例1:关键词:ContentResolver in AsyncTask onPostExecute, high iowait

原因:IOWait很高,说明当前系统在忙于I/O,因此数据库操作被阻塞

原来:

1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
final  Message message=Message.restoreMessageWithId(mProviderContext,messageId);
     if (message== null ){
     return ;
}
Account account=Account.restoreAccountWithId(mProviderContext,message.mAccountKey);
if (account== null ){
     return ; //isMessagingController returns false for null, but let's make itclear.
}
if (isMessagingController(account)){
     new  Thread(){
         @Override
         public  void  run(){
         mLegacyController.processPendingActions(message.mAccountKey);
     }}.start();
}

解决后:

 

1
2
3
4
5
6
7
8
9
10
11
12
13
newThread() {
     finalMessagemessage=Message.restoreMessageWithId(mProviderContext,messageId);
     if (message==null){
         return ;
     }
     Accountaccount=Account.restoreAccountWithId(mProviderContext,message.mAccountKey);
     if (account==null){
         return ; //isMessagingController returns false for null, but let's make itclear.
     }
     if (isMessagingController(account)) {
         mLegacyController.processPendingActions(message.mAccountKey);
     }
}.start();

    关于AsyncTask:http://developer.android.com/reference/android/os/AsyncTask.html

 

案例2:关键词:在UI线程进行网络数据的读写

1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
ANRin process: com.android.mediascape:PhotoViewer (last incom.android.mediascape:PhotoViewer)
     Annotation:keyDispatchingTimedOut
     CPU usage:
     Load:  6.74  6.89  6.12
     CPUusage from 8254ms to 3224ms ago:
     ovider.webmedia:  4 % =  4 % user + 0 % kernel / faults:  68  minor
     system_server:  2 % =  1 % user +  0 %kernel / faults:  18  minor
     re-initialized>:  0 % =  0 % user +  0 %kernel / faults:  50  minor
     events/ 0 0 % =  0 % user +  0 %kernel
     TOTAL: 7 % =  6 % user +  1 % kernel
 
     DALVIKTHREADS:
     "" main ""  prio= 5  tid= 3  NATIVE
     |group= "" main ""  sCount= 1  dsCount= 0  s=Yobj= 0x4001b240  self= 0xbda8
     | sysTid= 2579  nice= 0  sched= 0 /0cgrp=unknown handle=- 1343993184
     atorg.apache.harmony.luni.platform.OSNetworkSystem.receiveStreamImpl(NativeMethod)
     atorg.apache.harmony.luni.platform.OSNetworkSystem.receiveStream(OSNetworkSystem.java: 478 )
     atorg.apache.harmony.luni.net.PlainSocketImpl.read(PlainSocketImpl.java: 565 )
     atorg.apache.harmony.luni.net.SocketInputStream.read(SocketInputStream.java: 87 )
     atorg.apache.harmony.luni.internal.net.www.protocol.http.HttpURLConnection$LimitedInputStream.read(HttpURLConnection.java: 303 )
     atjava.io.InputStream.read(InputStream.java: 133 )
     atjava.io.BufferedInputStream.fillbuf(BufferedInputStream.java: 157 )
     atjava.io.BufferedInputStream.read(BufferedInputStream.java: 346 )
     atandroid.graphics.BitmapFactory.nativeDecodeStream(Native Method)
     atandroid.graphics.BitmapFactory.decodeStream(BitmapFactory.java: 459 )
     atcom.android.mediascape.activity.PhotoViewerActivity.getPreviewImage(PhotoViewerActivity.java: 4465 )
     atcom.android.mediascape.activity.PhotoViewerActivity.dispPreview(PhotoViewerActivity.java: 4406 )
     atcom.android.mediascape.activity.PhotoViewerActivity.access$ 6500 (PhotoViewerActivity.java: 125 )
     atcom.android.mediascape.activity.PhotoViewerActivity$ 33 $ 1 .run(PhotoViewerActivity.java: 4558 )
     atandroid.os.Handler.handleCallback(Handler.java: 587 )
     atandroid.os.Handler.dispatchMessage(Handler.java: 92 )
     atandroid.os.Looper.loop(Looper.java: 123 )
     atandroid.app.ActivityThread.main(ActivityThread.java: 4370 )
     atjava.lang.reflect.Method.invokeNative(Native Method)
     atjava.lang.reflect.Method.invoke(Method.java: 521 )
     atcom.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java: 868 )
     atcom.android.internal.os.ZygoteInit.main(ZygoteInit.java: 626 )
     atdalvik.system.NativeStart.main(Native Method)
 
    

    关于网络连接,在设计的时候可以设置个timeout的时间或者放入独立的线程来处理。

    关于Handler的问题,可以参考:http://developer.android.com/reference/android/os/Handler.html

    

案例3:关键词:Memoryleak/Thread leak

1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
11 - 1621 : 41 : 42.560  I/ActivityManager(  1190 ): ANR  in  process:android . process . acore (last  in  android . process . acore)
     11 - 1621 : 41 : 42.560  I/ActivityManager(  1190 ): Annotation:keyDispatchingTimedOut
     11 - 16  21 : 41 : 42.560  I/ActivityManager( 1190 ): CPU usage:
     11 - 16  21 : 41 : 42.560  I/ActivityManager(  1190 ):Load:  11.5  11.1  11.09
     11 - 16  21 : 41 : 42.560  I/ActivityManager( 1190 ): CPU usage from 9046ms  to  4018ms ago:
     11 - 16  21 : 41 : 42. 560I/ActivityManager(  1190 ): d . process . acore: 98 %=  97 % user +  0 % kernel / faults:  1134  minor
     11 - 16  21 : 41 : 42. 560I/ActivityManager(  1190 ): system_server:  0 % =  0 % user +  0 % kernel /faults:  1  minor
     11 - 16  21 : 41 : 42.560  I/ActivityManager(  1190 ): adbd: 0 % =  0 % user +  0 % kernel
     11 - 16  21 : 41 : 42.560  I/ActivityManager( 1190 ): logcat:  0 % =  0 % user +  0 % kernel
     11 - 16  21 : 41 : 42. 560I/ActivityManager(  1190 ): TOTAL: 100 % =  98 % user +  1 % kernel
 
     Cmdline: android . process . acore
 
     DALVIK THREADS:
     "main"prio= 5  tid= 3  VMWAIT
     |group="main" sCount= 1  dsCount= 0  s=N obj=0x40026240self=0xbda8
     | sysTid= 1815  nice= 0  sched= 0 / 0  cgrp=unknownhandle=- 1344001376
     atdalvik . system . VMRuntime . trackExternalAllocation(NativeMethod)
     atandroid . graphics . Bitmap . nativeCreate(Native Method)
     atandroid . graphics . Bitmap . createBitmap(Bitmap . java: 468 )
     atandroid . view . View . buildDrawingCache(View . java: 6324 )
     atandroid . view . View . getDrawingCache(View . java: 6178 )
     atandroid . view . ViewGroup . drawChild(ViewGroup . java: 1541 )
     ……
     atcom . android . internal . policy . impl . PhoneWindow $DecorView . draw(PhoneWindow . java: 1830 )
     atandroid . view . ViewRoot . draw(ViewRoot . java: 1349 )
     atandroid . view . ViewRoot . performTraversals(ViewRoot . java: 1114 )
     atandroid . view . ViewRoot . handleMessage(ViewRoot . java: 1633 )
     atandroid . os . Handler . dispatchMessage(Handler . java: 99 )
     atandroid . os . Looper . loop(Looper . java: 123 )
     atandroid . app . ActivityThread . main(ActivityThread . java: 4370 )
     atjava . lang . reflect . Method . invokeNative(Native Method)
     atjava . lang . reflect . Method . invoke(Method . java: 521 )
     atcom . android . internal . os . ZygoteInit $MethodAndArgsCaller . run(ZygoteInit . java: 868 )
     atcom . android . internal . os . ZygoteInit . main(ZygoteInit . java: 626 )
     atdalvik . system . NativeStart . main(Native Method)
 
     "Thread- 408 "prio= 5  tid= 329  WAIT
     |group="main" sCount= 1  dsCount= 0  s=N obj=0x46910d40self=0xcd0548
     | sysTid= 10602  nice= 0  sched= 0 / 0  cgrp=unknownhandle= 15470792
     at java . lang . Object . wait(Native Method)
     -waiting  on  <0x468cd420> (a java . lang . Object )
     atjava . lang . Object . wait( Object . java: 288 )
     atcom . android . dialer . CallLogContentHelper $UiUpdaterExecutor $1 .run(CallLogContentHelper . java: 289 )
     atjava . lang . Thread . run(Thread . java: 1096 )

    分析:

  atdalvik.system.VMRuntime.trackExternalAllocation(NativeMethod)内存不足导致block在创建bitmap上

    **MEMINFO in pid 1360 [android.process.acore] **

            native dalvik other total

            size: 17036 23111 N/A 40147

            allocated: 16484 20675 N/A 37159

            free: 296 2436 N/A 2732

    解决:如果机器的内存族,可以修改虚拟机的内存为36M或更大,不过最好是复查代码,查看哪些内存没有释放

本文转载自:http://blog.csdn.net/oyangyujun/article/details/47962205

共有 人打赏支持
k
粉丝 1
博文 129
码字总数 0
作品 0
朝阳
Android Bug 第一次,求思路 !(wait online...)

crashfile文件如下: EVENT=CRASHID=05181a7e96e23180d1afSN=Medfield7A2F2469DATE=2014-06-16/10:58:29 [size=13px]UPTIME=0000:12:40BUILD=mainidpt2-latest-134,intel/mofdv164/mofdv1:4......

Solo_heart ⋅ 2014/07/18 ⋅ 1

深入理解ANR

关于ANR,以前只知道Activity、BroadCastReceiver、Service三种组件的ANR时限、一般采用哪些方式避免ANR、以及通过data/anr/traces.txt去分析ANR原因,感觉好像这就够用了。 但是,前几天看源...

蓝灰_q ⋅ 2017/12/07 ⋅ 0

Android 系统稳定性 - ANR(二)

【原创】Android 系统稳定性 - ANR(二) 文章都为原创,转载请注明出处,未经允许而盗用者追究法律责任。 很久之前写的了,留着有点浪费,共享之。 编写者:李文栋 P.S. OpenOffice粘贴过来...

wangjian19 ⋅ 2014/12/10 ⋅ 0

ANR traces文件分析中的nativePollOnce类型怎么定位问题

最近在研究Android ANR的相关内容,我们都知道ANR的相关信息可以通过traces文件得到,有一种问题占ANR比例的相对比较大的部分 log如下: 想请教一下,这类ANR应该如何定位,如何解决?...

小森哥 ⋅ 2017/07/10 ⋅ 0

android ANR异常及其解决方式

ANR定义 在Android上,如果你的应用程序有一段时间响应不够灵敏,系统会向用户显示一个对话框,这个对话框称作应用程序无响应(ANR:Application Not Responding)对话框。用户可以选择让程序...

长平狐 ⋅ 2012/09/03 ⋅ 0

Android crash 收集

Android Crash 在开发中,会遇到crash问题,一般来说,crash发生在java层,但是,有时候会发生在其他层面上。大致,Android Crash 大致有三类: Java uncatch exception ANR crash Native cr...

精通吹水 ⋅ 2016/02/23 ⋅ 2

Android ANR错误有讲解

Android ANR这个错误大家并不陌生,但是从Android 2.2开始出错的ANR信息会自动上传给Google进行系统分析改进,当然了你的应用ANR错误其实保存在一个文件中,在/data/anr /traces.txt文件中,...

阿酷 ⋅ 2011/12/26 ⋅ 1

浅谈ANR及如何分析解决ANR

一:什么是ANR ANR:Application Not Responding,即应用无响应 二:ANR的类型 ANR一般有三种类型: KeyDispatchTimeout(5 seconds) --主要类型 按键或触摸事件在特定时间内无响应 BroadcastT...

Jerikc ⋅ 2013/06/13 ⋅ 0

笔记37 | Android App优化之ANR详解

地址 笔记37 | Android App优化之ANR详解 什么是ADR ANR全名Application Not Responding, 也就是"应用无响应". 当操作在一段时间内系统无法处理时, 系统层面会弹出上图那样的ANR对话框. 在A...

项勇 ⋅ 2017/11/20 ⋅ 0

database lock造成ANR

@迷途d书童 你好,想跟你请教个问题:在作Browser的monkey test时发生了ANR,查看log发现好多SQLiteDatabase: database lock has not been available for 30 sec. Current Owner of the lock ......

zhangJingyu ⋅ 2012/09/06 ⋅ 6

没有更多内容

加载失败,请刷新页面

加载更多

下一页

tcp/ip详解-链路层

简介 设计链路层的目的: 为IP模块发送和接收IP数据报 为ARP模块发送ARP请求和接收ARP应答 为RARP模块发送RARP请求和接收RARP应答 TCP/IP支持多种链路层协议,如以太网、令牌环往、FDDI、RS-...

loda0128 ⋅ 今天 ⋅ 0

spring.net aop代码例子

https://www.cnblogs.com/haogj/archive/2011/10/12/2207916.html

whoisliang ⋅ 今天 ⋅ 0

发送短信如何限制1小时内最多发送11条短信

发送短信如何限制1小时内最多发送11条短信 场景: 发送短信属于付费业务,有时为了防止短信攻击,需要限制发送短信的频率,例如在1个小时之内最多发送11条短信. 如何实现呢? 思路有两个 截至到当...

黄威 ⋅ 昨天 ⋅ 0

mysql5.7系列修改root默认密码

操作系统为centos7 64 1、修改 /etc/my.cnf,在 [mysqld] 小节下添加一行:skip-grant-tables=1 这一行配置让 mysqld 启动时不对密码进行验证 2、重启 mysqld 服务:systemctl restart mysql...

sskill ⋅ 昨天 ⋅ 0

Intellij IDEA神器常用技巧六-Debug详解

在调试代码的时候,你的项目得debug模式启动,也就是点那个绿色的甲虫启动服务器,然后,就可以在代码里面断点调试啦。下面不要在意,这个快捷键具体是啥,因为,这个keymap是可以自己配置的...

Mkeeper ⋅ 昨天 ⋅ 0

zip压缩工具、tar打包、打包并压缩

zip 支持压缩目录 1.在/tmp/目录下创建目录(study_zip)及文件 root@yolks1 study_zip]# !treetree 11└── 2 └── 3 └── test_zip.txt2 directories, 1 file 2.yum...

蛋黄Yolks ⋅ 昨天 ⋅ 0

聊聊HystrixThreadPool

序 本文主要研究一下HystrixThreadPool HystrixThreadPool hystrix-core-1.5.12-sources.jar!/com/netflix/hystrix/HystrixThreadPool.java /** * ThreadPool used to executed {@link Hys......

go4it ⋅ 昨天 ⋅ 0

容器之上传镜像到Docker hub

Docker hub在国内可以访问,首先要创建一个账号,这个后面会用到,我是用126邮箱注册的。 1. docker login List-1 Username不能使用你注册的邮箱,要用使用注册时用的username;要输入密码 ...

汉斯-冯-拉特 ⋅ 昨天 ⋅ 0

SpringBoot简单使用ehcache

1,SpringBoot版本 2.0.3.RELEASE ①,pom.xml <parent><groupId>org.springframework.boot</groupId><artifactId>spring-boot-starter-parent</artifactId><version>2.0.3.RELE......

暗中观察 ⋅ 昨天 ⋅ 0

Spring源码解析(八)——实例创建(下)

前言 来到实例创建的最后一节,前面已经将一个实例通过不同方式(工厂方法、构造器注入、默认构造器)给创建出来了,下面我们要对创建出来的实例进行一些“加工”处理。 源码解读 回顾下之前...

MarvelCode ⋅ 昨天 ⋅ 0

没有更多内容

加载失败,请刷新页面

加载更多

下一页

返回顶部
顶部