ANR 模拟分析

最新推荐文章于 2024-07-03 12:30:22 发布
最新推荐文章于 2024-07-03 12:30:22 发布
阅读量1.8k 收藏 1
点赞数
分类专栏： Android 文章标签： android
版权声明：本文为博主原创文章，遵循 CC 4.0 BY-SA 版权协议，转载请附上原文出处链接和本声明。
本文链接：https://blog.csdn.net/kanyou222/article/details/119388288
版权

一.系统服务导致的ANR 

1. 一个apk 调用系统服务，

//这是一个apk 调用了系统服务

buttton2.setOnClickListener(new View.OnClickListener() {
    @Override
    public void onClick(View v) {
        mPcm.testOne("one");  // 调用系统服务
    }
});


//系统服务中的函数，做了延迟，模拟 ANR 

public void testOne(String key)  {
    Slog.d(TAG,"phg__  testOne key " + key);
    try {
        Thread.sleep(20000);  // 延迟 20s
    } catch (Exception e){
        Slog.d(TAG,"phg__ testOne err ");
    }
    Slog.d(TAG,"phg__ testOne ");
}

2. 当我们点击apk的 button时，会调用了系统 testOne函数，由于系统函数做了20s的延迟，所以会发生 ANR ，这时 logcat 会打印如下错误

08-04 17:13:12.453  1178  8163 I ActivityManager: Done dumping
08-04 17:13:12.453  1178  8163 I AnrManager: dumpStackTraces end!
08-04 17:13:12.455  1178  8163 I AnrManager: ANR in com.coolos.testtwo (com.coolos.testtwo/.MainActivity), time=5282428
//ANR 原因
08-04 17:13:12.455  1178  8163 I AnrManager: Reason: Input dispatching timed out (ef785ad com.coolos.testtwo/com.coolos.testtwo.MainActivity (server) is not responding. Waited 5001ms for FocusEvent(hasFocus=false))
08-04 17:13:12.455  1178  8163 I AnrManager: Load: 28.83 / 28.99 / 29.13
08-04 17:13:12.455  1178  8163 I AnrManager: Android time :[2021-08-04 17:13:12.45] [5286.003]
08-04 17:13:12.455  1178  8163 I AnrManager: CPU usage from 52010ms to 0ms ago (2021-08-04 17:12:16.868 to 2021-08-04 17:13:08.879):
08-04 17:13:12.455  1178  8163 I AnrManager:   9.6% 1178/system_server: 5.4% user + 4.1% kernel / faults: 29901 minor 12 major
08-04 17:13:12.455  1178  8163 I AnrManager:   7.2% 388/logd: 1.4% user + 5.7% kernel / faults: 31 minor
08-04 17:13:12.455  1178  8163 I AnrManager:   0% 498/android.hardware.audio.service.mediatek: 0% user + 0% kernel / faults: 380 minor
08-04 17:13:12.455  1178  8163 I AnrManager:   2.6% 564/surfaceflinger: 1.4% user + 1.2% kernel / faults: 235 minor
08-04 17:13:12.455  1178  8163 I AnrManager:   2.3% 1949/com.android.launcher3: 1.5% user + 0.8% kernel / faults: 9566 minor 17 major
08-04 17:13:12.455  1178  8163 I AnrManager:   2.1% 1486/com.android.systemui: 1.3% user + 0.8% kernel / faults: 6981 minor 6 major
08-04 17:13:12.455  1178  8163 I AnrManager:   2% 817/vendor.mediatek.hardware.pq@2.2-service: 1% user + 0.9% kernel
08-04 17:13:12.455  1178  8163 I AnrManager:   1.9% 506/android.hardware.graphics.composer@2.1-service: 0.7% user + 1.2% kernel / faults: 1169 minor
08-04 17:13:12.455  1178  8163 I AnrManager:   1.7% 7168/adbd: 0.4% user + 1.2% kernel / faults: 1595 minor
08-04 17:13:12.455  1178  8163 I AnrManager:   0% 1459/usap64: 0% user + 0% kernel / faults: 14354 minor 81 major
08-04 17:13:12.455  1178  8163 I AnrManager:   1.1% 725/guardserver: 0.1% user + 1% kernel
08-04 17:13:12.455  1178  8163 I AnrManager:   1.1% 545/audioserver: 0.5% user + 0.6% kernel / faults: 70 minor
08-04 17:13:12.455  1178  8163 I AnrManager:   0.5% 8/rcu_preempt: 0% user + 0.5% kernel

