性能分析工具——os_signpost

1. os_signpost是什么

signpost這個(gè)單詞是路標(biāo)搜囱、指示牌的意思,比如距離北京還有158km。顧名思義溃槐,os_signpost就是在代碼里面插入一些標(biāo)記,作用相當(dāng)于路標(biāo)科吭。官方文檔是這樣描述的:

The os_signpost APIs let clients add lightweight instrumentation to
code for collection and visualization by performance analysis tooling.

用人話說就是:os_signpost是一個(gè)輕量級(jí)的可視化的性能分析工具昏滴。

2. os_signpost的提出背景

代碼層面的性能分析,最直觀的方式就是標(biāo)識(shí)出一段代碼的開始和結(jié)尾对人,然后計(jì)算下耗時(shí)谣殊。就像下面這樣:

CFTimeInterval begin = CACurrentMediaTime();
// do something 
CFTimeInterval end = CACurrentMediaTime();
NSLog(@"cost = %@",(end - begin));

然而如果代碼邏輯復(fù)雜、有先后關(guān)系牺弄、存在多個(gè)線程等姻几,單靠某小段代碼的標(biāo)記,就不是那么直觀了。

于是蛇捌,在2018年9月抚恒,蘋果推出了os_signpost,它可以配合Instruments络拌,顯示可視化的效果柑爸,WWDC視頻Measuring Performance Using Logging有介紹。因?yàn)槭?8年才推出盒音,所以os_signpost只支持iOS12及以上系統(tǒng),Xcode10及更高版本馅而。

3. os_signpost的用法

這里以O(shè)C代碼為例祥诽。

(1) 先導(dǎo)入頭文件,為了使用方便瓮恭,再定義兩個(gè)宏:
#include <os/signpost.h>

