ANR问题记录

ANR问题解决思路

Android应用ANR分析
Android ANR 分析
FastJNI导致的Android系统死机问题分析
Android ANR问题 -- Input超时实战问题解析上

判断某线程或服务是否在处于空闲状态:ANR分析

  • 术语
    nativePollOnce表示该线程的looper messagequeue中没有消息,线程处于空闲状态
    epoll_wait表示该线程的looper处于等待状态和nativePollOnce对应

获取ANR文件

  • 在低系统版本,用
    db pull data/anr/traces.txt 就能获取anr文件。
  • 在高系统版本需要使用adb bugreport pathName
    获取anr文件,比如adb bugreport /Users/hongjunmin/Desktop/ANR
    将文件解压后,可以在目录bugreport-CPH1803-OPMXX/FS/data/anr下看到ANR文件
    有时候会发现这个文件夹下没有最新的ANR信息,这时候去bugreport-bullhead-OPM/bugreport-bullhead-OPM.txt文件里搜 "VM TRACES AT LAST ANR" 往下翻就是这次ANR的trace信息了
    也可以通过开发者选项获取并阅读错误报告

案例解析

案例1
ANR提示:

03-13 21:11:44.813 E/ANRManager(  820): ANR in com.my.fm
03-13 21:11:44.813 E/ANRManager(  820): Reason: Executing service com.my.fm/.common.downloadmgr.DownloadService

第一反应是查看DownloadService的生命周期函数onCreate和onStartCommand里面有没有耗时操作,检查完毕,并没有,懵逼。
然后我在ANR日志中观察到如下数据:

03-13 21:11:37.806 I/ANRManager(  820): getProcessState
03-13 21:11:37.808 I/ActivityManager(  820): Android time :[2017-03-13 21:11:37.805] [8809.438]
03-13 21:11:37.808 I/ActivityManager(  820): CPU usage from 7105ms to 27ms ago:
03-13 21:11:37.808 I/ActivityManager(  820):   98% 12850/com.my.fm: 98% user + 0.1% kernel / faults: 15 minor
03-13 21:11:37.808 I/ActivityManager(  820):   7% 3510/cn.testin.itestin:nserver: 5.9% user + 1.1% kernel / faults: 3367 minor
03-13 21:11:37.808 I/ActivityManager(  820):   5.7% 820/system_server: 3.2% user + 2.5% kernel / faults: 61 minor
03-13 21:11:37.808 I/ActivityManager(  820):   3.3% 246/surfaceflinger: 0.5% user + 2.8% kernel
03-13 21:11:37.808 I/ActivityManager(  820):   2.1% 21712/com.android.launcher3: 0.9% user + 1.1% kernel
03-13 21:11:37.808 I/ActivityManager(  820):   1.6% 297/adbd: 0.7% user + 0.9% kernel / faults: 120 minor
03-13 21:11:37.808 I/ActivityManager(  820):   1.2% 12711/com.lbe.security.meizu:service: 1.1% user + 0.1% kernel
03-13 21:11:37.808 I/ActivityManager(  820):   0.9% 3539/cn.testin.itestin:reader: 0.4% user + 0.5% kernel
03-13 21:11:37.808 I/ActivityManager(  820):   0.7% 1342/com.meizu.cloud: 0.4% user + 0.2% kernel
03-13 21:11:37.808 I/ActivityManager(  820):   0.7% 12335/logcat: 0.1% user + 0.5% kernel
03-13 21:11:37.808 I/ActivityManager(  820):   0.5% 6527/com.android.systemui: 0.2% user + 0.2% kernel / faults: 13 minor
03-13 21:11:37.808 I/ActivityManager(  820):   0.5% 19427/kworker/0:0: 0% user + 0.5% kernel
03-13 21:11:37.808 I/ActivityManager(  820):   0.4% 6874/kworker/u16:3: 0% user + 0.4% kernel
03-13 21:11:37.808 I/ActivityManager(  820):   0.2% 121/fence_worker: 0% user + 0.2% kernel
03-13 21:11:37.808 I/ActivityManager(  820):   0.2% 128/bat_thread_kthr: 0% user + 0.2% kernel
03-13 21:11:37.808 I/ActivityManager(  820):   0.2% 129/mtk charger_hv_: 0% user + 0.2% kernel
03-13 21:11:37.808 I/ActivityManager(  820):   0.2% 813/gsm0710muxd: 0% user + 0.2% kernel
03-13 21:11:37.808 I/ActivityManager(  820):   0.2% 12134/kworker/u16:0: 0% user + 0.2% kernel
03-13 21:11:37.808 I/ActivityManager(  820):   0.1% 8/rcu_preempt: 0% user + 0.1% kernel
03-13 21:11:37.808 I/ActivityManager(  820):   0.1% 57/cfinteractive: 0% user + 0.1% kernel
03-13 21:11:37.808 I/ActivityManager(  820):   0.1% 61/hps_main: 0% user + 0.1% kernel
03-13 21:11:37.808 I/ActivityManager(  820):   0.1% 225/healthd: 0% user + 0.1% kernel
03-13 21:11:37.808 I/ActivityManager(  820):   0.1% 241/netd: 0% user + 0.1% kernel / faults: 12 minor
03-13 21:11:37.808 I/ActivityManager(  820):   0.1% 11546/uiautomator: 0.1% user + 0% kernel / faults: 3 minor
03-13 21:11:37.808 I/ActivityManager(  820):   0.1% 30849/tx_thread: 0% user + 0.1% kernel
03-13 21:11:37.808 I/ActivityManager(  820):   0.1% 30850/hif_thread: 0% user + 0.1% kernel
03-13 21:11:37.808 I/ActivityManager(  820):   0% 30851/rx_thread: 0% user + 0% kernel
03-13 21:11:37.808 I/ActivityManager(  820):  +0% 12945/app_process: 0% user + 0% kernel
03-13 21:11:37.808 I/ActivityManager(  820):  +0% 12961/top: 0% user + 0% kernel
03-13 21:11:37.808 I/ActivityManager(  820): 0.1% TOTAL: 0.1% user + 0% kernel + 0% softirq
03-13 21:11:37.808 I/ActivityManager(  820):

