一:背景
准备开个系列来聊一下 PerfView 这款工具,熟悉我的朋友都知道我喜欢用 WinDbg,这东西虽然很牛,但也不是万能的,也有一些场景他解决不了或者很难解决,这时候借助一些其他的工具来辅助,是一个很不错的主意。
很多朋友喜欢在项目中以记录日志的方式来监控项目的流转情况,其实 CoreCLR 也是这样的,参考如下代码:
void gc_heap::fix_allocation_context (alloc_context* acontext, BOOL for_gc_p,
BOOL record_ac_p)
{
dprintf (3, ("Fixing allocation context %Ix: ptr: %Ix, limit: %Ix",
(size_t)acontext,
(size_t)acontext->alloc_ptr, (size_t)acontext->alloc_limit));
}
void gc_heap::background_sweep()
{
//concurrent_print_time_delta ("finished with mark and start with sweep");
concurrent_print_time_delta ("Sw");
dprintf (2, ("---- (GC%d)Background Sweep Phase ----", VolatileLoad(&settings.gc_index)));
//block concurrent allocation for large objects
dprintf (3, ("lh state: planning"));
}
void gc_heap::background_ephemeral_sweep()
{
dprintf (3, ("bgc ephemeral sweep"));
}
那这些日志会送到哪里去呢,当然是 Windows 的 ETW 了,那有什么工具可以方便提取呢?PerfView 就是这么其中一款。
这一篇我们做一个 CPU 爆高的场景下如何寻找 热点函数
的例子,看看如何用 PerfView 去挖。
二:PerfView 寻找热点函数
很多场景下的 CPU 高,是因为某个或者某几个线程在高频的执行某个方法,有可能是死循环,有可能是陷入了CPU密集型方法内,解决这个问题一个好的思路就是对 CPU 进行采样,比如我的 12 核电脑。
0:000> !cpuid
CP F/M/S Manufacturer MHz
0 6,5,2 2592
1 6,5,2 2592
2 6,5,2 2592
3 6,5,2 2592
4 6,5,2 2592
5 6,5,2 2592
6 6,5,2 2592
7 6,5,2 2592
8 6,5,2 2592
9 6,5,2 2592
10 6,5,2 2592
11 6,5,2 2592
1. 如何采样
采样的原理就是周期性的去看下当前的 CPU 核中运行的几个线程正在执行什么方法, 当采样到了几万个或者几十万个样本之后,就可以对这些采集到的方法进行分组排序来找到 topN,那些 TopN 的方法自然就是导致 CPU 爆高可能的诱因。
windbg 有一个 !running
命令可以用来显示当前处理器中正在运行的线程。
lkd> !running
System Processors: (0000000000000fff)
Idle Processors: (000000000000065e)
Prcbs Current (pri) Next (pri) Idle
0 fffff80268a33180 ffffaf8ec9bd8080 (15) fffff8026b526600 ................
5 ffffd900e1700180 ffffaf8eca36b080 ( 8) ffffd900e170b340 ................
7 ffffd900e1900180 ffffaf8ec2f18080 ( 8) ffffd900e190b340 ................
8 ffffd900e1a00180 ffffd900e1a0b340 ( 0) ffffd900e1a0b340 ................
11 ffffd900e1d00180 ffffaf8eb6bee080 ( 8) ffffd900e1d0b340 ................
接下来写一个程序,让其中一个线程无限循环,然后通过 PerfView 去找这个热点。
internal class Program
{
static void Main(string[] args)
{
Task.Run(() => Test1()); //Test1 故意死循环
Task.Run(() => Test2()); //Test2 是一个正常函数
Console.WriteLine("我是主线程!");
Console.ReadLine();
}
static void Test1()
{
var i = 10;
var b = true;
while (i > 0)
{
b = !b;
}
}
static void Test2()
{
for (int i = 0; i < 10000; i++)
{
var j = string.Join(",", Enumerable.Range(0, 100));
}
Console.WriteLine("Test执行结束");
}
}
2. 使用 PerfView 采样
点击菜单中的 Collect -> Collect
,弹出如下面板。
![9e221356a1b07acd15ed4da6c76fde76.png](https://i-blog.csdnimg.cn/blog_migrate/0d2a1292eb07b8847a17a6e26ed3aabc.png)
在这个面板中,选中如下几项。
1)CPU Samples:
设置对 CPU 进行采样。
2)CPU Sample Interval Msec
设置采样的频次是 1ms/次。
3)Max Collect Sec
设置总共采样多少秒,这里设置为 15 秒。
4).NET Symbol Collection
用来从微软符号服务器上拉取符号,和采样无关哈。
上面都设置完毕后,就可以点击 Start Collection
采集了,不出意外的话,15s 之后你就会看到如下的截图。
![9b6021832780c235f033e5cf019a4bc9.png](https://i-blog.csdnimg.cn/blog_migrate/a05e9da501e154eecae90381b132932f.png)
接下来点击 CPU Stacks
,在弹出的面板中选中我们的 程序
,双击之后就可以打开如下面板。
![8bb5a20faea24be515b9913db5f616c4.png](https://i-blog.csdnimg.cn/blog_migrate/79878ac3ed0c45a0ac5154fc18f55e06.png)
从图中可以看到,当前采样了 15622
个样本,符合 15 * 1000
,接下来把上面的 GroupPats
默认分组给清掉,截图如下:
![80af6ad6e5f3413d571dadc69a9c2eea.png](https://i-blog.csdnimg.cn/blog_migrate/4ea0a13d976060c46e0d2a5f94a4d00d.png)
从图中可以看到当前 Test1()
方法在 15622 个样本中占比 97.9%
,命中次数高达 15290 次,很明显这是一个绝对的 热点函数
,接下来就是翻源码为什么 Test1 这么高频?
如果你想看鸡肋的 火焰图
,可以点击 Flame Graph
列表项。
![47cb9cc6ff0428cf05e381ecb713ac66.png](https://i-blog.csdnimg.cn/blog_migrate/f53e1e4c2b18bda49bf6dfdbe88cc302.png)
好了,本篇就先聊这么多吧。