快捷導(dǎo)航

btrace定位生產(chǎn)故障的方法示例

更新時(shí)間：2017年02月13日 14:09:41 投稿：daisy

這篇文章主要介紹了btrace定位生產(chǎn)故障的方法示例，文中通過(guò)示例代碼介紹的很詳細(xì)，相信對(duì)大家具有一定的參考價(jià)值，需要的朋友們下面來(lái)一起看看吧。

前言

很多時(shí)候我們需要知道更多的程序的運(yùn)行細(xì)節(jié)，但又不可能在開(kāi)發(fā)的時(shí)候就把程序中所有的運(yùn)行細(xì)節(jié)都打印到日志上，通常這個(gè)時(shí)候能采取的就是修改代碼，重新部署，然后再觀察，但這種方法對(duì)于online應(yīng)用來(lái)說(shuō)不是很好，另外一方面如果碰到不好改的代碼，例如引用的其他的外部的包什么的，就很麻煩了，BTrace就是一個(gè)可以在不改代碼、不重啟應(yīng)用的情況下，動(dòng)態(tài)的查看程序運(yùn)行細(xì)節(jié)的工具，下面這篇文章就介紹了btrace定位生產(chǎn)故障的方法，需要的朋友們可以參考借鑒。

現(xiàn)象

某些請(qǐng)求通過(guò)數(shù)據(jù)訪問(wèn)層很慢并導(dǎo)致處理線程阻塞，從監(jiān)控中未能檢查到異常。

編寫btrace腳本

@BTrace
public class DBProxyTrace {

 @OnMethod(clazz = "xxx.xxx.QueryHandler", method = "query",
  location = @Location(Kind.RETURN))
 public static void trace2(String sql, @Duration long duration) {
 if (duration/1000000 > 10 * 1000) {
  com.sun.btrace.BTraceUtils.println(duration/1000000 + "ms");
  com.sun.btrace.BTraceUtils.println("this task executes more than 10s. the sql is : "
   + sql);
  com.sun.btrace.BTraceUtils.println("jstack is : ");
  com.sun.btrace.BTraceUtils.jstack();
 }
 }
}

判斷執(zhí)行大于10秒的sql和堆棧信息。

編譯腳本DBProxyTrace.Java，確認(rèn)腳本沒(méi)有問(wèn)題。

./bin/btracec -cp build/ java/DBProxyTrace.java

執(zhí)行腳本DBProxyTrace.class

./bin/btrace -cp build/ 17342 DBProxyTrace.class

信息

10468ms
this task executes more than 10s. the sql is : rollback
jstack is :
xxx.QueryHandler.query(QueryHandler.java:106)
xxx.net.AbstractConnection.onReadData(AbstractConnection.java:245)
xxx.net.NIOReactor$RW.run(NIOReactor.java:77)
java.lang.Thread.run(Thread.java:745)

定位

阻塞在事務(wù)回滾。

使用jstack進(jìn)一步定位。

打印JVM堆棧

"$_NIOREACTOR-7-RW" prio=10 tid=0x00007f069856f000 nid=0xde1 waiting for monitor entry [0x00007f0677011000]
 java.lang.Thread.State: BLOCKED (on object monitor)
 at Oracle.jdbc.driver.PhysicalConnection.rollback(PhysicalConnection.java:1167)
 - waiting to lock <0x000000068086fbc0> (a oracle.jdbc.driver.T4CConnection)

結(jié)論

阻塞在了oracle驅(qū)動(dòng)rollback動(dòng)作，這里其實(shí)是因?yàn)閛racle驅(qū)動(dòng)為了保證串行請(qǐng)求響應(yīng)而在底層加了鎖，而這個(gè)通道被慢語(yǔ)句塞住了，所以rollback塞了。

總結(jié)

以上就是這篇文章的全部?jī)?nèi)容了，希望本文的內(nèi)容對(duì)大家的學(xué)習(xí)或者工作能帶來(lái)一定的幫助，如果有疑問(wèn)大家可以留言交流。