騰訊 Apm 框架 Matrix 源碼閱讀 - TracePlugin 之 AnrTracer

版本

v0.6.5

溫馨提示

  1. 在讀這篇文章之前墻裂建議先讀騰訊 Apm 框架 Matrix 源碼閱讀 - TracePlugin 架構(gòu)解析
  2. TracePlugin 是比較復(fù)雜的吸耿,很多東西文章中可能講的不是很清楚,配合 推薦 Matrix 源碼完整注釋
    可能會有更好的效果

概述

本篇文章是 騰訊開源的 APM 框架 Matrix 系列文章的第四篇,將對matrix-trace-canary這個模塊種的StartupTracer類進行解析。這個類主要監(jiān)控并上報App 冷/暖啟動時間哄尔,Activity啟動時間。上一篇為騰訊 Apm 框架 Matrix 源碼閱讀 - TracePlugin 之 StartupTracer

原理簡介

通過 UIThreadMonitor 感知Looper loop工作的開始乌企,刷新幀晌该,結(jié)束的時間,并在結(jié)束時分析是否超過閾值弥臼,如果超過就從AppMethodBeat中獲取相關(guān)數(shù)據(jù)進行分析并上報宴咧。

1. AnrTracer.生命周期方法

    public AnrTracer(TraceConfig traceConfig) {
        this.traceConfig = traceConfig;
        this.isAnrTraceEnable = traceConfig.isAnrTraceEnable();
    }

    @Override
    public void onAlive() {
        super.onAlive();
        if (isAnrTraceEnable) {
            //添加 LooperObserver 監(jiān)聽 詳見【1.1】
            UIThreadMonitor.getMonitor().addObserver(this);
            //子線程handler
            this.anrHandler = new Handler(MatrixHandlerThread.getDefaultHandler().getLooper());
        }
    }

    @Override
    public void onDead() {
        super.onDead();
        if (isAnrTraceEnable) {
            //移除 LooperObserver 監(jiān)聽
            UIThreadMonitor.getMonitor().removeObserver(this);
            if (null != anrTask) {
                //釋放 BeginRecord
                anrTask.getBeginRecord().release();
            }
            //anrHandler移除所有消息并退出
            anrHandler.removeCallbacksAndMessages(null);
            anrHandler.getLooper().quit();
        }
    }

首先構(gòu)造方法也是讀取配置并記錄起來,onAlive()方法注冊了LooperObserver監(jiān)聽醋火,初始化了子線程handler anrHandler悠汽,onDead()中移除 LooperObserver 監(jiān)聽,清空anrHandler消息并退出

1.1 AnrTracer.dispatchBegin

AnrTracer注冊了 LooperObserver 監(jiān)聽 所以會分別回調(diào)它里面的 dispatchBegin,doFrame,dispatchEnd方法芥驳,因為AnrTracer.doFrame并沒有什么實質(zhì)性的作用所以下面我們就對dispatchBegin,dispatchEnd這兩個方法進行分析柿冲。

    public void dispatchBegin(long beginMs, long cpuBeginMs, long token) {
        super.dispatchBegin(beginMs, cpuBeginMs, token);
        //創(chuàng)建 AnrHandleTask
        anrTask = new AnrHandleTask(AppMethodBeat.getInstance().maskIndex("AnrTracer#dispatchBegin"), token);
        if (traceConfig.isDevEnv()) {
            MatrixLog.v(TAG, "* [dispatchBegin] token:%s index:%s", token, anrTask.beginRecord.index);
        }
        //將anrTask加入到anrHandler的延時隊列中,如果超過5s anrTask還沒有被移除就會被執(zhí)行
        anrHandler.postDelayed(anrTask, Constants.DEFAULT_ANR - (SystemClock.uptimeMillis() - token));
    }

該方法主要作用是 創(chuàng)建anrTask并加入到anrHandler的延時隊列中

1.2 AnrTracer.dispatchEnd

     public void dispatchEnd(long beginMs, long cpuBeginMs, long endMs, long cpuEndMs, long token, boolean isBelongFrame) {
        super.dispatchEnd(beginMs, cpuBeginMs, endMs, cpuEndMs, token, isBelongFrame);
        if (traceConfig.isDevEnv()) {
            MatrixLog.v(TAG, "[dispatchEnd] token:%s cost:%sms cpu:%sms usage:%s",
                    token, endMs - beginMs, cpuEndMs - cpuBeginMs, Utils.calculateCpuUsage(cpuEndMs - cpuBeginMs, endMs - beginMs));
        }
        if (null != anrTask) {
            //將anrTask從anrHandler的延時隊列中移除
            anrTask.getBeginRecord().release();
            anrHandler.removeCallbacks(anrTask);
        }
    }