发现我们的应用myapp占用CPU达到了98%
这时候就有另一个思路,即使DownloadService本身没有耗时操作,但是如果有另一个任务或者线程频繁占用CPU,这么最终会拖累DownloadService的执行,同样会引发DownloadService的ANR。
接着观察到下面的数据


"Thread-2250" prio=5 tid=13 SUSPENDED
  | group="main" sCount=1 dsCount=0 obj=0x422a65c8 self=0x788a60a8
  | sysTid=12902 nice=0 sched=0/0 cgrp=default handle=2022342448
  | state=S schedstat=( 19713394162 221285073 1557 ) utm=1969 stm=2 core=2
  at java.util.ArrayList.isEmpty(ArrayList.java:~323)
  at java.util.Collections$SynchronizedCollection.isEmpty(Collections.java:410)
  at com.my.fm.common.util.f.a(Tool.java:273)
  at com.my.fm.common.downloadmgr.d$d.b(DownloadManager.java:1453)
  at com.my.common.downloadmgr.d.run(DownloadManager.java:274)

好,有点可疑,我就去定位(DownloadManager.java:1453)这行代码,发现这行代码是在一个while死循环里运行

 @Override
    public void run() {
        super.run();
        while (isRunning) { //isRunning在某些时候一直为true.
           //DownloadManager code
            }
        }
    }

虽然while循环在非主线程运行,但是会一直请求CPU占用,所以这处代码就像地雷,如何改正呢?很简单,使用Thread.sleep即可:

 @Override
    public void run() {
        super.run();
        while (isRunning) {
            //my working code
            //防止一直while占用大量CPU时间,造成ANR
            try {
                Thread.sleep(800);
            } catch (InterruptedException e) {
                e.printStackTrace();
            }
        }
    }

结论:不仅仅是不要在主线程做耗时操作,并发线程过多,或者非主线程长时间占用CPU, 同样会造成UI卡顿。

