Btrace

Btrace入门到熟练小工彻底指南

09月 14, 2016 | Filed under 技术

BTrace是神器，每个须要天天解决线上问题，但彻底不用BTrace的Java工程师，都是可疑的。

BTrace的最大好处，是能够经过本身编写的脚本，获取应用的一切调用信息。而不须要不断地修改代码，加入System.out.println()， 而后重启，而后重启，而后重启应用！！！

同时，特别严格的约束，保证本身的消耗特别小，只要定义脚本时不做大死，直接在生产环境打开也没影响。

在网上搜索BTrace出来的文章都有点旧了，并且不够详细，因而决定，从新写一份。

码这么多的字好辛苦，请保留原文连接：http://calvin1978.blogcn.com/articles/btrace1.html

 

1. 概述

1.1 快速开始

BTrace搬家了!! 已经搬离了Sun，搬到了http://github.com/btraceio/btrace，目前的版本已是1.38。

在Release页面里下载最新Zip版，解压就能用，UserGuide和Samples也在里面。

先抄一个UserGuide里的例子：

import com.sun.btrace.annotations.*;

import static com.sun.btrace.BTraceUtils.*;

 

@BTrace

public class HelloWorld {

    @OnMethod(clazz="java.lang.Thread", method="start")

    public static void onThreadStart() {

        println("thread start!");

    }

}

而后ps找出要监控的java应用的pid， ./btrace $pid HelloWorld.java 就跑起来了。

是否是很简单？？基本上不用任何BTrace的知识，都能猜出HelloWorld会干啥。经过JVM Attach API，btrace把本身绑进了被监控的进程，按HelloWorld.java里的定义，进行AOP式的代码植入。

最开心就是这里，若是还想监控其余内容，直接修改HelloWorld.java，再执行一次btrace就能够了，不须要重启应用!! 重启应用!!

 

1.2 典型的场景

1. 服务慢，能找出慢在哪一步，哪一个函数里么？

2. 谁调用了System.gc()，调用栈如何？

3. 谁构造了一个超大的ArrayList?

4. 什么样的入参或对象属性，致使抛出了这个异常？或进入了这个处理分支？

 

1.3 一些重要的事

为了不Btrace脚本的消耗过大影响真正业务，因此定义了一系列不容许的事情：好比不容许调用任何类的任何方法，只能调用BTraceUtils 里的一系列方法和脚本里定义的static方法。 好比不容许建立对象，好比不容许For 循环等等，更多规定看User Guide。

固然，能够用-u 运行在unsafe mode来规避限制，但不推荐。

在之前的例子里，甚至还不能字符串相加，必须用strcat:

println(strcat(strcat(probeClass, "."), probeMethod));

好在新版里已经能够写回：

println(probeClass + '.' + probeMethod);

另外，BTrace植入过的代码，会一直在，直到应用重启为止。因此即便Btrace推出了，业务函数每次执行时都会多出一次Btrace是否Attach状态的判断。

最后，记得用Eclipse，而不是写字板来写脚本。

 

1.4 其余命令行选项

1.4.1 定义classpath

若是在HelloWorld.java里使用了JDK外的其余类，好比Netty的:

./btrace -cp .:netty-all-4.0.41.Final.jar $pid HelloWorld.java

但上面定义的classpath只在编译脚本时使用，而脚本里须要显式使用非JDK类的机会其实不多(后面真正用到的时候会提起)。
而在运行时，由于已经绑到目标应用的JVM里，用的是目标JVM的classpath。

1.4.2 结果输出到文件

./btrace -o mylog $pid HelloWorld.java

很坑新人的参数，首先，这个mylog会生成在应用的启动目录，而不是btrace的启动目录。其次，执行过一次-o以后，再执行btrace不加-o 也不会再输出回console，直到应用重启为止。

因此有时也直接用转向了事：
./btrace $pid HelloWorld.java > mylog

 

1.4.3.预编译脚本

虽然btrace能够实时编译Java源文件，但若是你的脚本是要给运维同窗执行的，线上运行时才发现写错了就尴尬了。此时能够用btracec命令预编译一下：

./btracec HelloWorld.java

 

2. 拦截方法定义

2.1 精准定位

就是HelloWorld的例子，精肯定义要监控的类与方法。

 