//这里是综括，因为我们的是睡眠函数，所以CPU 使用率不高，也没有iowait，所以这里都正常
08-04 17:13:12.455  1178  8163 I AnrManager: 6.1% TOTAL: 3% user + 3% kernel + 0% iowait + 0% softirq

// 这里显示 1453:1453 apk 进程 到 系统进程 1178:2648  
08-04 17:13:12.460  1178  8163 I AnrManager: outgoing transaction from 1459:1459 to 1178:1923 start 5267.882075 android time 2021-08-04 17:12:54.332 spent time 14625 ms

3. 我们再来看ANR 文件

// 找到 main 中有 testOne 字符串的 main

"main" prio=5 tid=1 Native
  | group="main" sCount=1 dsCount=0 flags=1 obj=0x71c8a338 self=0xb4000071c2096c00
  | sysTid=1459 nice=-10 cgrp=default sched=0/0 handle=0x724959e4f8
  | state=S schedstat=( 471451233 9273919 175 ) utm=39 stm=7 core=6 HZ=100
  | stack=0x7fe813e000-0x7fe8140000 stackSize=8192KB
  | held mutexes=
  native: #00 pc 00000000000d7544  /apex/com.android.runtime/lib64/bionic/libc.so (__ioctl+4)
  native: #01 pc 0000000000093c38  /apex/com.android.runtime/lib64/bionic/libc.so (ioctl+160)
  native: #02 pc 0000000000051c6c  /system/lib64/libbinder.so (android::IPCThreadState::talkWithDriver(bool)+300)
  native: #03 pc 0000000000052c7c  /system/lib64/libbinder.so (android::IPCThreadState::waitForResponse(android::Parcel*, int*)+60)
  native: #04 pc 00000000000529e4  /system/lib64/libbinder.so (android::IPCThreadState::transact(int, unsigned int, android::Parcel const&, android::Parcel*, unsigned int)+188)
  native: #05 pc 000000000004b0b0  /system/lib64/libbinder.so (android::BpBinder::transact(unsigned int, android::Parcel const&, android::Parcel*, unsigned int)+152)
  native: #06 pc 00000000001254e0  /system/lib64/libandroid_runtime.so (android_os_BinderProxy_transact(_JNIEnv*, _jobject*, int, _jobject*, _jobject*, int)+152)
   // 一旦出现 transactNative ，就要看 服务端了
  at android.os.BinderProxy.transactNative(Native method)
  at android.os.BinderProxy.transact(BinderProxy.java:540)
  at android.yulong.querylist.IQueryList$Stub$Proxy.testOne(IQueryList.java:335)
  at com.coolpad.app.sdk.PhgCoolpadManager.testOne(PhgCoolpadManager.java:30)
  at com.coolos.testtwo.MainActivity$1.onClick(MainActivity.java:41)
  at android.view.View.performClick(View.java:7456)
  at android.view.View.performClickInternal(View.java:7429)
  at android.view.View.access$3600(View.java:814)
  at android.view.View$PerformClick.run(View.java:28526)
  at android.os.Handler.handleCallback(Handler.java:938)
  at android.os.Handler.dispatchMessage(Handler.java:99)
  at android.os.Looper.loop(Looper.java:244)
  at android.app.ActivityThread.main(ActivityThread.java:7806)
  at java.lang.reflect.Method.invoke(Native method)
  at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:612)
  at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:997)

