iOS 性能优化 - TimeProfiler分析代码耗时

一: Time Profiler

Time Profiler用来分析代码的执行时间,主要用来分析CPU使用情况。
注意:要在release模式(或者自定义的其他的打包模式)下分析,原因在release模式下:

    1. 会开启编译器优化,提高代码运行效率
    1. 生成调试的符号表,这样才能够在profile的时候看到代码的调用栈
    1. 多数条件编译只会在debug模式下生效,release模式和生产环境是一样的。

二: 原理

Time Profiler每隔1ms会对线程的调用栈采样,然后用统计学的方式去做出分析。

image

图中虚线是采样点,最后统计出调用栈和对应函数出现的个数。

从图中不难看出,method3并没有出现在统计结果中,也就意味着方法运行的足够快的时候,很有可能统计不到,但这对于分析来说并不会有太大影响,因为运行快的方法往往不会引起性能问题。

Tips:Time Profiler并不会精确的统计出方法的执行时间,当线程处于挂起和等待执行的状态时候,timer profiler并不能统计到,它只能统计到真正在CPU上执行的。

三: Demo

Demo工程是一个简易的相册应用,故意写的很烂,包含了三个界面:

• 第一个界面只提供一个入口

• main界面用瀑布流的方式提供图片预览,图片被加了滤镜

• detail界面可以查看大图

image
image
image

在iPhone 6上运行,在进入main界面的时候会看到明显的卡顿:


image

我们通过Time Profile来找到原因:Product -> Profile

这会让XCode执行一次Release的build,然后启动Instrument

image

我们选择Time Profiler启动,然后正常操作App,采集到了数据后,停止Instrument。

image

对图中的几点说明:

  • 各个线程的采集数据汇总
  • 点击1中的某一行,可以看到采集到的堆栈,注意2区域的右上角,可以选择隐藏系统的符号
  • 可以选择关注的队列/CPU等。
  • 可以用pinch手势来放大缩小时间范围,鼠标可以拖动选择一段区域

Tips:
Weight表示占用全部的百分比
Self Wight表示当前方法执行占用的百分比,如果看到是0,表示当前方法其实不占用什么时间,时间都是子程序调用占用的。

我们用鼠标拖动,选择选择CPU占用较高的部分,可以看到堆栈如下:

image

Tips: 按住Option,然后鼠标左键点图中的箭头,可以快速展开。

从图中看到:大部分时间占用在-[MainController loadAllImages]这个方法:读取本地图片,然后对图加滤镜

- (void)loadAllImages{
    NSMutableArray * images = [NSMutableArray new];
    for (long i = 1; i < 40; i++) {
        NSString * imageName = [NSString stringWithFormat:@"image_%ld",i % 20 + 1];
        NSString * imagePath = [[NSBundle mainBundle] pathForResource:imageName ofType:@"jpeg"];
        UIImage * image = [UIImage imageWithContentsOfFile:imagePath];
        [images addObject:[self filterdImage:image]];
    }
    self.images = [images copy];
}

我们可以勾选以下选项,来看得更清楚一些:

  • Hide System Libraries 隐藏系统的库
  • Invert Call Tree 倒置函数队战
image

勾选后

image

Timer Profiler的基本debug逻辑:分析 -> 找到最大的占用函数 -> 修复 -> 继续分析…,直到完全修复。有时候自己的代码会引起系统代码卡顿,所以查看系统库的卡顿也很有必要的。

可以通过双击一行,进入源代码界面,看看具体某一行的占用情况:


image

也可以选择查看次数:


image

查看次数:

image

或者,查看反汇编

image

四: FAQ

为什么我在Time Profiler看不到类和方法的名称呢?

绝大部分原因是你的打包模式没有开启dSYM或者debug symbols

image
为什么明明我的App很卡,可是用Time Profiler分析却找不到相关代码?

卡顿的原因主要分为两大类:CPU瓶颈和GPU瓶颈

当界面有大量的shadow,mask或者有非常多的View/Layer,GPU渲染纹理和顶点的时候可能会有压力,这时候应该用Core Animation观察GPU的使用率。

CPU引起的卡顿大多可以通过Time Profiler找到,如果找不到可能的原因有两个:

  • 代码引起了大量系统调用,占用CPU时间,这种情况你需要仔细分析Time Profiler中的系统占用。
  • 频繁的锁和线程切换。因为线程被挂起的时候,time profiler无法采样到,这种情况可以通过System Trace分析。

摘自:黄文臣博客-TimeProfiler分析代码耗时
TimeProfilerdemo

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

推荐阅读更多精彩内容