Java線上問題排查的BTrace詳解

BTrace 是什麼

BTrace 是檢查和解決線上的問題的殺器，BTrace 可以透過編寫腳本的方式，獲取程式執行過程中的一切信息，並且，注意了，不用重啟服務，是的，不用重啟服務。寫好腳本，直接用指令執行即可，不用動原程式的程式碼。

原理

總體來說，BTrace 是基於動態字節碼修改技術(Hotswap)來實現運行時java 程式的追蹤和替換。大體的原理可以用下面的公式來描述：Client(Java compile api + attach api) + Agent（腳本解析引擎+ ASM + JDK6 Instumentation） + Socket其實BTrace 就是使用了java attach api 附加agent.jar ，然後使用腳本解析引擎+asm來重寫指定類別的字節碼，再使用instrument 實現對原有類別的替換。

安裝與設定

#本次安裝與設定在 Linux Ubuntu 14.04 下進行。目前 BTrace 的最新版本為 1.3.9，程式碼託管在 [github] 上。 第一步，在github 上下載 releases 版 btrace-bin-1.3.9.tgz，zip 版的沒有 build 目錄。 第二步，解壓縮btrace-bin-1.3.9.tgz 到一個目錄即可，例如/home/fengzheng/soft/btrace , 到這一步其實可以用了，只是執行腳本的時候需要在btrace 指令前加上絕對路徑，如果想在任意目錄可執行，進行下一步第三步，配置環境變量，配置的環境變數包括JAVA_HOME和BTRACE_HOME ，例如我的設定如下：

export JAVA_HOME=/home/fengzheng/soft/jdk1.8.0_111
export JRE_HOME=${JAVA_HOME}/jre
export CLASSPATH=.:${JAVA_HOME}/lib:${JRE_HOME}/lib  
export PATH=${JAVA_HOME}/bin:$PATH
export BTRACE_HOME=/home/fengzheng/soft/btrace
export PATH=$PATH:$BTRACE_HOME/bin

##之後執行指令source /etc/profile ，使環境變數立即生效。接下來在任意目錄執行 btrace指令，都可以執行成功了。

簡單測試案例

btrace 最簡單的語法是btrace $pid script.java，所以需要知道要偵測的Java 程式的進程id，然後寫一個探測腳本即可。

1. 寫一個常駐記憶體的Java 程序，這裡寫了一個無限循環，每隔5秒鐘輸出一組計算結果，內容如下：

package kite.lab.utils;
/**
 * NumberUtil
 *
 * @author fengzheng
 * @date 2017/2/15
 */
public class NumberUtil {

    public int sum(){
        int result = 0;
        for(int i = 0; i< 100; i++){
            result += i * i;
        }
        return result;
    }

    public static void main(String[] args){
        while (true) {
            Thread.currentThread().setName("计算");
            NumberUtil util = new NumberUtil();
            int result = util.sum();
            System.out.println(result);
            try {
                Thread.sleep(5000);
            }catch (InterruptedException e){

            }
        }
    }
}

#順便說一下命令列編譯和執行Java 的過程：

編譯：javac -d . NumberUtil.java，定位到NumberUtil.java 所在目錄，然後執行此命令列，將會在目前目錄（.表示目前目錄）產生包名所示的目錄結構，kite/lab/utils/NumberUtil.class

執行：java kite.lab.utils.NumberUtil 即可　　

#2.執行上面的程式後，可用

jps 指令查看pid(一般情況下用哪個帳號啟動的程序，就要用哪個帳號執行jps ，root 帳號除外)，執行jps 指令看到如下結果：

root@ubuntu:/home/fengzheng/codes/btrace# jps
10906 Jps
10860 NumberUtil

3. 可以看到剛剛執行的java 行程為10860　　

4. 寫btrace 腳本，腳本內容簡單如下：

#

package kite;
import com.sun.btrace.annotations.*;
import static com.sun.btrace.BTraceUtils.Strings.strcat;
import static com.sun.btrace.BTraceUtils.jstack;
import static com.sun.btrace.BTraceUtils.println;
import static com.sun.btrace.BTraceUtils.str;

/**
 * NumberUtilBTrace
 *
 * @author fengzheng
 * @date 2017/6/20
 */
@BTrace
public class NumberUtilBTrace {

    @OnMethod(
            clazz="kite.lab.utils.NumberUtil",
            method="sum",
            location=@Location(Kind.RETURN)
    )
    public static void func(@Return int result) {
        println("trace: =======================");
        println(strcat("result:", str(result)));
        jstack();
    }
}

意思是在執行結束後（location=@Location(Kind.RETURN) 表示執行結束）輸出結果和堆疊資訊

5. 預編譯：執行前可以用預編譯指令檢查腳本的正確性，預編譯指令為btracec，它是一個javac-like 指令，btracec NumberUtilBTrace.java

#6. 呼叫命令列執行，btrace 10860 NumberUtilBTrace.java ，(如果要儲存到本地檔案中，可以使用轉向命令btrace 10860 NumberUtilBTrace.java > mylog.log )列印的資訊如下

##

trace: =======================
result:328350
kite.lab.utils.NumberUtil.sum(NumberUtil.java:16)
kite.lab.utils.NumberUtil.main(NumberUtil.java:27)

7. 按ctrl + c ，會給予退出提示，再按1 退出

###使用場景###