這個方法就是將anrTask從延時隊列中移除兆旬。如果及時移除了就不會進行任何操作假抄,如果超過5s還沒有移除就會被Matrix判定為自定義的ANR,這個時候就會走到anrTask.run方法丽猬。

1.3 AnrHandleTask.run

  public void run() {
            //當(dāng)前時間
            long curTime = SystemClock.uptimeMillis();
            //app 是否處于前臺
            boolean isForeground = isForeground();
            // process 優(yōu)先級
            int[] processStat = Utils.getProcessPriority(Process.myPid());
            //獲取需要分析的方法棧信息
            long[] data = AppMethodBeat.getInstance().copyData(beginRecord);
            //釋放 beginRecord
            beginRecord.release();
            //當(dāng)前可見activity
            String scene = AppMethodBeat.getVisibleScene();

            // memory
            long[] memoryInfo = dumpMemory();

            // 線程狀態(tài)
            Thread.State status = Looper.getMainLooper().getThread().getState();
            //堆棧信息
            StackTraceElement[] stackTrace = Looper.getMainLooper().getThread().getStackTrace();
            String dumpStack = Utils.getStack(stackTrace, "|*\t\t", 12);

            // 通過token(dispatchStart時間)獲取不同Type 的耗費時間
            UIThreadMonitor monitor = UIThreadMonitor.getMonitor();
            long inputCost = monitor.getQueueCost(UIThreadMonitor.CALLBACK_INPUT, token);
            long animationCost = monitor.getQueueCost(UIThreadMonitor.CALLBACK_ANIMATION, token);
            long traversalCost = monitor.getQueueCost(UIThreadMonitor.CALLBACK_TRAVERSAL, token);

            // trace
            LinkedList<MethodItem> stack = new LinkedList();
            if (data.length > 0) {
                // 根據(jù)之前 data 查到的 methodId 宿饱,拿到對應(yīng)插樁函數(shù)的執(zhí)行時間、執(zhí)行深度脚祟,將每個函數(shù)的信息封裝成 MethodItem谬以,然后存儲到 stack 鏈表當(dāng)中
                TraceDataUtils.structuredDataToStack(data, stack, true, curTime);
                //根據(jù)規(guī)則 裁剪 stack 中的數(shù)據(jù),
                TraceDataUtils.trimStack(stack, Constants.TARGET_EVIL_METHOD_STACK, new TraceDataUtils.IStructuredDataFilter() {
                    @Override
                    public boolean isFilter(long during, int filterCount) {
                        return during < filterCount * Constants.TIME_UPDATE_CYCLE_MS;
                    }

                    @Override
                    public int getFilterMaxCount() {
                        return Constants.FILTER_STACK_MAX_COUNT;
                    }

                    @Override
                    public void fallback(List<MethodItem> stack, int size) {
                        MatrixLog.w(TAG, "[fallback] size:%s targetSize:%s stack:%s", size, Constants.TARGET_EVIL_METHOD_STACK, stack);
                        Iterator iterator = stack.listIterator(Math.min(size, Constants.TARGET_EVIL_METHOD_STACK));
                        while (iterator.hasNext()) {
                            iterator.next();
                            iterator.remove();
                        }
                    }
                });
            }

            StringBuilder reportBuilder = new StringBuilder();
            StringBuilder logcatBuilder = new StringBuilder();
            //獲取最大的耗時時間
            long stackCost = Math.max(Constants.DEFAULT_ANR, TraceDataUtils.stackToString(stack, reportBuilder, logcatBuilder));

            // 查詢出最耗時的 方法id
            String stackKey = TraceDataUtils.getTreeKey(stack, stackCost);
            MatrixLog.w(TAG, "%s \npostTime:%s curTime:%s",
                    printAnr(scene, processStat, memoryInfo, status, logcatBuilder, isForeground, stack.size(),
                            stackKey, dumpStack, inputCost, animationCost, traversalCost, stackCost), token, curTime); // for logcat

            //異常情況判斷(當(dāng) AnrHandleTask 沒有及時執(zhí)行時會發(fā)生)
            if (stackCost >= Constants.DEFAULT_ANR_INVALID) {
                MatrixLog.w(TAG, "The checked anr task was not executed on time. "
                        + "The possible reason is that the current process has a low priority. just pass this report");
                return;
            }
            // report
            try {
                TracePlugin plugin = Matrix.with().getPluginByClass(TracePlugin.class);
                if (null == plugin) {
                    return;
                }
                JSONObject jsonObject = new JSONObject();
                jsonObject = DeviceUtil.getDeviceInfo(jsonObject, Matrix.with().getApplication());
                jsonObject.put(SharePluginInfo.ISSUE_STACK_TYPE, Constants.Type.ANR);
                jsonObject.put(SharePluginInfo.ISSUE_COST, stackCost);
                jsonObject.put(SharePluginInfo.ISSUE_STACK_KEY, stackKey);
                jsonObject.put(SharePluginInfo.ISSUE_SCENE, scene);
                jsonObject.put(SharePluginInfo.ISSUE_TRACE_STACK, reportBuilder.toString());
                jsonObject.put(SharePluginInfo.ISSUE_THREAD_STACK, Utils.getStack(stackTrace));
                jsonObject.put(SharePluginInfo.ISSUE_PROCESS_PRIORITY, processStat[0]);
                jsonObject.put(SharePluginInfo.ISSUE_PROCESS_NICE, processStat[1]);
                jsonObject.put(SharePluginInfo.ISSUE_PROCESS_FOREGROUND, isForeground);
                // memory info
                JSONObject memJsonObject = new JSONObject();
                memJsonObject.put(SharePluginInfo.ISSUE_MEMORY_DALVIK, memoryInfo[0]);
                memJsonObject.put(SharePluginInfo.ISSUE_MEMORY_NATIVE, memoryInfo[1]);
                memJsonObject.put(SharePluginInfo.ISSUE_MEMORY_VM_SIZE, memoryInfo[2]);
                jsonObject.put(SharePluginInfo.ISSUE_MEMORY, memJsonObject);

                Issue issue = new Issue();
                issue.setKey(token + "");
                issue.setTag(SharePluginInfo.TAG_PLUGIN_EVIL_METHOD);
                issue.setContent(jsonObject);
                plugin.onDetectIssue(issue);

            } catch (JSONException e) {
                MatrixLog.e(TAG, "[JSONException error: %s", e);
            }

        }

