anr日志导出及分析

anr发生原因

Application Not Responding（简称:ANR）指应用中一些特定的事件（如用户触摸事件、广播等）在应用的主线程没有在规定的时间内处理完，系统自动做出终止应用运行的响应。Android系统中，ActivityManagerService(简称AMS)和WindowManagerService(简称WMS)会检测App的响应时间，如果App在特定时间无法相应屏幕触摸或键盘输入时间，或者特定事件没有处理完毕，就会出现ANR。
anr出现的场景：
InputDispatching Timeout：5秒内无法响应屏幕触摸事件或键盘输入事件。
BroadcastQueue Timeout ：在执行前台广播（BroadcastReceiver）的onReceive()函数时10秒没有处理完成，后台为60秒。
Service Timeout ：前台服务20秒内，后台服务在200秒内没有执行完毕。
ContentProvider Timeout ：ContentProvider的publish在10s内没进行完。

1、查看是否有anr文件

在命令行窗口
adb shell
ls adb /data/anr

adb查询anr日志.PNG

2、导出anr日志

法1：
在android studio terminal窗口
adb bugreport

导出anr日志.PNG

法2:
打开CMD小黑框，进入到Android SDK 目录下的platform-tools文件夹下面直接输出： adb logcat 复制粘贴到一个txt文本下，方便稍后查看
法3：
执行adb pull /data/anr/traces.txt D:\traces.txt 输出traces日志。

anr日志分析

例1

                try {
                    Thread.sleep(9000);
                } catch (InterruptedException e) {
                    e.printStackTrace();
                }

在activity中按钮点击后在主线程执行sleep操作。导出的anr日志如下：

主线程sleep导致anr分析.PNG

搜索DALVIK_THREADS，找到第一个，然后看主线程main，tid是线程id。然后线程状态是Sleeping。继续往下看堆栈可以看到是因为按钮点击后sleep时间过长，导致后续点击事件无法及时处理导致。

各参数含义：
group:线程所处的线程组
sCount: 线程被正常挂起的次数
dsCount: 线程因调试而挂起次数
nice:线程的调度有优先级
utm：线程在用户态中调度时间值
stm:线程在内核态中的调度时间值
core:最后执行这个线程的CPU核序号

例2

public class MainActivity extends AppCompatActivity implements View.OnClickListener {
    private static final String TAG = "MainActivity";
    private Button button;
    private Button button2;
    private Button btnMVTest;
    private Object object= new Object();
    private MyLock myLock = new MyLock();
    @Override
    protected void onCreate(Bundle savedInstanceState) {
        super.onCreate(savedInstanceState);
        setContentView(R.layout.activity_main);
        findViews();
    }

    private void findViews() {
        button = findViewById(R.id.button);
        button2 = findViewById(R.id.button2);
        btnMVTest = findViewById(R.id.btnMVTest);
        button.setOnClickListener(this);
        button2.setOnClickListener(this);
        btnMVTest.setOnClickListener(this);
    }

    @Override
    public void onClick(View v) {
        int viewId = v.getId();
        switch (viewId){
            case R.id.button2:
                testBlock();
                break;
            case R.id.btnMVTest:
                Log.e(TAG, "onClick: mainThread="+Thread.currentThread().getName()+" tid="+Thread.currentThread().getId());
                doSqlQuery();
                break;
        }
    }

    private void testBlock(){
        Thread thread = new Thread(new Runnable() {
            @Override
            public void run() {
                synchronized (myLock){
                    try {
                        doSqlQuery();
                        Thread.sleep(11000);
                    } catch (InterruptedException e) {
                        e.printStackTrace();
                    }
                }
            }
        });
        thread.setName("DTestBlock");
        thread.start();
    }

    private void doSqlQuery(){
        synchronized (myLock){
            Log.e(TAG, "doThreadBlcok: 被锁住的函数 threadName="+Thread.currentThread().getName()+" threadId="+Thread.currentThread().getId());
        }
    }

    private class MyLock{

    }
}

看下anr日志。

主线程阻塞导致anr分析.PNG

可以看到主线程处于Blocked(阻塞)状态，为何阻塞：

- waiting to lock <0x0b19381d> (a com.example.router.MainActivity$MyLock) held by thread 3

尝试锁定MyLock实例对象，但是它被线程3所持有。

线程3sleep.PNG

线程3执行了sleep，在sleep的时间段内，没有释放对象锁。导致主线程获取不到对象锁而处于阻塞状态，后续点击事件响应不了，从而导致anr。

3、总结
anr是比较严重的性能问题，对用户体验影响较大。而且手动导出anr日志有时并没那么方便，可以考虑使用第三方性能检测工具如bugly等，简化anr的排查。

参考：
//www.greatytc.com/p/eb6826c40e05