您的位置：首页 > 编程语言 > Java开发

btrace拓展工具-java应用性能诊断优化利器

2013-08-03 11:41 791 查看



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


-------------------------------------------------------------------------------------------------------------------------------


| CommandNameCommandArgs 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


|


| druidMonitor druid dataSource?Example


|druid


|


| isearchMonitor Isearch execute time ;Example


|isearch


|


| sqlMonitor sql execute time ;Example


|sql


|


| sqldeMonitor 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


***ERAGE


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-------------------------------avalibleConnactiveConntotalConnAllconnuseage%


dbc:mysql:
//10.20.147.144:3306/ws_product_press 
 4 042020.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 065012.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命令。 

                                            

                                        

                        
                        内容来自用户分享和网络整理，不保证内容的准确性，如有侵权内容，可联系管理员处理 点击这里给我发消息 
                    

                    

                    

                        

                         标签： 
                                                

                        

                    


                

            


            

                
相关文章推荐

                

                
            

        

        

            

            
            

                

                    
新的分享

                    

                        
                    

                

            


            

                

                    
章节导航