#define INNER_BEGIN_LOG(subsystem, category, name) \
os_log_t m_log_##name = os_log_create((#subsystem), (#category));\
os_signpost_id_t m_spid_##name = os_signpost_id_generate(m_log_##name);\
os_signpost_interval_begin(m_log_##name, m_spid_##name, (#name));

#define INNER_END_LOG(name) \
os_signpost_interval_end(m_log_##name, m_spid_##name, (#name));

這兩個(gè)宏是成對(duì)使用的雄坪,這段代碼可以放公共的頭文件里,方便不同的地方用屯蹦。

(2)給代碼插入路標(biāo):

頁面初始化的時(shí)候插入名字為init的標(biāo)記维哈,
viewDidAppear的時(shí)候插入名字為viewDidAppear的標(biāo)記,
接口請(qǐng)求回調(diào)時(shí)插入名字為requestDidCompleted的標(biāo)記登澜,
數(shù)據(jù)處理完插入名字為requestProcessComplete的標(biāo)記阔挠。

- (id)init {
    self = [super init];
    if (self) {
        if (@available(iOS 12.0, *)) {
            INNER_BEGIN_LOG(fourPage, init, init);
            INNER_END_LOG(init);
        }
        // do something
    }
    return self;
}

- (void)viewDidAppear:(BOOL)animated {
    [super viewDidAppear:animated];
    // do something
    if (@available(iOS 12.0, *)) {
        INNER_BEGIN_LOG(fourPage, init, viewDidAppear);
        INNER_END_LOG(viewDidAppear);
    }
}

- (void)requestDidCompleted {
    if (@available(iOS 12.0, *)) {
        INNER_BEGIN_LOG(fourPage, init, requestDidCompleted);
        INNER_END_LOG(requestDidCompleted);
    }
    // do something
    if (@available(iOS 12.0, *)) {
        INNER_BEGIN_LOG(fourPage, init, requestProcessComplete);
        INNER_END_LOG(requestProcessComplete);
    }
}
(3)工程設(shè)置

Debug Information Format設(shè)置為DWARF with dSYM File,如下圖所示:

工程設(shè)置.png

這一步是為了后面在Instruments里面能直接看到代碼脑蠕。
現(xiàn)在就可以插上手機(jī)跑起來了购撼。

(4)配合Instruments

打開Instruments的Time Profiler,默認(rèn)會(huì)有一行Points of Interest谴仙,這里用不到迂求,可以把它刪了,選中再按刪除鍵即可晃跺。然后添加os_signpost揩局,如下圖所示:
添加os_signpost.png

把這個(gè)模板保存起來,點(diǎn)上面的File->Save As Template...掀虎,輸入名字signpost凌盯。

下次就可以直接從Instruments里打開這個(gè)自定義模板了:
自定義模板.png

選擇剛剛跑的app,點(diǎn)左上角的紅色按鈕開始錄制涩盾,這時(shí)候界面顯示Recording十气,操作完了再次點(diǎn)左上角按鈕結(jié)束錄制,然后進(jìn)入下面這樣的界面:
Instruments_1.png

剛錄制完可能看不到剛剛插入的那些標(biāo)記春霍,只要在上圖中紅線位置往下拖砸西,把界面展開就看到了,如下圖所示。左邊紅框就是添加的4個(gè)標(biāo)記芹枷,右邊紅框里的豎線是相應(yīng)的時(shí)間衅疙。把鼠標(biāo)放在右邊紅框位置,滾動(dòng)滾輪鸳慈,可以縮放時(shí)間軸饱溢,筆記本用戶可以用觸摸板縮放。
Instruments_2.png

時(shí)間軸放大后可以清晰地看到走芋,從init到viewDidApper整個(gè)過程耗時(shí)840ms绩郎,其中接口請(qǐng)求耗時(shí)405ms,接口解析耗時(shí)35ms翁逞,頁面渲染耗時(shí)400ms肋杖,還可以看到這段時(shí)間主線程壓力很大。

到這就可以分析一下具體哪些代碼耗時(shí)了挖函。在下圖1位置選Samples状植,就是對(duì)代碼采樣,在2位置消耗CPU較嚴(yán)重的區(qū)域點(diǎn)擊怨喘,然后在3位置就可以看到該位置對(duì)應(yīng)的代碼津畸,如果前面Debug Information Format沒有設(shè)置的話,這里是看不到代碼的必怜。
Instruments_3.png

以我的代碼為例肉拓,可以計(jì)算出onlineChatBtn加載圖片用了48ms,而它的父視圖初始化用了305ms梳庆,這些都是在主線程執(zhí)行的帝簇。

4. 小結(jié)

通過以上這些操作,我們能夠看到兩個(gè)標(biāo)記之間具體執(zhí)行了哪些代碼靠益,以及哪些代碼比較耗時(shí)丧肴,進(jìn)而可以有針對(duì)性地進(jìn)行優(yōu)化。

最后編輯于
?著作權(quán)歸作者所有,轉(zhuǎn)載或內(nèi)容合作請(qǐng)聯(lián)系作者
  • 序言:七十年代末胧后,一起剝皮案震驚了整個(gè)濱河市芋浮,隨后出現(xiàn)的幾起案子,更是在濱河造成了極大的恐慌壳快,老刑警劉巖纸巷,帶你破解...
    沈念sama閱讀 206,214評(píng)論 6 481
  • 序言:濱河連續(xù)發(fā)生了三起死亡事件,死亡現(xiàn)場(chǎng)離奇詭異眶痰,居然都是意外死亡瘤旨,警方通過查閱死者的電腦和手機(jī),發(fā)現(xiàn)死者居然都...
    沈念sama閱讀 88,307評(píng)論 2 382
  • 文/潘曉璐 我一進(jìn)店門竖伯,熙熙樓的掌柜王于貴愁眉苦臉地迎上來存哲,“玉大人因宇,你說我怎么就攤上這事∷钔担” “怎么了察滑?”我有些...
    開封第一講書人閱讀 152,543評(píng)論 0 341
  • 文/不壞的土叔 我叫張陵,是天一觀的道長(zhǎng)修肠。 經(jīng)常有香客問我贺辰,道長(zhǎng),這世上最難降的妖魔是什么嵌施? 我笑而不...
    開封第一講書人閱讀 55,221評(píng)論 1 279
  • 正文 為了忘掉前任饲化,我火速辦了婚禮,結(jié)果婚禮上吗伤,老公的妹妹穿的比我還像新娘滓侍。我一直安慰自己,他們只是感情好牲芋,可當(dāng)我...
    茶點(diǎn)故事閱讀 64,224評(píng)論 5 371
  • 文/花漫 我一把揭開白布。 她就那樣靜靜地躺著捺球,像睡著了一般缸浦。 火紅的嫁衣襯著肌膚如雪。 梳的紋絲不亂的頭發(fā)上氮兵,一...
    開封第一講書人閱讀 49,007評(píng)論 1 284
  • 那天裂逐,我揣著相機(jī)與錄音,去河邊找鬼泣栈。 笑死卜高,一個(gè)胖子當(dāng)著我的面吹牛,可吹牛的內(nèi)容都是我干的南片。 我是一名探鬼主播掺涛,決...
    沈念sama閱讀 38,313評(píng)論 3 399
  • 文/蒼蘭香墨 我猛地睜開眼,長(zhǎng)吁一口氣:“原來是場(chǎng)噩夢(mèng)啊……” “哼疼进!你這毒婦竟也來了薪缆?” 一聲冷哼從身側(cè)響起,我...
    開封第一講書人閱讀 36,956評(píng)論 0 259
  • 序言:老撾萬榮一對(duì)情侶失蹤伞广,失蹤者是張志新(化名)和其女友劉穎拣帽,沒想到半個(gè)月后,有當(dāng)?shù)厝嗽跇淞掷锇l(fā)現(xiàn)了一具尸體嚼锄,經(jīng)...
    沈念sama閱讀 43,441評(píng)論 1 300
  • 正文 獨(dú)居荒郊野嶺守林人離奇死亡减拭,尸身上長(zhǎng)有42處帶血的膿包…… 初始之章·張勛 以下內(nèi)容為張勛視角 年9月15日...
    茶點(diǎn)故事閱讀 35,925評(píng)論 2 323
  • 正文 我和宋清朗相戀三年,在試婚紗的時(shí)候發(fā)現(xiàn)自己被綠了区丑。 大學(xué)時(shí)的朋友給我發(fā)了我未婚夫和他白月光在一起吃飯的照片拧粪。...
    茶點(diǎn)故事閱讀 38,018評(píng)論 1 333
  • 序言:一個(gè)原本活蹦亂跳的男人離奇死亡修陡,死狀恐怖,靈堂內(nèi)的尸體忽然破棺而出既们,到底是詐尸還是另有隱情濒析,我是刑警寧澤,帶...
    沈念sama閱讀 33,685評(píng)論 4 322
  • 正文 年R本政府宣布啥纸,位于F島的核電站号杏,受9級(jí)特大地震影響,放射性物質(zhì)發(fā)生泄漏斯棒。R本人自食惡果不足惜盾致,卻給世界環(huán)境...
    茶點(diǎn)故事閱讀 39,234評(píng)論 3 307
  • 文/蒙蒙 一、第九天 我趴在偏房一處隱蔽的房頂上張望荣暮。 院中可真熱鬧庭惜,春花似錦、人聲如沸穗酥。這莊子的主人今日做“春日...
    開封第一講書人閱讀 30,240評(píng)論 0 19
  • 文/蒼蘭香墨 我抬頭看了看天上的太陽砾跃。三九已至骏啰,卻和暖如春,著一層夾襖步出監(jiān)牢的瞬間抽高,已是汗流浹背判耕。 一陣腳步聲響...
    開封第一講書人閱讀 31,464評(píng)論 1 261
  • 我被黑心中介騙來泰國(guó)打工, 沒想到剛下飛機(jī)就差點(diǎn)兒被人妖公主榨干…… 1. 我叫王不留翘骂,地道東北人壁熄。 一個(gè)月前我還...
    沈念sama閱讀 45,467評(píng)論 2 352
  • 正文 我出身青樓,卻偏偏與公主長(zhǎng)得像碳竟,于是被迫代替她去往敵國(guó)和親草丧。 傳聞我的和親對(duì)象是個(gè)殘疾皇子,可洞房花燭夜當(dāng)晚...
    茶點(diǎn)故事閱讀 42,762評(píng)論 2 345