最后编辑于
©著作权归作者所有,转载或内容合作请联系作者
  • 序言:七十年代末,一起剥皮案震惊了整个滨河市,随后出现的几起案子,更是在滨河造成了极大的恐慌,老刑警刘岩,带你破解...
    沈念sama阅读 213,014评论 6 492
  • 序言:滨河连续发生了三起死亡事件,死亡现场离奇诡异,居然都是意外死亡,警方通过查阅死者的电脑和手机,发现死者居然都...
    沈念sama阅读 90,796评论 3 386
  • 文/潘晓璐 我一进店门,熙熙楼的掌柜王于贵愁眉苦脸地迎上来,“玉大人,你说我怎么就摊上这事。” “怎么了?”我有些...
    开封第一讲书人阅读 158,484评论 0 348
  • 文/不坏的土叔 我叫张陵,是天一观的道长。 经常有香客问我,道长,这世上最难降的妖魔是什么? 我笑而不...
    开封第一讲书人阅读 56,830评论 1 285
  • 正文 为了忘掉前任,我火速办了婚礼,结果婚礼上,老公的妹妹穿的比我还像新娘。我一直安慰自己,他们只是感情好,可当我...
    茶点故事阅读 65,946评论 6 386
  • 文/花漫 我一把揭开白布。 她就那样静静地躺着,像睡着了一般。 火红的嫁衣衬着肌肤如雪。 梳的纹丝不乱的头发上,一...
    开封第一讲书人阅读 50,114评论 1 292
  • 那天,我揣着相机与录音,去河边找鬼。 笑死,一个胖子当着我的面吹牛,可吹牛的内容都是我干的。 我是一名探鬼主播,决...
    沈念sama阅读 39,182评论 3 412
  • 文/苍兰香墨 我猛地睁开眼,长吁一口气:“原来是场噩梦啊……” “哼!你这毒妇竟也来了?” 一声冷哼从身侧响起,我...
    开封第一讲书人阅读 37,927评论 0 268
  • 序言:老挝万荣一对情侣失踪,失踪者是张志新(化名)和其女友刘颖,没想到半个月后,有当地人在树林里发现了一具尸体,经...
    沈念sama阅读 44,369评论 1 303
  • 正文 独居荒郊野岭守林人离奇死亡,尸身上长有42处带血的脓包…… 初始之章·张勋 以下内容为张勋视角 年9月15日...
    茶点故事阅读 36,678评论 2 327
  • 正文 我和宋清朗相恋三年,在试婚纱的时候发现自己被绿了。 大学时的朋友给我发了我未婚夫和他白月光在一起吃饭的照片。...
    茶点故事阅读 38,832评论 1 341
  • 序言:一个原本活蹦乱跳的男人离奇死亡,死状恐怖,灵堂内的尸体忽然破棺而出,到底是诈尸还是另有隐情,我是刑警宁泽,带...
    沈念sama阅读 34,533评论 4 335
  • 正文 年R本政府宣布,位于F岛的核电站,受9级特大地震影响,放射性物质发生泄漏。R本人自食恶果不足惜,却给世界环境...
    茶点故事阅读 40,166评论 3 317
  • 文/蒙蒙 一、第九天 我趴在偏房一处隐蔽的房顶上张望。 院中可真热闹,春花似锦、人声如沸。这庄子的主人今日做“春日...
    开封第一讲书人阅读 30,885评论 0 21
  • 文/苍兰香墨 我抬头看了看天上的太阳。三九已至,却和暖如春,着一层夹袄步出监牢的瞬间,已是汗流浃背。 一阵脚步声响...
    开封第一讲书人阅读 32,128评论 1 267
  • 我被黑心中介骗来泰国打工, 没想到刚下飞机就差点儿被人妖公主榨干…… 1. 我叫王不留,地道东北人。 一个月前我还...
    沈念sama阅读 46,659评论 2 362
  • 正文 我出身青楼,却偏偏与公主长得像,于是被迫代替她去往敌国和亲。 传闻我的和亲对象是个残疾皇子,可洞房花烛夜当晚...
    茶点故事阅读 43,738评论 2 351

推荐阅读更多精彩内容

  • Android 自定义View的各种姿势1 Activity的显示之ViewRootImpl详解 Activity...
    passiontim阅读 171,884评论 25 707
  • java 接口的意义-百度 规范、扩展、回调 抽象类的意义-乐视 为其子类提供一个公共的类型封装子类中得重复内容定...
    交流电1582阅读 2,218评论 0 11
  • 从三月份找实习到现在,面了一些公司,挂了不少,但最终还是拿到小米、百度、阿里、京东、新浪、CVTE、乐视家的研发岗...
    时芥蓝阅读 42,220评论 11 349
  • 在实际情况中,当Android项目的用户量特别大时候,一些细小的问题也会被放大,ANR问题就是一个典型的例子。一些...
    Uprising阅读 53,971评论 4 116