BTrace 是一个事后工具，所谓事后工具就是在服务已经上线了，但是发现存在以下问题的时候，可以用 BTrace。

1. 比如哪些方法执行太慢，例如监控执行时间超过1s的方法

2. 查看哪些方法调用了 System.gc() ，调用栈是怎样的

3. 查看方法参数或对象属性

4. 哪些方法发生了异常

多说一点，为了更好解决问题，最好还要配合事前准备和进行中监控，事前准备就是埋点嘛，在一些可能出现问题的方法中进行日志输出，进行中监控就是利用一些实时监控工具，例如 VisualVM 、jmc 这些带界面的工具或者 jdk 提供的命令行工具等，再高级一点的就是利用 Graphite 这样的Metrics 工具配合 web 界面展示出来。

使用限制

为了保证trace语句只读,最小化对被检测程序造成影响， BTrace对trace脚本有一些限制(比如不能改变被trace代码中的状态)

• BTrace class不能新建类, 新建数组, 抛异常, 捕获异常,

• 不能调用实例方法以及静态方法(com.sun.btrace.BTraceUtils除外)

• 不能将目标程序和对象赋值给BTrace的实例和静态field

• 不能定义外部, 内部, 匿名, 本地类

• 不能有同步块和方法

• 不能有循环

• 不能实现接口, 不能扩展类

• 不能使用assert语句, 不能使用class字面值

拦截方法定义

@OnMethod 可以指定 clazz 、method、location。由此组成了在什么时机（location 决定）监控某个类/某些类（clazz 决定）下的某个方法/某些方法(method 决定)。

如何定位

1. 精准定位

直接定位到一个类下的一个方法，上面测试用的例子就是

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 参数，告诉脚本实际匹配到的类和方法名。

3. 按接口或继承类定位

例如要匹配继承或实现了 com.kite.base 的接口或基类的，只要在类前加上 + 号就可以了，例如

@OnMethod(clazz="+com.kite.base", method="doSome")

4. 按注解定位

在前面加上 @ 即可，例如@OnMethod(clazz="@javax.jws.WebService", method="@javax.jws.WebMethod")　　

拦截时机

拦截时机由 location 决定，当然也可为同一个定位加入多个拦截时机，即可以在进入方法时拦截、方法返回时拦截、抛出异常时拦截

1. Kind.Entry与Kind.Return

分别表示函数的开始和返回，不写 location 的情况下，默认为 Kind.Entry,仅获取参数值，可以用 Kind.Entry ，要获取返回值或执行时间就要用 Kind.Return

2. Kind.Error, Kind.Throw和 Kind.Catch

表示异常被 throw 、异常被捕获还有异常发生但是没有被捕获的情况，在拦截函数的参数定义里注入一个Throwable的参数，代表异常

@OnMethod(clazz = "com.kite.demo", location = @Location(value = Kind.LINE, line = 20))
public static void onBind() {
   println("执行到第20行");
}

@OnMethod(clazz = "java.net.ServerSocket", method = "bind", location =@Location(Kind.ERROR)) 
public static void onBind(Throwable exception, @Duration long duration){ }

3. Kind.Call 和 Kind.Line　　

Kind.Call 表示被监控的方法调用了哪些其他方法，例如：

@OnMethod(clazz = "com.kite",
            method = "login",
            location = @Location(value = Kind.CALL, clazz = "/.*/", method = "/.*/", where = Where.AFTER))
    public static void onBind(@Self Object self, @TargetInstance Object instance, @TargetMethodOrField String method, @Duration long duration){
        println(strcat("self: ", str(self)));
        println(strcat("instance: ", str(instance)));
        println(strcat("method: ", str(method)));
        println(strcat("duration(ms): ", str(duration / 1000000)));
    }

@Self 表示当前监控的函数所在类，如果是静态类则为空，@TargetInstance 表示函数中调用的方法或属性所在的类，如果是静态方法则为空，@TargetMethodOrField 表示调用的方法或属性，如果要获取执行时间，那么 where 必须设置为 Where.AFTER

Kind.Line 监测类是否执行到了设置的行数，例如：

@OnMethod(clazz = "com.kite.demo", location = @Location(value = Kind.LINE, line = 20))
public static void onBind() {

   println("执行到第20行");

}

几个例子

查看谁调用了GC

@OnMethod(clazz = "java.lang.System", method = "gc")
    public static void onSystemGC() {
        println("entered System.gc()");
        jstack();
    }

打印耗时超过100ms的方法

@OnMethod(clazz = "/com\\.kite\\.controller\\..*/",method = "/.*/",location = @Location(Kind.RETURN))
    public static void slowQuery(@ProbeClassName String pcn,@ProbeMethodName String probeMethod, @Duration long duration){
        if(duration > 1000000 * 100){
            println(strcat("类：", pcn));
            println(strcat("方法：", probeMethod));
            println(strcat("时长：", str(duration / 1000000)));
        }
    }

BTrace 提供了一系列的 sample, 可到 github 上查看。

注意问题

如果出现 Unable to open socket file: target process not responding or HotSpot VM not loaded 这个问题，可能的原因是执行 BTrace 脚本的用户和 Java 进程运行的用户不是同一个，使用 ps -aux | grep $pid查看一下 Java 进程的执行用户，保证和 BTrace 脚本执行用户相同即可　

以上是Java線上問題排查的BTrace詳解的詳細內容。更多資訊請關注PHP中文網其他相關文章！