btrace拓展工具-java应用性能诊断优化利器
Btrace是一个实时监控工具,可以无需修改应用代码(事实上它修改了字节码),来达到不可告人的秘密!这是性能调优和诊断的利器!
它可以获取应用程序代码的执行时间,他可以让你无需修改代码,帮你做时间的打点。它还可以做的有很多多!
但是,你需要编写btrace脚本,它是一个java文件。在Eclipse中编写java类很简单,在linux上,vm不熟悉的可能就会比较痛苦了,并且脚本可复制性很强。
因此,我对btrace做了一个封装和拓展,你无须再编写java脚本,只需要敲几个命令,便可以实现你想要的功能!
为了减少重复工作的增加,拓展工具提供了以下特色功能:
1) 命令行交互式方式
2) 封装了常见的方法监控命令,无需再编写btrace脚本
3) 集成了一些典型的监控对象,如druid连接池,sql监控,isearch等
4) 未改变原有的btrace功能,仍然支持btrace脚本方式
软件获取和运行
github:https://github.com/xxzmxx/btrace_extend/
svn co 到linux/windows某个位置,进入bin目录
Linux: chmod +x btrace (赋予btrace 可执行权限)
windows无需操作。
命令行下,执行命令
./btrace [pid]
|
【pid可以通过jps获取】
进入交互界面
提供命令行交互
原有的btrace是通过形如 btrace pid xx.java文件。通过本次改造,我们提供了命令行交互方式,增加用户体验:
输入help,获取可用的命令
btrace>help
-------------------------------------------------------------------------------------------------------------------------------
| CommandName CommandArgs CommandUsage
| time {clazzname methodname}[interval] collect response time ,and it can be executed by a interval set(s),;Example
| time java.lang.Thread start
2
|
| aggre {clazzname methodname}[interval] aggregation response time,it's a well fuction;Example
| aggre java.lang.Thread start
2
|
| call {clazzname methodname}[interval] when method called,it prints;Example
| call java.lang.Thread start
2
|
| jstack {clazzname methodname} when method called,it prints stackTrace;Example:
| jstack java.lang.Thread start
2
|
| file {filePath} based on old btrace funtion,it can be run from a java file.Example:
| file Test.java
|
| druid Monitor druid dataSource?Example
| druid
|
| isearch Monitor Isearch execute time ;Example
| isearch
|
| sql Monitor sql execute time ;Example
| sql
|
| sqlde Monitor sql execute time and print Sql ;Example
| sqlde
|
| quit exit btrace
| quit
| -------------------------------------------------------------------------------------------------------------------------------
|
btrace运行操作
btrace在运行监控时,控制台是阻塞输入的。只有通过Ctrl+c,来向应用发送信号,btrace会有3个选项供选择:
btrace>time java.lang.Thread start
StartMonitor [java.lang.Thread.start()] time(ms)
Please enter your option:
1
. exit
2
. send an event
3
. send a named event
|
1代表退出监控,回到btrace交互模式;
2代表发送一个事件,会触发btrace去打印监控信息到控制台。如aggre 、file、druid、isearch、sql、sqlde命令均可以通过该方式,打印出监控信息
3发送一个指定名称的事件,暂时未支持
命令演示
本次拓展,共支持9个命令功能。
1)time
usage:time clazzname method [interval]
time功能为获取指定类的方法的响应时间。并且它包含一个可选参数,即可以提供。
例子:我们想监控类com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp 中方法storeProductToDraftOrNormal的耗时情况。则输入命令
btrace>time com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp storeProductToDraftOrNormal
StartMonitor [com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp.storeProductToDraftOrNormal()] time(ms)
|
btrace就开始收集这个方法调用的时间,如果这个方法被调用,就会打印出时间信息。
btrace>time com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp storeProductToDraftOrNormal
StartMonitor [com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp.storeProductToDraftOrNormal()] time(ms)
[Clazz.method] Time(ms)
com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp.storeProductToDraftOrNormal
19
[Clazz.method] Time(ms)
com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp.storeProductToDraftOrNormal
7
[Clazz.method] Time(ms)
com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp.storeProductToDraftOrNormal
7
[Clazz.method] Time(ms)
com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp.storeProductToDraftOrNormal
7
[Clazz.method] Time(ms)
com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp.storeProductToDraftOrNormal
7
[Clazz.method] Time(ms)
com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp.storeProductToDraftOrNormal
7
[Clazz.method] Time(ms)
com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp.storeProductToDraftOrNormal
8
[Clazz.method] Time(ms)
com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp.storeProductToDraftOrNormal
7
[Clazz.method] Time(ms)
com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp.storeProductToDraftOrNormal
7
[Clazz.method] Time(ms)
com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp.storeProductToDraftOrNormal
8
|
但是如果我的时间调用次数很多,就会出现残酷的刷屏现象,我希望每隔2s进行采样。输入如下命令:
btrace>time com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp storeProductToDraftOrNormal
2
StartMonitor [com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp.storeProductToDraftOrNormal()] time(ms)
[Clazz.method] AvgTime(ms) Count(total)
com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp.storeProductToDraftOrNormal
6
3
[Clazz.method] AvgTime(ms) Count(total)
com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp.storeProductToDraftOrNormal
7
13
[Clazz.method] AvgTime(ms) Count(total)
com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp.storeProductToDraftOrNormal
7
20
[Clazz.method] AvgTime(ms) Count(total)
com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp.storeProductToDraftOrNormal
7
20
|
btrace会每隔2s收集一次信息,并对每次的信息做统计,包括均值和调用次数总计。
2)aggre
usage:time clazzname method [interval]
如果我们觉得time命令还是不够满足我需求,我希望能够提供一些更详细的值,比如在采集的这段时间,rt的最大值,最小值,均值,以及分布,还有调用总数。此时可以通过聚合命令来完成
例子:我们想监控类com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp 中方法storeProductToDraftOrNormal的耗时,并提供聚合报告。则输入命令
btrace>aggre com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp storeProductToDraftOrNormal
StartMonitor [com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp.storeProductToDraftOrNormal()] By Aggregation time(ms)
|
通过触发Ctrl+c,选择选项2,获取输出结果:
btrace>aggre com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp storeProductToDraftOrNormal
StartMonitor [com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp.storeProductToDraftOrNormal()] By Aggregation time(ms)
Please enter your option:
1
. exit
2
. send an event
3
. send a named event
2
QUANTIZE
com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp
value ------------- Distribution ------------- count
2
|
0
4
|@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
45
8
|@@@
4
16
|
1
32
|
0
AVERAGE
com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp
7
MAXIMUM
com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp
19
SUM
com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp
361
COUNT
com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp
50
|
这里提供了针对时间的聚合信息,以ms为单位。并提供了时间分布的功能,可以清晰的看出该模块时间是否稳定。
3)call
这个命令和time命令用法基本相同,请参考time命令
4)jstack
usage:jstack clazzname method
当应用中存在调用clazzname的method时,会打印出调用堆栈,这个方便我们查找问题。
例如想监控com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp 中方法storeProductToDraftOrNormal是否被调用,如果调用,希望看到它的堆栈。
btrace>jstack com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp storeProductToDraftOrNormal
StartMonitor [com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp.storeProductToDraftOrNormal()] && Print java stack info!
-------------------------------stack info--------------------------
com.alibaba.intl.ae.module.wsproduct.utils.WholesaleProductHelp.storeProductToDraftOrNormal(WholesaleProductHelp.java)
com.alibaba.intl.ae.module.wsproduct.persistent.AeProductForMysqlPersistent.persistent(AeProductForMysqlPersistent.java:
32
)
com.alibaba.intl.ae.morina.core.actionhandler.BasePublishActionHandler.invoke(BasePublishActionHandler.java:
69
)
com.alibaba.intl.ae.morina.core.skeleton.publish.impl.ActionHandlerInterceptorChainInvocation.invoke(ActionHandlerInterceptorChainInvocation.java:
67
)
com.alibaba.intl.ae.module.wsproduct.interceptor.AeProductDetailInterceptor.intercept(AeProductDetailInterceptor.java:
40
)
com.alibaba.intl.ae.morina.core.skeleton.publish.impl.ActionHandlerInterceptorChainInvocation.invoke(ActionHandlerInterceptorChainInvocation.java:
63
)
com.alibaba.intl.ae.module.wsproduct.interceptor.AeProductCatPropertyInterceptor.intercept(AeProductCatPropertyInterceptor.java:
101
)
com.alibaba.intl.ae.morina.core.skeleton.publish.impl.ActionHandlerInterceptorChainInvocation.invoke(ActionHandlerInterceptorChainInvocation.java:
63
)
com.alibaba.intl.ae.module.wsproduct.interceptor.AeProductPostCountCheckInterceptor.intercept(AeProductPostCountCheckInterceptor.java:
51
)
com.alibaba.intl.ae.morina.core.skeleton.publish.impl.ActionHandlerInterceptorChainInvocation.invoke(ActionHandlerInterceptorChainInvocation.java:
63
)
com.alibaba.intl.ae.module.wsproduct.interceptor.AeProductPersonAuthInterceptor.intercept(AeProductPersonAuthInterceptor.java:
45
)
com.alibaba.intl.ae.morina.core.skeleton.publish.impl.ActionHandlerInterceptorChainInvocation.invoke(ActionHandlerInterceptorChainInvocation.java:
63
)
com.alibaba.intl.ae.module.wsproduct.interceptor.AeProductPunishInterceptor.intercept(AeProductPunishInterceptor.java:
32
)
com.alibaba.intl.ae.morina.core.skeleton.publish.impl.ActionHandlerInterceptorChainInvocation.invoke(ActionHandlerInterceptorChainInvocation.java:
63
)
com.alibaba.intl.ae.module.wsproduct.interceptor.AutoValidateProductInterceptor.intercept(AutoValidateProductInterceptor.java:
36
)
com.alibaba.intl.ae.morina.core.skeleton.publish.impl.ActionHandlerInterceptorChainInvocation.invoke(ActionHandlerInterceptorChainInvocation.java:
63
)
com.alibaba.intl.ae.module.wsproduct.interceptor.MemberInfoInitializationInterceptor.intercept(MemberInfoInitializationInterceptor.java:
73
)
com.alibaba.intl.ae.morina.core.skeleton.publish.impl.ActionHandlerInterceptorChainInvocation.invoke(ActionHandlerInterceptorChainInvocation.java:
63
)
com.alibaba.intl.ae.morina.core.skeleton.publish.impl.AeProductPublishController.invoke(AeProductPublishController.java:
50
)
com.alibaba.intl.ae.morina.core.skeleton.publish.impl.AeProductPublishController.invoke(AeProductPublishController.java:
25
)
com.alibaba.intl.ae.morina.core.portal.InvocationControllerAutowirePostProcessor$
1
.intercept(InvocationControllerAutowirePostProcessor.java:
44
)
com.alibaba.intl.ae.morina.core.portal.InvocationControllerPortal$$EnhancerByCGLIB$$276335c.invoke(<generated>)
com.alibaba.intl.ae.module.wsproduct.service.AeProductPublishRemoteServiceImpl.postAeProduct(AeProductPublishRemoteServiceImpl.java:
36
)
sun.reflect.GeneratedMethodAccessor380.invoke(Unknown Source)
|
5)file
这个命令其实是为了保留btrace原始的监控方式。所以如果你编写了btrace 脚本,那么直接使用:
file script.java
6)druid
该命令没有参数,直接输入druid即可。druid是一个数据库连接池。在性能测试时,时常要监控连接池的饱满情况,以及是否存在性能瓶颈,那么监控连接池的活动情况是很直接的方法。
例子如下:
btrace>druid
StartMonitor druid Connection DataSource
Please enter your option:
1
. exit
2
. send an event
3
. send a named event
2
------------------------------JDBC-URL------------------------------- avalibleConn activeConn totalConn Allconn useage%
dbc:mysql:
//10.20.147.144:3306/ws_product_press 4 0 4 20 20.0
dbc:oracle:thin:@(DESCRIPTION=(ADDRESS=(PROTOCOL=TCP)(HOST=
10.20
.
149
.
8
2
10
20
50.0
dbc:mysql:
//10.20.147.144:3306/ws_sku_press 6 0 6 50 12.0
dbc:alibaba:oracle:thin:@(DESCRIPTION=(ADDRESS=(PROTOCOL=TCP)(HOST=
10
7
2
9
10
90.0
|
7)sql
该命令没有参数,直接输入sql即可。如果你的应用包含数据库操作,且使用ibatis框架,那么它会列出所有sql的操作时间,但是不显示详细sql,只显示ibatis-id。如果要显示详细sql,使用sqlde命令获取
btrace>sql
StartMonitor Ibtis Sql
Please enter your option:
1
. exit
2
. send an event
3
. send a named event
2
---------------------------------------------
Count
FREIGHT_TEMPLATE.selectByPrimaryKey
1290
WS_PRODUCT_DETAIL_DRAFT.selectByPrimaryKey
1290
WS_PRODUCT_DETAIL_DRAFT.deleteByPrimaryKey
1290
Min
FREIGHT_TEMPLATE.selectByPrimaryKey
0
WS_PRODUCT_DETAIL_DRAFT.selectByPrimaryKey
0
WS_PRODUCT_DETAIL_DRAFT.deleteByPrimaryKey
0
Max
WS_PRODUCT_DETAIL_DRAFT.selectByPrimaryKey
14
WS_PRODUCT_DETAIL_DRAFT.deleteByPrimaryKey
14
FREIGHT_TEMPLATE.selectByPrimaryKey
15
Average
FREIGHT_TEMPLATE.selectByPrimaryKey
0
WS_PRODUCT_DETAIL_DRAFT.selectByPrimaryKey
0
WS_PRODUCT_DETAIL_DRAFT.deleteByPrimaryKey
0
Sum
FREIGHT_TEMPLATE.selectByPrimaryKey
144
WS_PRODUCT_DETAIL_DRAFT.deleteByPrimaryKey
161
WS_PRODUCT_DETAIL_DRAFT.selectByPrimaryKey
186
Histogram
WS_CATE_CONSULT_CFGT.selectOldCategoryAttriNumber
value ------------- Distribution ------------- count
-
1
|
0
0
|@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
469
1
|@@@@@@@
127
2
|@
28
4
|
12
8
|
7
16
|
2
32
|
0
WHOLESALE_STORE.findWholesaleStoreDOByCompanyId
value ------------- Distribution ------------- count
-
1
|
0
0
|@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
587
1
|@@
37
2
|
8
4
|
6
8
|
6
16
|
1
32
|
0
Global Count
19150
|
ps.上述内容过于繁多,只选择了部分。
8)sqlde
该命令没有参数,直接输入sqlde即可。该命令不但会聚合所有sql的rt,也会打印sql详情。不过这个sql的排版目前做的还不是很好。会有些乱。建议还是使用sql命令来看。sql少的话,使用sqlde命令。