自动检测性能问题 - BlockCanary 原理解析

原文链接:http://www.woaitqs.cc/2016/09/01/check-performance.html

大家应该对 LeakCanary 比较熟悉,这个 Square 公司推出的自动检测内存泄露的工具,给开发人员带来了极大的便利。而国人也开发出一个用于自动检测性能卡顿的工具,BlockCanary, 今天将从原理上分析 BlockCanary 是如何实现的。

performance.png

怎么定义卡顿

介绍原理前,首先得知道什么是卡顿了?这就得引入视觉暂留这个概念。我们在看电影时,电影不是动态的,而是由一幕一幕播放完成的,但一般情况下,我们不会觉得卡顿,这就是视觉暂留的功效。

视觉暂留是光对视网膜所产生的视觉在光停止作用后,仍保留一段时间的现象,其具体应用是电影的拍摄和放映。原因是由视神经的反应速度造成的,其时值是二十四分之一秒。这也是是动画、电影等视觉媒体形成和传播的根据。

简单地说,如果相隔的两帧间隔不超过二十四分之一秒, 人是无法感知的。对于 Android 手机而言,如果屏幕之间的变化时间足够短,那我们就不会觉得卡顿。

Android 系统在 Kitkat 之后,也引入了黄油计划,这个计划目的在于优化 Android 的各种性能体验。在这其中,最重要的功能是引入了 VSYNC。VSYNC(垂直同步信号) 会按照 60 FPS/second 的频率,也就是差不多 16.67ms 的间隔,进行一次信号发送,而 Android 的绘制机制会根据这个信号来进行相应的处理。如果一次页面渲染未能在 16.67ms 内完成,那么界面就不会发生改变,只能等待下一次 VSYNC 信号,然而这样就会照成卡顿。

所以,结合上面粗略的知识介绍,卡顿可以在一定程度上,通过统计两帧之间间隔大于 16.67ms 的次数,来进行定量的衡量。

关于 Vsync 的更多知识,以及黄油计划的信息,可以参考这个链接 http://www.androidpolice.com/2012/07/12/getting-to-know-android-4-1-part-3-project-butter-how-it-works-and-what-it-added/

Looper 提供的机制

先看看我们熟悉的 Looper 的源码,里面实现的功能就是不断地从 MessageQueue 里面取出 Message 对象,并加以执行。

for (;;) {
    Message msg = queue.next(); // might block
    if (msg == null) {
        // No message indicates that the message queue is quitting.
        return;
    }

    // This must be in a local variable, in case a UI event sets the logger
    Printer logging = me.mLogging;
    if (logging != null) {
        logging.println(">>>>> Dispatching to " + msg.target + " " +
                msg.callback + ": " + msg.what);
    }

    msg.target.dispatchMessage(msg);

    if (logging != null) {
        logging.println("<<<<< Finished to " + msg.target + " " + msg.callback);
    }

    // ignore some code...

    msg.recycleUnchecked();
}

注意到,在 dispatchMessage 的前后,分别有两个 log 的输出事件,而 dispatchMessage 就是线程上的一次消息处理。如果两次消息处理事件,都超过了 16.67ms, 那就一定发生了卡顿,这也是 BlockCanary 的基础原理。

BlockCanary 实现了 Printer,我们看看具体的实现。

class LooperMonitor implements Printer {
  @Override
  public void println(String x) {
      if (!mPrintingStarted) {
          mStartTimestamp = System.currentTimeMillis();
          mStartThreadTimestamp = SystemClock.currentThreadTimeMillis();
          mPrintingStarted = true;
          startDump();
      } else {
          final long endTime = System.currentTimeMillis();
          mPrintingStarted = false;
          if (isBlock(endTime)) {
              notifyBlockEvent(endTime);
          }
          stopDump();
      }
  }

  private boolean isBlock(long endTime) {
      return endTime - mStartTimestamp > mBlockThresholdMillis;
  }

  // ignore other codes...
}

这里实现了 println 方法,而 Looper 中也调用了 println 方法,而且在除非 dump 日志的情况下,也只有在事件消息前后进行 println 操作。换而言之,我们可以初步认为两个 println 调用之间的时间超过 16.67ms 就证明了卡顿。上面的代码也非常地清晰明了说明了这点。

输出堆栈

在发现卡顿时,还需要提供当前线程的堆栈,这样才能方便开发人员知晓在哪里发生了卡顿,而 Java 刚好也提供了类似的机制,代码也非常的简单。

@Override
protected void doSample() {
    StringBuilder stringBuilder = new StringBuilder();

    for (StackTraceElement stackTraceElement : mCurrentThread.getStackTrace()) {
        stringBuilder
                .append(stackTraceElement.toString())
                .append(BlockInfo.SEPARATOR);
    }

    synchronized (sStackMap) {
        if (sStackMap.size() == mMaxEntryCount && mMaxEntryCount > 0) {
            sStackMap.remove(sStackMap.keySet().iterator().next());
        }
        sStackMap.put(System.currentTimeMillis(), stringBuilder.toString());
    }
}

BlockCanary 的原理简介就到这里,还是比较轻松和简单的。

Choreographer

最近在腾讯的博文中,也看到了另一种更为精确的方案,这里做简单介绍。方案上采用了同样来自黄油计划的 Choreographer。

在其源码中,有这样的一个接口 FrameCallback.

public interface FrameCallback {

    // 每帧绘制的时候的回调
    public void doFrame(long frameTimeNanos);
}
public void postFrameCallback(FrameCallback callback) {
    postFrameCallbackDelayed(callback, 0);
}

如上,Choreographer 提供了注入 FrameCallback 的入口。我们只需要对两次 doFrame 的时间间隔进行计算,超过 16.67ms 就算卡顿。

但根据作者的统计,这种方法比较难拿到堆栈消息,只适合在线上进行量化的统计,不方便进行卡顿原因的分析。


文档信息


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

推荐阅读更多精彩内容