// 从logcat 中可以看到 是1923 服务端线程
这个sysTid=1923 就是线程号

 这里表示是 Sleeping 
"Binder:1178_8" prio=5 tid=119 Sleeping
  | group="main" sCount=1 dsCount=0 flags=1 obj=0x14900d30 self=0xb40000713467d800
  | sysTid=1923 nice=-10 cgrp=default sched=0/0 handle=0x70e45fdcc0
  | state=S schedstat=( 5204780710 1516256968 8915 ) utm=327 stm=192 core=2 HZ=100
  | stack=0x70e4506000-0x70e4508000 stackSize=995KB
  | held mutexes=
  at java.lang.Thread.sleep(Native method)
  - sleeping on <0x0fc0565a> (a java.lang.Object)
  at java.lang.Thread.sleep(Thread.java:442)
  - locked <0x0fc0565a> (a java.lang.Object)
  at java.lang.Thread.sleep(Thread.java:358)
  at com.yulong.querylist.QueryListImpl.testOne(QueryListImpl.java:337)
  at android.yulong.querylist.IQueryList$Stub.onTransact(IQueryList.java:184)
  at android.os.Binder.execTransactInternal(Binder.java:1154)
  at android.os.Binder.execTransact(Binder.java:1123)

二. apk自己导致的ANR

1. 直接在 apk  button 的 onClick函数中设置了延迟函数，模拟ANR

buttton4.setOnClickListener(new View.OnClickListener() {
    @Override
    public void onClick(View v) {
        try {
            Thread.sleep(20000); // 延迟20s ，模拟ANR 
        } catch (Exception e){

        }
    }
});

2. 点击button， 会出现ANR，logcat 抓取的log如下

08-04 17:30:13.813  1178  8375 E ActivityManager: com.coolos.testtwo,Input dispatching timed out (ced7f6a com.coolos.testtwo/com.coolos.testtwo.MainActivity (server) is not responding. Waited 5002ms for FocusEvent(hasFocus=false))
08-04 17:30:13.815  1178  8375 E Junk    : {"eventTag":"ActivityManager","eventId":"0010021","eventMsg":"com.coolos.testtwo,Input dispatching timed out (ced7f6a com.coolos.testtwo\/com.coolos.testtwo.MainActivity (server) is not responding. Waited 5002ms for FocusEvent(hasFocus=false))","reserve2":"9e11c060c18d4e1e","eventTimestamp":"20210804173013","eventPkg":"com.coolos.testtwo"}
08-04 17:30:13.816  1178  8375 I AnrManager: startAnrDump
08-04 17:30:13.816  1178  8375 I AnrManager: isANRFlowSkipped() AnrFlow = 0
08-04 17:30:13.817  1178  8375 I AnrManager: enableTraceLog: false
08-04 17:30:13.828  1178  8376 I AnrManager: /sys/kernel/debug/binder/timeout_log isn't exist
08-04 17:30:13.865  1178  1693 I AnrManager: informMessageDump pid= 1462
08-04 17:30:13.871  1178  8375 I AnrManager: Android time :[2021-08-04 17:30:13.86] [6307.419]
08-04 17:30:13.871  1178  8375 I AnrManager: CPU usage from 1024938ms to 48ms ago (2021-08-04 17:13:08.931 to 2021-08-04 17:30:13.821):
08-04 17:30:13.871  1178  8375 I AnrManager:   1.7% 388/logd: 0.7% user + 1% kernel / faults: 40 minor
08-04 17:30:13.871  1178  8375 I AnrManager:   1.6% 297/kmemleak: 0% user + 1.6% kernel
08-04 17:30:13.871  1178  8375 I AnrManager:   1.2% 1178/system_server: 0.5% user + 0.6% kernel / faults: 36782 minor 382 major
08-04 17:30:13.871  1178  8375 I AnrManager:   0.4% 498/android.hardware.audio.service.mediatek: 0.4% user + 0% kernel / faults: 510 minor
08-04 17:30:13.871  1178  8375 I AnrManager:   0.3% 1486/com.android.systemui: 0.1% user + 0.1% kernel / faults: 22480 minor 152 major
08-04 17:30:13.871  1178  8375 I AnrManager:   0.2% 564/surfaceflinger: 0.1% user + 0.1% kernel / faults: 1406 minor
08-04 17:30:13.871  1178  8375 I AnrManager:   0.2% 7168/adbd: 0% user + 0.1% kernel / faults: 640 minor

