若是是Java进程不知道什么缘由退出或被杀死,想要分析具体缘由,通常来讲分下面几步:java
1. 拿到Java应用程序的日志文件
2. 查找JVM的致命错误日志
3. 查找操做系统的core dump文件
4. 使用Dtrace查找“是谁杀死了Java进程
1. 拿到Java应用程序的日志文件算法
通常来讲日志文件中会有很应用相关的错误信息。Java进程异常退出的缘由最有可能就是应用程序自己的问题。所以检查Java应用程序的日志文件多是最快定位到错误的方法。shell
2. 查找JVM的致命错误日志 bash
若是应用程序日志文件中没有发现什么线索。那么还能够查看 JVM的致命错误日志。有些致命的错误,好比JNI或虚拟机自己产生的错误,可能使得Java应用程序来不及写日志就退出了。这时候能够查一个以 "hs_err_pid" 开头的日志名,例如hs_err_pid1125.log,其中1125是进程号。这个文件中也记录了一些宝贵的信息来提供一些线索,特别是Java自身的一些Bug。这个文件通常为于当前的工做目录中。用户能够用find命令本身搜索到。jvm
3. 查找操做系统的core dump文件
做为被操做系统所调度的进程,Java进程也会在不一样的信号下产生Core Dump文件,例如Sig_ill和Seg_segv。这些很是严重的错误的确会使得Java虚拟机根原本不及产生任何日志就宕了。拿到core dump文件就可使用不少工具来分析具体缘由了,例如jmap, jstack等等均可以友好的进行Java进程的Core文件的分析。通常来讲,Core文件也放到进程的当前工做目录,用户能够用find命令搜索 “core”。另外能够用coreadm来预先指定core文件存放的地方以及文件名的格式,例如:coreadm -g /var/core/core.%f.%p.%t工具
可是,有不少状况,进程被杀死的缘由很复杂。有可能被别的进程之外杀掉,或被一些脚本不当心kill掉,或者被管理员(或入侵者kill -9)处理掉。这些状况都不会产生日志文件和core dump文件。这些状况很难跟踪。但若是是Solaris10下,可使用下面的Dtrace脚原本肯定“是谁杀死了Java进程”spa
#!/usr/sbin/dtrace -qs proc:::signal-send /args[1]->pr_pid == $1/ { printf("%s(pid:%d) is sending signal %d to %s"n", execname, pid, args[2],args[1]->pr_fname); }
如何运行(1125)是进程号
$ ./sig1.d 1125
sched(pid:0) is sending signal 24 to bc
sched(pid:0) is sending signal 24 to bc
bash(pid:3987) is sending signal 15 to bc
bash(pid:3987) is sending signal 15 to bc
bash(pid:3987) is sendg signal 9 to bc操作系统
-----------------------------------------------------------------------------------------------------------------------------------------线程
下面咱们来详细讨论如何分析JVM致命错误日志。
3d
1、 如何获得这个日志文件.
若是有一个严重的错误引发Java进程非正常退出,咱们叫Crash,这时候会产生一个日志文件。缺省状况下,这个文件会产生在工做目录下。可是,能够在Java启动参数经过下面的设置,来改变这个文件的位置和命名规则。例如:
java -XX:ErrorFile=/var/log/java/java_error_%p.log
就将这个错误文件放在/var/log/java下,而且以java_error_pid.log的形式出现。
2、产生错误的缘由形成严重错误的缘由有多种可能性。
Java虚拟机自身的Bug是缘由之一,可是这种可能不是很大。在绝大多数状况下,是因为系统的库文件、API或第三方的库文件形成的;系统资源的短缺也有可能形成这种严重的错误。在发生了Crash以后,若是没法定位根本缘由,也应该迅速找到解决方法。
三.对日志文件的分析首先要检查日志的文件头:
例以下面是从一个客户发过来的错误日志的文件头
-------------------------------------
#
# 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”意味着这是个栈溢出的错误。
另一个有用的信息就是:
# 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
文件头以后,是当前线程的DUMP信息,线程以后是JVM进程的DUMP信息,包括全部线程的状态、地址和ID。最后还有JVM状态,Heap状态,动态链接库等等的信息。这些烦乱的信息中,包含有很是有用的信息。下面咱们根据几个具体的实例来分析Java虚拟机Crash的典型例子。
四.内存回收引发的Crash。
内存回收引发的Crash有如下的特色:在日志文件头通常有“ EXCEPTION_ACCESS _VIOLATION”和“# Problematic frame: # V [jvm.dll+....”的信息,意味着这是在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的数值。
五.栈溢出引发的CrashJava代码引发的栈溢出,一般不会引发JVM的Crash,而是抛出一个Java异常:java.lang.StackOverflowError。可是在Java虚拟机中,Java的代码和本地C或C++代码公用相同的Stack。这样,在执行本地代码所形成的栈溢出,就有可能引发JVM的Crash了。 栈溢出引发的Crash会在日志的文件头中看到“EXCEPTION_STACK_OVERFLOW”字样。另外,在当前线程的Stack信息中也能发现一些信息。