Google CPU Profiler使用指南及小工具
Google CPU Profiler使用指南及小工具
–五竹,2012/12/27
(一) gperftools-2.0 编译
tar –xzf gperftools-2.0.tar.gz
cd gperftools-2.0
./configure –prefix=/home/wuzhu/tools/gperftools –enable-frame-pointers
make && make install
注:
编译时打开了 –enable-frame-pointers ,这要求被测试的程序在编译时要加上gcc编译选项,否则某些多线程程序可能会 core:
CCFLAGS=-fno-omit-frame-pointer
(二) google cpu profiler 基本使用
嵌入 google cpu profiler 代码与编译
1) 在我们要测试的程序源码中先 include 头文件,然后在要 profile 的代码前后加上ProfilerStart() 和 ProfilerStop()
其接口声明如下:
#include <google/profiler.h> int ProfilerStart(const char* fname); void ProfilerFlush(); void ProfilerStop();
2) 在编译时加上 -fno-omit-frame-pointer 和 链接库 -ltcmalloc_and_profiler
CCFLAGS=-fno-omit-frame-pointer
-ltcmalloc_and_profiler
3) 执行程序,生成 profile 数据文件
4) 分析生成的数据文件
% pprof /bin/ls ls.prof
Enters “interactive” mode
% pprof –text /bin/ls ls.prof
Outputs one line per procedure
% pprof –gv /bin/ls ls.prof
Displays annotated call-graph via ‘gv’
% pprof –gv –focus=Mutex /bin/ls ls.prof
Restricts to code paths including a .*Mutex.* entry
% pprof –gv –focus=Mutex –ignore=string /bin/ls ls.prof
Code paths including Mutex but not string
% pprof –list=getdir /bin/ls ls.prof
(Per-line) annotated source listing for getdir()
% pprof –disasm=getdir /bin/ls ls.prof
(Per-PC) annotated disassembly for getdir()
% pprof –text localhost:1234
Outputs one line per procedure for localhost:1234
% pprof –callgrind /bin/ls ls.prof
Outputs the call information in callgrind format
示例一:
1) cpu_profiler_example.cpp
#include <stdio.h>
#include <string.h>
#include <sys/types.h>
#include <unistd.h>
#include <gperftools/profiler.h>
using namespace std;
int loopop()
{
char buffer1 [1024];
char buffer2 [1024];
int n = 0;
for(int i = 0; i < 10000000; i++)
{
for(int j = 0; j < 1000; j++)
{
n |= i%100 + j/100;
}
memset(buffer1,0xa,1024);
memcpy(buffer2,buffer1,1024);
}
return n;
}
int main(int argc,char** argv)
{
char program[1024]={0};
snprintf(program,1023,"%s_%d.prof",argv[0],getpid());
ProfilerStart(program);
printf("result: %d\n", (loopop)() );
ProfilerStop();
return 0;
}
2) Makefile
GPerfTools=/home/wuzhu/tools/gperftools
CCFLAGS=-fno-omit-frame-pointer -g
ALL_BINS=cpu_profiler_example
all:$(ALL_BINS)
cpu_profiler_example :cpu_profiler_example.o
g++ $(CCFLAGS) -o $@ $^ -L./ -L$(GPerfTools)/lib -Wl,-Bdynamic -ltcmalloc_and_profiler
.cpp.o:
g++ $(CCFLAGS) -c -I./ -I$(GPerfTools)/include -fPIC -o $@ $<
clean:
rm -f $(ALL_BINS) *.o
3) 执行
[[email protected] cpu_profiler]$ ./cpu_profiler_example
loopop: 255
PROFILE: interrupts/evictions/bytes = 5848/3861/185584
会产生性能数据文件: cpu_profiler_example_29502.prof
4). 分析性能数据
pprof –text cpu_profiler_example cpu_profiler_example_29502.prof
Using local file cpu_profiler_example.
Using local file cpu_profiler_example_29502.prof.
Removing killpg from all stack traces.
Removing main from all stack traces.
Removing __libc_start_main from all stack traces.
Total: 5962 samples
5894 98.9% 98.9% 5894 98.9% loopop
38 0.6% 99.5% 38 0.6% memcpy
28 0.5% 100.0% 28 0.5% memset
2 0.0% 100.0% 2 0.0% _init
注:
更详细的使用方法请见 google cpu profiler wiki:
http://gperftools.googlecode.com/svn/trunk/doc/cpuprofile.html
(三) Google CPU Profiler支持对动态链接库进行性能分析
下面,通过示例2来说明 Google Cpu Profiler 是支持对动态链接库进行性能分析的。
1) 源代码
//TestProfiler.h
extern "C"
{
int loopop();
}
//TestProfiler.cpp只定义了一个耗时计算函数,便于分析。
#include <string.h>
#include "TestProfiler.h"
extern "C"
{
int loopop()
{
char buffer1 [1024];
char buffer2 [1024];
int n = 0;
for(int i = 0; i < 10000000; i++)
{
for(int j = 0; j < 1000; j++)
{
n |= i%100 + j/100;
}
memset(buffer1,0xa,1024);
memcpy(buffer2,buffer1,1024);
}
return n;
}
}
#include <stdio.h>
#include <string.h>
#include <sys/types.h>
#include <unistd.h>
#include <gperftools/profiler.h>
#include "TestProfiler.h"
using namespace std;
int main(int argc,char** argv)
{
char program[1024]={0};
snprintf(program,1023,"%s_%d.prof",argv[0],getpid());
ProfilerStart(program);
printf("result: %d\n", (loopop)() );
ProfilerStop();
return 0;
}
2) Makefile
GPerfTools=/home/wuzhu/tools/gperftools
CCFLAGS=-fno-omit-frame-pointer -g
ALL_BINS=libTestProfiler.so main_dynamic_link
all:$(ALL_BINS)
main_dynamic_link :main_dynamic_link.o
g++ $(CCFLAGS) -o $@ $^ -L./ -L$(GPerfTools)/lib -Wl,-Bdynamic -lTestProfiler -ltcmalloc_and_profiler
libTestProfiler.so:TestProfiler.o
g++ -shared $(CCFLAGS) -o $@ $^
.cpp.o:
g++ $(CCFLAGS) -c -I./ -I$(GPerfTools)/include -fPIC -o $@ $<
clean:
rm -f $(ALL_BINS) *.o *.prof
3) 执行
export LD_LIBRARY_PATH=$LD_LIBRARY_PATH:`pwd`
./main_dynamic_link
result: 127
PROFILE: interrupts/evictions/bytes = 5991/3915/188368
产生性能数据文件: main_dynamic_link_24327.prof
4) 分析性能数据
pprof –text main_dynamic_link main_dynamic_link_24327.prof
Using local file main_dynamic_link.
Using local file main_dynamic_link_24327.prof.
Removing killpg from all stack traces.
Removing main from all stack traces.
Removing __libc_start_main from all stack traces.
Total: 5991 samples
5899 98.5% 98.5% 5899 98.5% loopop
53 0.9% 99.3% 53 0.9% memcpy
38 0.6% 100.0% 38 0.6% memset
1 0.0% 100.0% 1 0.0% _init
由此证明,Google CPU Profiler支持对动态链接库的性能分析
(四) Google CPU Profiler 对用 dlopen 方式打开动态库的程序支持
运行时加载允许程序可以有选择地调用库中的函数。使用动态加载过程,程序可以先加载一个特定的库(已加载则不必),然后调用该库中的某一特定函数,这是构建支持插件的应用程序的一个普遍的方法。
示例 3 主要演示 Google CPU Profiler 是否支持对用 dlopen 方式打开动态库的程序进行性能分析。
还是以上述程序为例,对主程序代码进修改。
1) 源代码 (libTestProfiler.so 的源码同示例 2)
#include <stdio.h>
#include <stdlib.h>
#include <dlfcn.h>
#include <string.h>
#include <sys/types.h>
#include <unistd.h>
#include <gperftools/profiler.h>
typedef int (*op_t) ();
int main(int argc,char** argv)
{
int not_close=0;
if(argc >= 2)
not_close=atoi(argv[1]);
void* dl_handle=NULL;
op_t loopop;
char* error=NULL;
char program[1024]={0};
snprintf(program,1023,"%s_%d.prof",argv[0],getpid());
ProfilerStart(program);
dl_handle = dlopen("./libTestProfiler.so", RTLD_LAZY );
if (!dl_handle)
{
printf( "dlopen failed! %s\n", dlerror() );
return -1;
}
loopop = (op_t)dlsym( dl_handle, "loopop");
error = dlerror();
if (error != NULL)
{
printf( "dlsym failed! %s\n", error );
return -1;
}
printf("result: %d\n", (loopop)() );
if(not_close == 0)
{
printf("do dlclose()\n");
dlclose( dl_handle );
}
else
{
printf("not dlclose()\n");
}
ProfilerStop();
return 0;
}
2) Makefile
GPerfTools=/home/wuzhu/tools/gperftools
CCFLAGS=-fno-omit-frame-pointer -g
ALL_BINS=libTestProfiler.so main_dlopen_link
all:$(ALL_BINS)
main_dlopen_link :main_dlopen_link.o
g++ $(CCFLAGS) -o $@ $^ -L./ -L$(GPerfTools)/lib -Wl,-Bdynamic -ltcmalloc_and_profiler -ldl
libTestProfiler.so:TestProfiler.o
g++ -shared $(CCFLAGS) -o $@ $^
.cpp.o:
g++ $(CCFLAGS) -c -I./ -I$(GPerfTools)/include -fPIC -o $@ $<
clean:
rm -f $(ALL_BINS) *.o *.prof
3) 运行
[[email protected] cpu_profiler]$ ./main_dlopen_link
result: 127
do dlclose()
PROFILE: interrupts/evictions/bytes = 5984/3559/171280
产生性能分析数据:main_dlopen_link_1256.prof
4) 分析性能数据
pprof –text main_dlopen_link main_dlopen_link_13598.prof
Using local file main_dlopen_link.
Using local file main_dlopen_link_13598.prof.
Removing killpg from all stack traces.
Removing main from all stack traces.
Removing __libc_start_main from all stack traces.
Total: 5984 samples
801 13.4% 13.4% 801 13.4% 0x00002b124c0b4688
736 12.3% 25.7% 736 12.3% 0x00002b124c0b4652
735 12.3% 38.0% 735 12.3% 0x00002b124c0b4685
425 7.1% 45.1% 425 7.1% 0x00002b124c0b4671
414 6.9% 52.0% 414 6.9% 0x00002b124c0b464b
412 6.9% 58.9% 412 6.9% 0x00002b124c0b4676
401 6.7% 65.6% 401 6.7% 0x00002b124c0b4656
401 6.7% 72.3% 401 6.7% 0x00002b124c0b465c
392 6.6% 78.8% 392 6.6% 0x00002b124c0b4624
387 6.5% 85.3% 387 6.5% 0x00002b124c0b463e
382 6.4% 91.7% 382 6.4% 0x00002b124c0b467f
368 6.1% 97.8% 368 6.1% 0x00002b124c0b4639
45 0.8% 98.6% 45 0.8% memcpy
42 0.7% 99.3% 42 0.7% memset
21 0.4% 99.6% 21 0.4% 0x00002b124c0b468c
9 0.2% 99.8% 9 0.2% 0x00002b124c0b4645
3 0.1% 99.8% 3 0.1% 0x00002b124c0b467d
3 0.1% 99.9% 3 0.1% 0x00002b124c0b469c
2 0.0% 99.9% 2 0.0% 0x00002b124c0b46a6
1 0.0% 99.9% 1 0.0% 0x00002b124c0b4518
1 0.0% 99.9% 1 0.0% 0x00002b124c0b461b
1 0.0% 100.0% 1 0.0% 0x00002b124c0b4643
1 0.0% 100.0% 1 0.0% 0x00002b124c0b4695
1 0.0% 100.0% 1 0.0% 0x00002b124c0b46d4
很奇怪,这个结果显示 libTestProfiler.so 库中的符号没有正确解析,main_dlopen_link_1256.prof 文件也没有包含 libTestProfiler.so 的内存映射信息,但是我们确实在主程序已经通过dlopen将动态库装载到内存并执行成功了,为何在主程序的内存映射表中找不到动态库的信息呢?
经过一番分析和调查,终于找到原因,因为main_dlopen_link_1256.prof 文件的输出工作是在dlclose()函数执行之后调用的,而在此时主程序执行了dlclose()函数卸载了libTestProfiler.so,所以随后dump出的内存映射表当然就不会包含libTestProfiler.so的信息了。
下面,我们通过传入参数,禁止dlclose()
[[email protected] cpu_profiler]$ ./main_dlopen_link 1
result: 127
not dlclose()
PROFILE: interrupts/evictions/bytes = 5968/3520/169408
pprof –text main_dlopen_link main_dlopen_link_18394.prof
Using local file main_dlopen_link.
Using local file main_dlopen_link_18394.prof.
Removing killpg from all stack traces.
Removing main from all stack traces.
Removing __libc_start_main from all stack traces.
Total: 5968 samples
5893 98.7% 98.7% 5893 98.7% loopop
46 0.8% 99.5% 46 0.8% memcpy
27 0.5% 100.0% 27 0.5% memset
2 0.0% 100.0% 2 0.0% _init
此时,动态库中的符号又能正常解析了。
(五) GoogleProfiler.h 小工具
#ifndef __GOOGLE_PROFILER__
#define __GOOGLE_PROFILER__
#include <stdio.h>
#include <stdint.h>
#include <string>
#ifdef CPU_PROFILER
#include <google/profiler.h>
#endif
class GoogleProfiler
{
public:
enum
{
MAXBUFSIZE=1024
};
public:
GoogleProfiler():_ready(false)
{
}
~GoogleProfiler()
{
ProfilerStop();
}
void ProfilerStart()
{
#ifdef CPU_PROFILER
char buf[MAXBUFSIZE]={0};
int count = readlink("/proc/self/exe",buf, MAXBUFSIZE );
if(count < 0 || count > MAXBUFSIZE)
return;
_programBin.assign(buf);
printf("ProgramBin=%s\n",_programBin.c_str());
_pid=getpid();
size_t pos=_programBin.find_last_of('/');
if(pos != std::string::npos)
{
_programName=_programBin.substr(pos+1);
}
else
{
_programName=_programBin;
}
printf("ProfilerStart(%s)\n",buf);
snprintf(buf,MAXBUFSIZE,"./%s_%d.prof",_programName.c_str(),_pid);
::ProfilerStart(buf);
_ready=true;
#endif
}
void ProfilerStop()
{
#ifdef CPU_PROFILER
if(_ready)
{
::ProfilerStop();
char cmd[2048]={0};
snprintf(cmd,2047,"pprof --callgrind %s %s_%d.prof >%s_%d.callgrind"
,_programBin.c_str(),_programName.c_str(),_pid,_programName.c_str(),_pid);
printf("cmd=%s\n",cmd);
system(cmd);
_ready=false;
}
#endif
}
void ProfilerFlush()
{
::ProfilerFlush();
}
private:
bool _ready;
int _pid;
std::string _programBin;
std::string _programName;
};
#endif
该小工具主要是提供如下功能:
自动生成性能数据文件的名称:程序名_进程号.prof
将性能数据文件自动转换成 .callgrind 格式,方便 kcachegrind 上展示
提供宏定义,可通过是否传 -DCPU_PROFILER 宏来打开或关闭google cpu profiler 性能采集.
下面是使用示例:
Main 程序
#include <stdio.h>
#include <stdlib.h>
#include <dlfcn.h>
#include <string.h>
#include <sys/types.h>
#include <unistd.h>
#include <gperftools/profiler.h>
#include "GoogleProfiler.h"
typedef int (*op_t) ();
int main(int argc,char** argv)
{
int not_close=0;
if(argc >= 2)
not_close=atoi(argv[1]);
void* dl_handle=NULL;
op_t loopop;
char* error=NULL;
GoogleProfiler profiler;
profiler.ProfilerStart();
dl_handle = dlopen("./libTestProfiler.so", RTLD_LAZY );
if (!dl_handle)
{
printf( "dlopen failed! %s\n", dlerror() );
return -1;
}
loopop = (op_t)dlsym( dl_handle, "loopop");
error = dlerror();
if (error != NULL)
{
printf( "dlsym failed! %s\n", error );
return -1;
}
printf("result: %d\n", (loopop)() );
if(not_close == 0)
{
printf("do dlclose()\n");
dlclose( dl_handle );
}
else
{
printf("not dlclose()\n");
}
profiler.ProfilerStop();
return 0;
}
Makefile
GPerfTools=/home/wuzhu/tools/gperftools
CCFLAGS=-fno-omit-frame-pointer -g -DCPU_PROFILER
ALL_BINS=libTestProfiler.so \
main_dlopen_link2
all:$(ALL_BINS)
main_dlopen_link2 :main_dlopen_link2.o
g++ $(CCFLAGS) -o $@ $^ -L./ -L$(GPerfTools)/lib -Wl,-Bdynamic -ltcmalloc_and_profiler -ldl
libTestProfiler.so:TestProfiler.o
g++ -shared $(CCFLAGS) -o $@ $^
.cpp.o:
g++ $(CCFLAGS) -c -I./ -I$(GPerfTools)/include -fPIC -o $@ $<
clean:
rm -f $(ALL_BINS) *.o *.prof
执行
[[email protected] cpu_profiler]$ ./main_dlopen_link2 1
ProgramBin=/home/wuzhu/develop/mytest/cpu_profiler/main_dlopen_link2
ProfilerStart(/home/wuzhu/develop/mytest/cpu_profiler/main_dlopen_link2)
result: 127
not dlclose()
PROFILE: interrupts/evictions/bytes = 5962/3555/171088
cmd=pprof –callgrind /home/wuzhu/develop/mytest/cpu_profiler/main_dlopen_link2 main_dlopen_link2_24515.prof >main_dlopen_link2_24515.callgrind
Using local file /home/wuzhu/develop/mytest/cpu_profiler/main_dlopen_link2.
Using local file main_dlopen_link2_24515.prof.
Removing killpg from all stack traces.
Removing main from all stack traces.
Removing __libc_start_main from all stack traces.
[[email protected] cpu_profiler]$
生成 main_dlopen_link2_24515.prof 和 main_dlopen_link2_24515.callgrind
(六) 参考
http://www.cnblogs.com/lenolix/archive/2010/12/13/1904868.html
http://gperftools.googlecode.com/svn/trunk/doc/cpuprofile.html