// 负载不高
08-04 17:30:13.871  1178  8375 I AnrManager: 0.9% TOTAL: 0.3% user + 0.6% kernel + 0% iowait + 0% softirq

// 没有 outgoing transaction  log

2. ANR 文件

"main" prio=5 tid=1 Sleeping
  | group="main" sCount=1 dsCount=0 flags=1 obj=0x71c8a338 self=0xb4000071c2096c00
  | sysTid=1462 nice=0 cgrp=default sched=0/0 handle=0x724959e4f8
  | state=S schedstat=( 448196613 10582922 171 ) utm=37 stm=7 core=7 HZ=100
  | stack=0x7fe813e000-0x7fe8140000 stackSize=8192KB
  | held mutexes=
  at java.lang.Thread.sleep(Native method)
  - sleeping on <0x09704768> (a java.lang.Object)  // 这里直接显示 anr 原因了
  at java.lang.Thread.sleep(Thread.java:442)
  - locked <0x09704768> (a java.lang.Object)
  at java.lang.Thread.sleep(Thread.java:358)
  at com.coolos.testtwo.MainActivity$4.onClick(MainActivity.java:64)
  at android.view.View.performClick(View.java:7456)
  at android.view.View.performClickInternal(View.java:7429)
  at android.view.View.access$3600(View.java:814)
  at android.view.View$PerformClick.run(View.java:28526)
  at android.os.Handler.handleCallback(Handler.java:938)
  at android.os.Handler.dispatchMessage(Handler.java:99)
  at android.os.Looper.loop(Looper.java:244)
  at android.app.ActivityThread.main(ActivityThread.java:7806)
  at java.lang.reflect.Method.invoke(Native method)
  at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:612)
  at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:997)

一般发生ANR时，我们会向前 推 5s 来查找具体原因

Hogan_pei
关注
  • 0
    点赞
  • 踩
  • 1
    收藏
    觉得还不错? 一键收藏
  • 0
    评论