這個方法就完成了從AppMethodBeat中獲取數(shù)據(jù)在進行整理由桌,裁剪为黎,組建長json后進行上報的工作邮丰。

AnrTracer 上報數(shù)據(jù)解析

tag: Trace_EvilMethod
key:token(dispatchStart的時間)

detail:固定為ANR
cost:總耗時
usage:主線程cpu占用率
scene:當(dāng)前可見Activity名稱
stack:方法棧信息, 每個item之間用“\n”隔開铭乾,每個item的含義為剪廉,調(diào)用深度,methodId炕檩,調(diào)用次數(shù)斗蒋,耗時
    * 比如:0,118,1,5 -> 調(diào)用深度為0,methodId=118笛质,調(diào)用次數(shù)=1泉沾,耗時5ms
stackKey:主要耗時方法 的methodId
threadStack:堆棧信息
processPriority:動態(tài)線程優(yōu)先級
processNice:(靜態(tài)線程優(yōu)先級)
isProcessForeground:是否是后臺線程
memory:內(nèi)存情況包含如下三部分
    dalvik_heap:dalvik已使用內(nèi)存大小(KB)
    native_heap:native已使用內(nèi)存大芯伞(KB)
    vm_size:虛擬內(nèi)存總大小

系列文章

參考資料

最后編輯于
?著作權(quán)歸作者所有,轉(zhuǎn)載或內(nèi)容合作請聯(lián)系作者
  • 序言:七十年代末爆哑,一起剝皮案震驚了整個濱河市,隨后出現(xiàn)的幾起案子舆吮,更是在濱河造成了極大的恐慌揭朝,老刑警劉巖,帶你破解...
    沈念sama閱讀 221,888評論 6 515
  • 序言:濱河連續(xù)發(fā)生了三起死亡事件色冀,死亡現(xiàn)場離奇詭異潭袱,居然都是意外死亡,警方通過查閱死者的電腦和手機锋恬,發(fā)現(xiàn)死者居然都...
    沈念sama閱讀 94,677評論 3 399
  • 文/潘曉璐 我一進店門屯换,熙熙樓的掌柜王于貴愁眉苦臉地迎上來,“玉大人与学,你說我怎么就攤上這事彤悔。” “怎么了索守?”我有些...
    開封第一講書人閱讀 168,386評論 0 360
  • 文/不壞的土叔 我叫張陵晕窑,是天一觀的道長。 經(jīng)常有香客問我卵佛,道長杨赤,這世上最難降的妖魔是什么? 我笑而不...
    開封第一講書人閱讀 59,726評論 1 297
  • 正文 為了忘掉前任截汪,我火速辦了婚禮疾牲,結(jié)果婚禮上,老公的妹妹穿的比我還像新娘衙解。我一直安慰自己阳柔,他們只是感情好,可當(dāng)我...
    茶點故事閱讀 68,729評論 6 397
  • 文/花漫 我一把揭開白布蚓峦。 她就那樣靜靜地躺著盔沫,像睡著了一般医咨。 火紅的嫁衣襯著肌膚如雪。 梳的紋絲不亂的頭發(fā)上架诞,一...
    開封第一講書人閱讀 52,337評論 1 310
  • 那天,我揣著相機與錄音干茉,去河邊找鬼谴忧。 笑死,一個胖子當(dāng)著我的面吹牛角虫,可吹牛的內(nèi)容都是我干的沾谓。 我是一名探鬼主播,決...
    沈念sama閱讀 40,902評論 3 421
  • 文/蒼蘭香墨 我猛地睜開眼戳鹅,長吁一口氣:“原來是場噩夢啊……” “哼均驶!你這毒婦竟也來了?” 一聲冷哼從身側(cè)響起枫虏,我...
    開封第一講書人閱讀 39,807評論 0 276
  • 序言:老撾萬榮一對情侶失蹤妇穴,失蹤者是張志新(化名)和其女友劉穎,沒想到半個月后隶债,有當(dāng)?shù)厝嗽跇淞掷锇l(fā)現(xiàn)了一具尸體腾它,經(jīng)...
    沈念sama閱讀 46,349評論 1 318
  • 正文 獨居荒郊野嶺守林人離奇死亡,尸身上長有42處帶血的膿包…… 初始之章·張勛 以下內(nèi)容為張勛視角 年9月15日...
    茶點故事閱讀 38,439評論 3 340
  • 正文 我和宋清朗相戀三年死讹,在試婚紗的時候發(fā)現(xiàn)自己被綠了瞒滴。 大學(xué)時的朋友給我發(fā)了我未婚夫和他白月光在一起吃飯的照片。...
    茶點故事閱讀 40,567評論 1 352
  • 序言:一個原本活蹦亂跳的男人離奇死亡赞警,死狀恐怖妓忍,靈堂內(nèi)的尸體忽然破棺而出,到底是詐尸還是另有隱情愧旦,我是刑警寧澤世剖,帶...
    沈念sama閱讀 36,242評論 5 350
  • 正文 年R本政府宣布,位于F島的核電站忘瓦,受9級特大地震影響搁廓,放射性物質(zhì)發(fā)生泄漏。R本人自食惡果不足惜耕皮,卻給世界環(huán)境...
    茶點故事閱讀 41,933評論 3 334
  • 文/蒙蒙 一境蜕、第九天 我趴在偏房一處隱蔽的房頂上張望。 院中可真熱鬧凌停,春花似錦粱年、人聲如沸。這莊子的主人今日做“春日...
    開封第一講書人閱讀 32,420評論 0 24
  • 文/蒼蘭香墨 我抬頭看了看天上的太陽完箩。三九已至,卻和暖如春拉队,著一層夾襖步出監(jiān)牢的瞬間弊知,已是汗流浹背。 一陣腳步聲響...
    開封第一講書人閱讀 33,531評論 1 272
  • 我被黑心中介騙來泰國打工粱快, 沒想到剛下飛機就差點兒被人妖公主榨干…… 1. 我叫王不留秩彤,地道東北人。 一個月前我還...
    沈念sama閱讀 48,995評論 3 377
  • 正文 我出身青樓事哭,卻偏偏與公主長得像漫雷,于是被迫代替她去往敵國和親。 傳聞我的和親對象是個殘疾皇子鳍咱,可洞房花燭夜當(dāng)晚...
    茶點故事閱讀 45,585評論 2 359

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