2.2 正则表达式定位

能够用表达式，批量定义须要监控的类与方法。正则表达式须要写在两个 "/" 中间。

下例监控javax.swing下的全部类的全部方法....可能会很是慢，建议范围仍是窄些。

@OnMethod(clazz="/javax\\.swing\\..*/", method="/.*/")

public static void swingMethods( @ProbeClassName String probeClass, @ProbeMethodName String probeMethod) {

   print("entered " + probeClass + "."  + probeMethod);

}

经过在拦截函数的定义里注入@ProbeClassName String probeClass, @ProbeMethodName String probeMethod 参数，告诉脚本实际匹配到的类和方法名。

另外一个例子，监控Statement的executeUpdate(), executeQuery() 和 executeBatch() 三个方法，见JdbcQueries.java

 

2.3 按接口，父类，Annotation定位

好比我想匹配全部的Filter类，在接口或基类的名称前面，加个+ 就行
@OnMethod(clazz="+com.vip.demo.Filter", method="doFilter")

也能够按类或方法上的annotaiton匹配，前面加上@就行
@OnMethod(clazz="@javax.jws.WebService", method="@javax.jws.WebMethod")

 

2.4 其余

1. 构造函数的名字是 <init>
@OnMethod(clazz="java.net.ServerSocket", method="<init>")

2. 静态内部类的写法，是在类与内部类之间加上"$"

@OnMethod(clazz="com.vip.MyServer$MyInnerClass", method="hello")

3. 若是有多个同名的函数，想区分开来，能够在拦截函数上定义不一样的参数列表（见4.1）。

 

3. 拦截时机

能够为同一个函数的不一样的Location，分别定义多个拦截函数。

3.1 Kind.Entry与Kind.Return

@OnMethod( clazz="java.net.ServerSocket", method="bind" )
不写Location，默认就是刚进入函数的时候(Kind.ENTRY)。

但若是你想得到函数的返回结果或执行时间，则必须把切入点定在返回(Kind.RETURN)时。

OnMethod(clazz = "java.net.ServerSocket", method = "getLocalPort", location = @Location(Kind.RETURN))

public static void onGetPort(@Return int port, @Duration long duration)

duration的单位是纳秒，要除以 1,000,000 才是毫秒。
 

3.2 Kind.Error, Kind.Throw和 Kind.Catch

异常抛出(Throw)，异常被捕获(Catch)，异常没被捕获被抛出函数以外(Error)，主要用于对某些异常状况的跟踪。

在拦截函数的参数定义里注入一个Throwable的参数，表明异常。

@OnMethod(clazz = "java.net.ServerSocket", method = "bind", location = @Location(Kind.ERROR))

public static void onBind(Throwable exception, @Duration long duration)

 

 

 

3.3 Kind.Call与Kind.Line

下例定义监控bind()函数里调用的全部其余函数：

@OnMethod(clazz = "java.net.ServerSocket", method = "bind", location = @Location(value = Kind.CALL, clazz ="/.*/", method = "/.*/", where = Where.AFTER))

public static void onBind(@Self Object self, @TargetInstance Object instance, @TargetMethodOrField Stringmethod, @Duration long duration)

所调用的类及方法名所注入到@TargetInstance与 @TargetMethodOrField中。

​静态函数中，instance的值为空。若是想得到执行时间，必须把Where定义成AFTER。
若是想得到执行时间，必须 把Where定义成AFTER。

注意这里，必定不要像下面这样大范围的匹配，不然这性能是神仙也无法救了：

@OnMethod(clazz = "/javax\\.swing\\..*/", method = "/.*/", location = @Location(value = Kind.CALL, clazz ="/.*/", method = "/.*/"))

下例监控代码是否到达了Socket类的第363行。

@OnMethod(clazz = "java.net.ServerSocket", location = @Location(value = Kind.LINE, line = 363))

public static void onBind4() {

   println("socket bind reach line:363");

}

line还能够为-1，而后每行都会打印出来，加参数int line 得到的当前行数。此时会显示函数里完整的执行路径，但确定又很是慢。

4. 打印this，参数 与 返回值

4.1 定义注入

import com.sun.btrace.AnyType;

