ANR問題記錄

ANR問題解決思路

Android應(yīng)用ANR分析
Android ANR 分析
FastJNI導(dǎo)致的Android系統(tǒng)死機(jī)問題分析
Android ANR問題 -- Input超時(shí)實(shí)戰(zhàn)問題解析上

判斷某線程或服務(wù)是否在處于空閑狀態(tài):ANR分析

  • 術(shù)語
    nativePollOnce表示該線程的looper messagequeue中沒有消息唉侄,線程處于空閑狀態(tài)
    epoll_wait表示該線程的looper處于等待狀態(tài)和nativePollOnce對應(yīng)

獲取ANR文件

  • 在低系統(tǒng)版本籍滴,用
    db pull data/anr/traces.txt 就能獲取anr文件衍慎。
  • 在高系統(tǒng)版本需要使用adb bugreport pathName
    獲取anr文件,比如adb bugreport /Users/hongjunmin/Desktop/ANR
    將文件解壓后网严,可以在目錄bugreport-CPH1803-OPMXX/FS/data/anr下看到ANR文件
    有時(shí)候會(huì)發(fā)現(xiàn)這個(gè)文件夾下沒有最新的ANR信息,這時(shí)候去bugreport-bullhead-OPM/bugreport-bullhead-OPM.txt文件里搜 "VM TRACES AT LAST ANR" 往下翻就是這次ANR的trace信息了
    也可以通過開發(fā)者選項(xiàng)獲取并閱讀錯(cuò)誤報(bào)告

案例解析

案例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

第一反應(yīng)是查看DownloadService的生命周期函數(shù)onCreate和onStartCommand里面有沒有耗時(shí)操作嗤无,檢查完畢震束,并沒有,懵逼当犯。
然后我在ANR日志中觀察到如下數(shù)據(jù):

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):

發(fā)現(xiàn)我們的應(yīng)用myapp占用CPU達(dá)到了98%
這時(shí)候就有另一個(gè)思路垢村,即使DownloadService本身沒有耗時(shí)操作,但是如果有另一個(gè)任務(wù)或者線程頻繁占用CPU,這么最終會(huì)拖累DownloadService的執(zhí)行嚎卫,同樣會(huì)引發(fā)DownloadService的ANR嘉栓。
接著觀察到下面的數(shù)據(jù)


"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)

好,有點(diǎn)可疑拓诸,我就去定位(DownloadManager.java:1453)這行代碼侵佃,發(fā)現(xiàn)這行代碼是在一個(gè)while死循環(huán)里運(yùn)行

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

雖然while循環(huán)在非主線程運(yùn)行,但是會(huì)一直請求CPU占用奠支,所以這處代碼就像地雷馋辈,如何改正呢?很簡單倍谜,使用Thread.sleep即可:

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

結(jié)論:不僅僅是不要在主線程做耗時(shí)操作,并發(fā)線程過多尔崔,或者非主線程長時(shí)間占用CPU, 同樣會(huì)造成UI卡頓井联。