专栏目录
Android开发- Android ANR原因分析
记录和分享程序人生的点点滴滴
11-19 2166
ANR，全称是Application Not Responding，即应用程序无响应（不是Activity无响应）。当App在特定时间内无法响应屏幕触摸或键盘输入事件，或者特定事件没有处理完毕，就会发生ANR。
Android anr问题分析
纵容的博客
05-17 6271
本文主要介绍anr问题一手分析、分类判断，归类后提case给平台处理。 不是针对应用开发的anr分析和优化处理。anr问题主要分为1、input 无焦点anr Reason: Input dispatching timed out (Application does not have a focused window) 2、input 有焦点anr Reason: Input dispatching timed out (eadf99 net.zedge.android/net.zedge.android.
安卓ANR问题最全解析
最新发布
似霰的博客
07-03 1656
安卓ANR问题最全解析
debug时报anr错：Reason: Input dispatching timed out 解决办法
Azadoo的博客
12-02 4668
在排查问题对点击事件的监听debug时，突然遇到这种anr报错 大体意思是点击事件超过5s没有回应 系统报出anr错误并闪退，导致我无法debug查看问题。android Studio 输入下方命令行即可不闪退 正常debug操作。
Android 性能优化——ANR监控与解决
maniuT的博客
04-12 1963
ANR(Application Not responding):如果 Android 应用的界面线程处于阻塞状态的时间过长，会触发“应用无响应”(ANR) 错误。如果应用位于前台，系统会向用户显示一个对话框。ANR 对话框会为用户提供强制退出应用的选项。 ANR 是一个问题，因为负责更新界面的应用主线程无法处理用户输入事件或绘制操作，这会引起用户的不满
Android Application Not Responding原因以及解决方法(ANR)
Mr_GCH的博客
11-17 4121
一. ANR (Application Not Responding)是什么：　 ANR定义：在Android上，应用程序如果没有响应，系统会向用户显示一个对话框，这个对话框称作应用程序无响应（ANR：Application Not Responding）对话框。用户可以选择等待或者强制关闭。所以一个流畅的合理的应用程序中不能出现ANR，而让用户每次都要处理这个对话框。因此，在程序里对响应性能的设计很重要，这样系统不会显示ANR给用户。 二.ANR出现的原因： 按键或触摸事件 5s 内无响应； Broadc
Android ANR：Application Not Responding详解
liuzhenyu0619的专栏
04-12 530
一、ANR概述 ANR，全称为Application Not Responding，也就是应用程序无响应。 如果 Android 应用的界面线程处于阻塞状态的时间过长，就会触发“应用无响应”(ANR) 的错误。此时系统会向用户显示一个对话框，ANR 对话框会为用户提供强行退出应用的选项。 二、ANR的四种类型 在Android系统中，应用程序的响应由Activity Manager及Window Manager两个系统服务所监控。通常情况下，应用出现如下四类情况时，系统将报ANR： KeyDispa
效能智优ANR.rar
04-05
8. **自动化测试**：可能包含一套自动化测试框架，用于模拟用户操作，发现并预防ANR的发生。 压缩包中的“效能智优ANR.exe”可能是一个Windows平台下的安装程序，用于在开发环境中安装和配置这个工具。用户可以通过...
安卓日志分析崩溃拦截相关-防止应用程序奔溃抛出错误异常防止应用程序anr.rar
07-29
"安卓日志分析崩溃拦截相关-防止应用程序奔溃抛出错误异常防止应用程序anr.rar"这个资源包聚焦于一个核心问题：如何避免应用程序出现异常崩溃和ANR（Application Not Responding）错误。下面将详细解释相关知识点。 ...
Android-Log-Analyzer:一个简单的python脚本来分析Android ANR日志
07-06
app_marker_name = 全部或部分您的应用名称 - "com.example" key_word_agent = "您的代理日志中包含的一个词" key_word_device = "您的设备日志中包含的一个词（如果有）" key_word_anr = "您的 anr 中包含的一个词...
monkey测试分析
06-30
app_anr.log.rar"文件，通常是在应用发生ANR（Application Not Responding，即应用无响应）时生成的系统日志，它包含了应用挂起时的状态信息，包括主线程的堆栈跟踪、最近的 Binder 交易等，对于分析ANR原因非常有...
ANR 分析
wei11556的博客
04-05 4958
ANR分析
ANR 弹窗的显示原理
m0_64604466的博客
11-28 739
BroadCastQueue TimeOut: 未在规定时间内未处理完广播：前台广播 10s 内, 后台 60s 内 ContentProvider TimeOut:  publish 在 10s 内没有完成 Input Dispatching timeout:  5s 内未响应键盘输入、触摸屏幕等事件 ANR 的根本原因是：应用未在规定的时间内处理 AMS 指定的任务才会 ANR。 所以 Service 未在指定时间内执行完成而且非主进程的 Service 仍然需要通过..
Android 性能优化——ANR监控与解决_waited 5000ms for focusevent
2401_83974607的博客
04-09 785
进阶课程，基本涵盖了95%以上HarmonyOS鸿蒙开发知识点，真正体系化！**
Android ANR 分析解决方法
tianxiangshan的专栏
08-03 1448
一：什么是ANR ANR:Application Not Responding，即应用无响应 二：ANR的类型 ANR一般有三种类型： 1. KeyDispatchTimeout(5 seconds) --主要类型按键或触摸事件在特定时间内无响应 2. BroadcastTimeout(10 seconds) --BroadcastReceiver在特定时间内无法处理
Android和焦点相关的ANR
qq_41095045的博客
06-15 613
在android设备上和焦点相关的anr我认为有两种。
ANR原理及分析
热门推荐
Code1994的博客
09-27 1万+
ANR 基础知识 ANR（Application Not Responding）的监测原理本质上是消息机制，设定一个delay消息，超时未被移除则触发ANR。具体逻辑处理都在system server端，包括发送超时消息，移除超时消息，处理超时消息以及ANR弹框展示等；对于app而言，触发ANR的条件是主线程阻塞。 分类 很多情况下对应类型的ANR与正在执行的组件（ContentProvider、Broadcast、Service）本身没有关系。 Service ANR：前台20s，后台200s；s
ANR系列之二：Input类型ANR产生原理讲解
分享+记录
10-13 5640
本文主要分四大块进行介绍： 1.介绍ANR的判定逻辑（以输入事件为例）。 2.介绍发生ANR之后，ANR相关的信息是如何采集的。 3.介绍ANR的logcat和trace文件在系统中是何时记录生成，以及如何解读。 4.一些围绕ANR的扩展性问题讲解。
ANR Trace分析
06-08
ANR Trace分析是一种通过分析ANR错误日志（或称为ANR跟踪文件）来确定ANR错误的根本原因的方法。通过分析ANR跟踪文件，您可以了解应用程序中哪些线程阻塞了主线程，并确定导致线程阻塞的原因。 在Android设备上，您可以使用命令行工具 `adb shell dumpsys activity ANR` 来获取ANR错误日志。此命令将打印出最近的ANR错误日志，其中包含了主线程的堆栈跟踪信息、CPU使用情况、线程状态等信息。 下面是一个ANR错误日志的示例： ``` ANR in com.example.myapp PID: 1234 Reason: Input dispatching timed out (Waiting to send non-key event because the touched window has not finished processing certain input events that were delivered to it over 500.0ms ago. Waited for 1.0s.) Load: 98% CPU usage from 10000ms to 0ms ago: 60% com.example.myapp: 50% user + 9.7% kernel / faults: 250 minor 4 major 39% system_server: 14% user + 24% kernel / faults: 324 minor 3 major 0.1% com.android.systemui: 0% user + 0.1% kernel / faults: 17 minor 1 major 0% com.android.phone: 0% user + 0% kernel / faults: 11 minor 0% com.android.launcher: 0% user + 0% kernel / faults: 7 minor 0% kswapd0: 0% user + 0% kernel 0% kworker/u:1: 0% user + 0% kernel ``` 通过分析上面的ANR错误日志，您可以了解以下信息： - 应用程序包名为 com.example.myapp，进程ID为 1234。 - ANR出现的原因是输入事件分发超时，即应用程序在等待某个窗口处理输入事件时超时了。 - 应用程序的CPU负载达到了98%。 - 应用程序占用了60%的CPU时间。 - 系统服务 system_server 占用了39%的CPU时间。 - 其他进程的CPU使用率非常低。 通过分析这些信息，您可以确定ANR错误的原因，并尝试采取相应的措施来解决问题，比如将耗时操作移到后台线程中执行、优化代码、调整系统配置等。
评论
添加红包

请填写红包祝福语或标题

个

红包个数最小为10个

元

红包金额最低5元

当前余额3.43元 前往充值 >
需支付：10.00元
成就一亿技术人!
领取后你会自动成为博主和红包主的粉丝 规则
hope_wisdom
发出的红包
实付元
使用余额支付
点击重新获取
扫码支付
钱包余额 0

抵扣说明：

1.余额是钱包充值的虚拟货币，按照1:1的比例进行支付金额的抵扣。
2.余额无法直接购买下载，可以购买VIP、付费专栏及课程。

余额充值