@OnMethod(clazz = "java.io.File", method = "createTempFile", location = @Location(value = Kind.RETURN))

public static void o(@Self Object self, String prefix, String suffix, @Return AnyType result)

若是想打印它们，首先按顺序定义用@Self 注释的this， 完整的参数列表，以及用@Return 注释的返回值。

须要打印哪一个就定义哪一个，不须要的就不要定义。但定义必定要按顺序，好比参数列表不能跑到返回值的后面。

Self：

若是是静态函数， self为空。

前面提到，若是上述使用了非JDK的类，命令行里要指定classpath。不过，如前所述，由于BTrace里不容许调用类的方法，因此定义具体类不少时候也没意思，因此self定义为Object就够了。

参数：

参数数列表要么不要定义，要定义就要定义完整，不然BTrace没法处理不一样参数的同名函数。

若是有些参数你实在不想引入非JDK类，又不会形成同名函数不可区分，能够用AnyType来定义（不能用Object）。

若是拦截点用正则表达式中匹配了多个函数，函数之间的参数个数不同，你又仍是想把参数打印出来时，能够用AnyType[] args来定义。

但不知道是否是当前版本的bug，AnyType[] args 不能和 location＝Kind.RETURN 同用，不然会进入一种奇怪的静默状态，只要有一个函数定义错了，整个Btrace就什么都打印不出来。

结果：

同理，结果也能够用AnyType来定义，特别是用正则表达式匹配多个函数的时候，连void均可以表示。

 

4.2 打印

再次强调，为了保证性能不受影响，Btrace不容许调用任何实例方法。
好比不能调用getter方法（怕在getter里有复杂的计算），只会经过直接反射来读取属性名。
又好比，除了JDK类，其余类toString时只会打印其类名＋System.IdentityHashCode。
println, printArray，都按上面的规律进行，因此只能打打基本类型。

若是想打印一个Object的属性，用printFields()来反射。

若是只想反射某个属性，参照下面打印Port属性的写法。从性能考虑，应把field用静态变量缓存起来。

注意JDK类与非JDK类的区别：

import java.lang.reflect.Field;

//JDK的类这样写就行

private static Field fdFiled = field("java.io,FileInputStream", "fd");

 

//非JDK的类，要给出ClassLoader，不然ClassNotFound

private static Field portField = field(classForName("com.vip.demo.MyObject", contextClassLoader()), "port");

 

public static void onChannelRead(@Self Object self) {

    println("port:" + getInt(portField, self));

}

 

 

4.3.TLS，拦截函数间的通讯机制

若是要多个拦截函数之间要通讯，可使用@TLS定义 ThreadLocal的变量来共享

@TLS

private static int port = -1;

 

@OnMethod(clazz = "java.net.ServerSocket", method = "<init>")

public static void onServerSocket(int p){

    port = p;

}

@OnMethod(clazz = "java.net.ServerSocket", method = "bind")

public static void onBind(){

  println("server socket at " + port);

}

 

5. 典型场景

5.1 打印慢调用

下例打印全部用时超过1毫秒的filter。

@OnMethod(clazz = "+com.vip.demo.Filter", method = "doFilter", location = @Location(Kind.RETURN))

public static void onDoFilter2(@ProbeClassName String pcn,  @Duration long duration) {

    if (duration > 1000000) {

        println(pcn + ",duration:" + (duration / 100000));

    }

}

最好能抽取了打印耗时的函数，减小代码重复度。

定位到某一个Filter慢了以后，能够直接用Location(Kind.CALL)，进一步找出它里面的哪一步慢了。

 

5.2 谁调用了这个函数

好比，谁调用了System.gc() ?

@OnMethod(clazz = "java.lang.System", method = "gc")

public static void onSystemGC() {

    println("entered System.gc()");

    jstack();

}

 

5.3 捕捉异常，或进入了某个特定代码行时，this对象及参数的值

按以前的提示，本身组合一下便可。

 

5.4 打印函数的调用/慢调用的统计信息

若是你已经看到了这里，那基本也不用我再啰嗦了，本身看Samples的Histogram.java,HistoOnEvent.java

能够用AtomicInteger构造计数器，而后定时(@OnTimer)，或根据事件(@OnEvent)输出结果(ctrl+c后选择发送事件)。