最后編輯于
?著作權(quán)歸作者所有,轉(zhuǎn)載或內(nèi)容合作請聯(lián)系作者
  • 序言:七十年代末,一起剝皮案震驚了整個(gè)濱河市您旁,隨后出現(xiàn)的幾起案子烙常,更是在濱河造成了極大的恐慌,老刑警劉巖鹤盒,帶你破解...
    沈念sama閱讀 218,941評論 6 508
  • 序言:濱河連續(xù)發(fā)生了三起死亡事件蚕脏,死亡現(xiàn)場離奇詭異,居然都是意外死亡侦锯,警方通過查閱死者的電腦和手機(jī)驼鞭,發(fā)現(xiàn)死者居然都...
    沈念sama閱讀 93,397評論 3 395
  • 文/潘曉璐 我一進(jìn)店門,熙熙樓的掌柜王于貴愁眉苦臉地迎上來尺碰,“玉大人挣棕,你說我怎么就攤上這事译隘。” “怎么了洛心?”我有些...
    開封第一講書人閱讀 165,345評論 0 356
  • 文/不壞的土叔 我叫張陵固耘,是天一觀的道長。 經(jīng)常有香客問我词身,道長厅目,這世上最難降的妖魔是什么? 我笑而不...
    開封第一講書人閱讀 58,851評論 1 295
  • 正文 為了忘掉前任法严,我火速辦了婚禮损敷,結(jié)果婚禮上,老公的妹妹穿的比我還像新娘深啤。我一直安慰自己拗馒,他們只是感情好,可當(dāng)我...
    茶點(diǎn)故事閱讀 67,868評論 6 392
  • 文/花漫 我一把揭開白布溯街。 她就那樣靜靜地躺著瘟忱,像睡著了一般。 火紅的嫁衣襯著肌膚如雪苫幢。 梳的紋絲不亂的頭發(fā)上访诱,一...
    開封第一講書人閱讀 51,688評論 1 305
  • 那天,我揣著相機(jī)與錄音韩肝,去河邊找鬼触菜。 笑死,一個(gè)胖子當(dāng)著我的面吹牛哀峻,可吹牛的內(nèi)容都是我干的涡相。 我是一名探鬼主播,決...
    沈念sama閱讀 40,414評論 3 418
  • 文/蒼蘭香墨 我猛地睜開眼剩蟀,長吁一口氣:“原來是場噩夢啊……” “哼催蝗!你這毒婦竟也來了?” 一聲冷哼從身側(cè)響起育特,我...
    開封第一講書人閱讀 39,319評論 0 276
  • 序言:老撾萬榮一對情侶失蹤丙号,失蹤者是張志新(化名)和其女友劉穎,沒想到半個(gè)月后缰冤,有當(dāng)?shù)厝嗽跇淞掷锇l(fā)現(xiàn)了一具尸體犬缨,經(jīng)...
    沈念sama閱讀 45,775評論 1 315
  • 正文 獨(dú)居荒郊野嶺守林人離奇死亡赤赊,尸身上長有42處帶血的膿包…… 初始之章·張勛 以下內(nèi)容為張勛視角 年9月15日...
    茶點(diǎn)故事閱讀 37,945評論 3 336
  • 正文 我和宋清朗相戀三年滨达,在試婚紗的時(shí)候發(fā)現(xiàn)自己被綠了录淡。 大學(xué)時(shí)的朋友給我發(fā)了我未婚夫和他白月光在一起吃飯的照片颤枪。...
    茶點(diǎn)故事閱讀 40,096評論 1 350
  • 序言:一個(gè)原本活蹦亂跳的男人離奇死亡,死狀恐怖嘿般,靈堂內(nèi)的尸體忽然破棺而出练链,到底是詐尸還是另有隱情携丁,我是刑警寧澤,帶...
    沈念sama閱讀 35,789評論 5 346
  • 正文 年R本政府宣布焚碌,位于F島的核電站畦攘,受9級特大地震影響,放射性物質(zhì)發(fā)生泄漏呐能。R本人自食惡果不足惜,卻給世界環(huán)境...
    茶點(diǎn)故事閱讀 41,437評論 3 331
  • 文/蒙蒙 一抑堡、第九天 我趴在偏房一處隱蔽的房頂上張望摆出。 院中可真熱鬧,春花似錦首妖、人聲如沸偎漫。這莊子的主人今日做“春日...
    開封第一講書人閱讀 31,993評論 0 22
  • 文/蒼蘭香墨 我抬頭看了看天上的太陽象踊。三九已至,卻和暖如春棚壁,著一層夾襖步出監(jiān)牢的瞬間杯矩,已是汗流浹背。 一陣腳步聲響...
    開封第一講書人閱讀 33,107評論 1 271
  • 我被黑心中介騙來泰國打工袖外, 沒想到剛下飛機(jī)就差點(diǎn)兒被人妖公主榨干…… 1. 我叫王不留史隆,地道東北人。 一個(gè)月前我還...
    沈念sama閱讀 48,308評論 3 372
  • 正文 我出身青樓曼验,卻偏偏與公主長得像泌射,于是被迫代替她去往敵國和親。 傳聞我的和親對象是個(gè)殘疾皇子鬓照,可洞房花燭夜當(dāng)晚...
    茶點(diǎn)故事閱讀 45,037評論 2 355

推薦閱讀更多精彩內(nèi)容