======================================================
注:本文源代码点此下载
======================================================
java的应用有时候会因为各种原因crash,这时候会产生一个类似java_errorpid.log的错误日志。可以拿到了
这个日志,怎样分析crash的原因呢?下面我们来详细讨论如何分析java_errorpid.log的错误日志。
一. 如何得到这个日志文件
如果有一个严重的错误引起java进程非正常退出,我们叫crash,这时候会产生一个日志文件。缺省情况下,这个
文件会产生在工作目录下。但是,可以在java启动参数通过下面的设置,来改变这个文件的位置和命名规则。例如:
java -xx:errorfile=/var/log/java/java_error_%p.log
就将这个错误文件放在/var/log/java下,并且以java_error_pid.log的形式出现。
二.产生错误的原因
造成严重错误的原因有多种可能性。java虚拟机自身的bug是原因之一,但是这种可能不是很大。在绝大多数情况下,
是由于系统的库文件、api或第三方的库文件造成的;系统资源的短缺也有可能造成这种严重的错误。在发生了crash
之后,如果无法定位根本原因,也应该迅速找到work around的方法。
三.对日志文件的分析
首先要检查日志的文件头:例如,下面是从一个客户发过来的错误日志的文件头
-------------------------------------
#
# an unexpected error has been detected by hotspot virtual machine:
#
# exception_access_violation (0xc0000005) at pc=0x0815e87e, pid=7268, tid=4360
#
# java vm: java hotspot(tm) server vm (1.4.2_13-b06 mixed mode)
# problematic frame:
# v [jvm.dll+0x15e87e]
#
--------------------------------------
文件头中有很多有用的信息,“exception_access_violation ”意味着java应用crash的时候,
正在运行jvm自己的代码,而不是外部的java代码或其他类库代码。这种情况很可能是jvm的bug,但是
也不一定。除了“exception_access_violation ”,还有可能是别的信息,例如“sigsegv(0xb)”,
意味着jvm正在执行本地或jni的代码;“exception_stack_overflow”意味着这是个栈溢出的错误。
(**********看到这里我们知道我报错时正在运行jvm自己的代码,而不是外部的java代码或其他类库代码*********)
另外一个有用的信息就是:
# problematic frame:
# v [jvm.dll+0x15e87e]
它说明crash的时候,jvm正在从哪个库文件执行代码。除了“v”以外,还有可能是“c”、“j”、“v”、“j”。具体的表示意思如下:
frametype description:
c: native c frame
j: interpreted java frame
v: vmframe
v: vmgenerated stub frame
j: other frame types, including compiled java frames
(**********看到这里我们知道我报错时是v: vmframe这种情况*********)
文件头之后,是当前线程的dump信息,线程之后是jvm进程的dump信息,包括所有线程的状态、地址和id。最后还有jvm状态,
heap状态,动态连接库等等的信息。这些烦乱的信息中,包含有非常有用的信息。下面我们根据几个具体的实例来分析java虚拟
机crash的典型例子。
四.内存回收引起的crash
内存回收引起的crash有以下的特点:在日志文件头一般有“ exception_access _violation”和
“# problematic frame: # v [jvm.dll+....”的信息,意味着这是在jvm内部处理,而且多半是jvm的bug。
(**********看到这里我们知道我报错时意味着这是在jvm内部处理,而且多半是jvm的bug*********)
对于这类问题,最快的方法就是绕过它。
另外,在thread的dump信息最后,还能看到有关内存回收的行为例如:
--------------- t h r e a d ---------------
current thread (0x00a56668): vmthread [id=4360]
siginfo: exceptioncode=0xc0000005, reading address 0x00000057
registers:
........
stack: [0x03cf0000,0x03d30000), sp=0x03d2fc18, free space=255k
native frames: (j=compiled java code, j=interpreted, vv=vm code, c=native code)
v [jvm.dll+0x15e87e]
vm_operation (0x063efbac): full generation collection, mode: safepoint, requested by thread 0x040f83f8
------------------------------------------------------------
可以清楚的看到jvm正在做 “full generation collection”。另外还有可能看到,其他的回收行为:
generation collection for allocation
full generation collection
parallel gc failed allocation
parallel gc failed permanent allocation
parallel gc system gc
(***********这些错,俺都没碰到***********)
对于内存回收的错误,一般采取改变回收的算法和参数的方法来绕过去。例如,来自客户的日志除了上面的
日志信息,在日志中heap信息中还能发现一些其他信息:
--------------------------------------------------------------
heap
def new generation total 22592k, used 19530k [0x10010000, 0x11890000, 0x138f0000)
eden space 20096k, 97% used [0x10010000, 0x11322bd8, 0x113b0000)
from space 2496k, 0% used [0x113b0000, 0x113b0000, 0x11620000)
to space 2496k, 0% used [0x11620000, 0x11620000, 0x11890000)
tenured generation total 190696k, used 100019k [0x138f0000, 0x1f32a000, 0x30010000)
the space 190696k, 52% used [0x138f0000, 0x19a9cf38, 0x19a9d000, 0x1f32a000)
compacting perm gen total 38656k, used 38588k [0x30010000, 0x325d0000, 0x34010000)
the space 38656k, 99% used [0x30010000, 0x325bf038, 0x325bf200, 0x325d0000)
----------------------------------------------------------------
上面的信息能看出在crash的时候,jvm的permsize空间几乎已经消耗完了,并且回收算法在压缩perm空间的时候出了错。
因此,建议改变内存回收的算法,或扩大permsize和maxpermsize的数值。
(*******这个倒是可以尝试*******)
五.栈溢出引起的crash
java代码引起的栈溢出,通常不会引起jvm的crash,而是抛出一个java异常:java.lang.stackoverflowerror。
但是在java虚拟机中,java的代码和本地c或c++代码公用相同的stack。这样,在执行本地代码所造成的栈溢出,
就有可能引起jvm的crash了。
栈溢出引起的crash会在日志的文件头中看到“exception_stack_overflow”字样。另外,在当前线程的stack
信息中也能发现一些信息。例如下面的例子:
-----------------------------------------------------------------------------------
# an unexpected error has been detected by hotspot virtual machine:
#
# exception_stack_overflow (0xc00000fd) at pc=0x10001011, pid=296, tid=2940
#
# java vm: java hotspot(tm) client vm (1.6-internal mixed mode, sharing)
# problematic frame:
# c [app.dll+0x1011]
#
--------------- t h r e a d ---------------
current thread (0x000367c0): javathread "main" [_thread_in_native, id=2940]
:
stack: [0x00040000,0x00080000), sp=0x00041000, free space=4k
native frames: (j=compiled java code, j=interpreted, vv=vm code, c=native code)
c [app.dll+0x1011]
c [app.dll+0x1020]
c [app.dll+0x1020]
:
c [app.dll+0x1020]
c [app.dll+0x1020]
......
java frames: (j=compiled java code, j=interpreted, vv=vm code)
j test.foo()v+0
j test.main([ljava/lang/string;)v+0
v ~stubroutines::call_stub
--------------------------------------------------------------------------------
在上面的信息中,可以发现这是个栈溢出的错误。并且当前栈剩余的空间已经很小了(free space =4k)。
因此建议将jvm的stack的尺寸调大,主要设计两个参数:“-xss” 和“-xx:stackshadowpages=n”。
但是,将栈的尺寸调大,也意味着在有限的内存资源中,能打开的最大线程数会减少。
======================================================
在最后,我邀请大家参加新浪APP,就是新浪免费送大家的一个空间,支持PHP+MySql,免费二级域名,免费域名绑定 这个是我邀请的地址,您通过这个链接注册即为我的好友,并获赠云豆500个,价值5元哦!短网址是http://t.cn/SXOiLh我创建的小站每天访客已经达到2000+了,每天挂广告赚50+元哦,呵呵,饭钱不愁了,\(^o^)/