JVM崩溃的原因及解决！

雨点打透心脏的1/2处 2021-09-10 10:58 434阅读 0赞

前些天，搞JNI的时候，报了个JVM崩溃的错。错误信息如下：

\#  
\# An unexpected error has been detected by HotSpot Virtual Machine:  
\#  
\#  EXCEPTION\_ACCESS\_VIOLATION (0xc0000005) at pc=0x009fcf52, pid=4752, tid=4440  
\#  
\# Java VM: Java HotSpot(TM) Client VM (1.5.0\_14-b03 mixed mode)  
\# Problematic frame:  
\# V  \[jvm.dll+0x9cf52\]  
\#  
\# An error report file with more information is saved as hs\_err\_pid4752.log  
\#  
\# If you would like to submit a bug report, please visit:  
\#   [http://java.sun.com/webapps/bugreport/crash.jsp][http_java.sun.com_webapps_bugreport_crash.jsp]  
\#  
我只不过是想通过C++生成一个Java的Date对象，然后输出当前时间。通过这点错误信息我们大概可以知道的是

JVM crash了，输出错误到hs\_err\_pid4752.log日志。

\----------------------------------------------------------------------------------------------------

结果运行死活都报这个错，也产生了一个log错误日志。其实运行一次产生一个，错都一样，我只举其中一个：

为了防止本机信息泄露，我把路径屏掉。

\#  
\# An unexpected error has been detected by HotSpot Virtual Machine:  
\#  
\#  EXCEPTION\_ACCESS\_VIOLATION (0xc0000005) at pc=0x009fcf52, pid=4344, tid=5876  
\#  
\# Java VM: Java HotSpot(TM) Client VM (1.5.0\_14-b03 mixed mode)  
\# Problematic frame:  
\# V  \[jvm.dll+0x9cf52\]  
\#

\---------------  T H R E A D  ---------------

Current thread (0x00823d30):  JavaThread "main" \[\_thread\_in\_vm, id=5876\]

siginfo: ExceptionCode=0xc0000005, reading address 0x00000000

Registers:  
EAX=0x00000000, EBX=0x06f8c0f8, ECX=0x0006f954, EDX=0x00823df0  
ESP=0x0006f934, EBP=0x0006f980, ESI=0x0006f954, EDI=0x0006f9e8  
EIP=0x009fcf52, EFLAGS=0x00010246

Top of Stack: (sp=0x0006f934)  
0x0006f934:   009eb893 00000000 00823d30 009ecac3  
0x0006f944:   00823d30 00000000 0006f9fc 0006f998  
0x0006f954:   00823df0 0082b438 009a1e20 00823d30  
0x0006f964:   0006f980 009ebb6a 00823d30 0000000e  
0x0006f974:   00000004 0006f9e8 0006f998 0006f9e8  
0x0006f984:   1000148b 00823df0 0082b434 00000000  
0x0006f994:   0006f9fc 0006fa5c 06f8c0f8 06f8c0f8  
0x0006f9a4:   cccccccc cccccccc cccccccc cccccccc

Instructions: (pc=0x009fcf52)  
0x009fcf42:   44 24 04 24 fc 8b 00 8b 00 c3 8b 44 24 04 24 fc  
0x009fcf52:   8b 00 ff 74 24 04 8b c8 e8 93 fe ff ff c3 8b 44

Stack: \[0x00030000,0x00070000),  sp=0x0006f934,  free space=254k  
Native frames: (J=compiled Java code, j=interpreted, Vv=VM code, C=native code)  
V  \[jvm.dll+0x9cf52\]  
C  \[NativeCode.dll+0x148b\]  
C  \[NativeCode.dll+0x1253\]  
j  com.sy.test.TestNative.sayHello()V+0  
j  com.sy.test.TestNative.main(\[Ljava/lang/String;)V+22  
v  ~StubRoutines::call\_stub  
V  \[jvm.dll+0x875dd\]  
V  \[jvm.dll+0xdfd96\]  
V  \[jvm.dll+0x874ae\]  
V  \[jvm.dll+0x8e6f1\]  
C  \[javaw.exe+0x14c5\]  
C  \[javaw.exe+0x3151\]  
C  \[kernel32.dll+0x16fd7\]

Java frames: (J=compiled Java code, j=interpreted, Vv=VM code)  
j  com.sy.test.TestNative.sayHello()V+0  
j  com.sy.test.TestNative.main(\[Ljava/lang/String;)V+22  
v  ~StubRoutines::call\_stub

\---------------  P R O C E S S  ---------------

Java Threads: ( => current thread )  
  0x008306d0 JavaThread "Low Memory Detector" daemon \[\_thread\_blocked, id=5624\]  
  0x0082fb30 JavaThread "CompilerThread0" daemon \[\_thread\_blocked, id=5988\]  
  0x0082e8c0 JavaThread "Signal Dispatcher" daemon \[\_thread\_blocked, id=2400\]  
  0x0082de70 JavaThread "Finalizer" daemon \[\_thread\_blocked, id=5704\]  
  0x0082ccf0 JavaThread "Reference Handler" daemon \[\_thread\_blocked, id=4240\]  
=>0x00823d30 JavaThread "main" \[\_thread\_in\_vm, id=5876\]

Other Threads:  
  0x0082a060 VMThread \[id=1960\]  
  0x00831270 WatcherThread \[id=5708\]

VM state:not at safepoint (normal execution)

VM Mutex/Monitor currently owned by a thread: None

Heap  
 def new generation   total 576K, used 209K \[0x02de0000, 0x02e80000, 0x032c0000)  
  eden space 512K,  40% used \[0x02de0000, 0x02e14510, 0x02e60000)  
  from space 64K,   0% used \[0x02e60000, 0x02e60000, 0x02e70000)  
  to   space 64K,   0% used \[0x02e70000, 0x02e70000, 0x02e80000)  
 tenured generation   total 1408K, used 0K \[0x032c0000, 0x03420000, 0x06de0000)  
   the space 1408K,   0% used \[0x032c0000, 0x032c0000, 0x032c0200, 0x03420000)  
 compacting perm gen  total 8192K, used 1715K \[0x06de0000, 0x075e0000, 0x0ade0000)  
   the space 8192K,  20% used \[0x06de0000, 0x06f8cdb0, 0x06f8ce00, 0x075e0000)  
No shared spaces configured.

Dynamic libraries:  
0x00400000 - 0x0040d000  \*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*

0x7c920000 - 0x7c9b4000  C:\\WINDOWS\\system32\\ntdll.dll  
0x7c800000 - 0x7c91d000  C:\\WINDOWS\\system32\\kernel32.dll  
0x77da0000 - 0x77e49000  C:\\WINDOWS\\system32\\ADVAPI32.dll  
0x77e50000 - 0x77ee2000  C:\\WINDOWS\\system32\\RPCRT4.dll  
0x77fc0000 - 0x77fd1000  C:\\WINDOWS\\system32\\Secur32.dll  
0x77d10000 - 0x77d9f000  C:\\WINDOWS\\system32\\USER32.dll  
0x77ef0000 - 0x77f38000  C:\\WINDOWS\\system32\\GDI32.dll  
0x77be0000 - 0x77c38000  C:\\WINDOWS\\system32\\MSVCRT.dll  
0x76300000 - 0x7631d000  C:\\WINDOWS\\system32\\IMM32.DLL  
0x62c20000 - 0x62c29000  C:\\WINDOWS\\system32\\LPK.DLL  
0x73fa0000 - 0x7400b000  C:\\WINDOWS\\system32\\USP10.dll  
0x6d710000 - 0x6d723000  C:\\PROGRA~1\\KASPER~1\\KASPER~1\\mzvkbd.dll  
0x76bc0000 - 0x76bcb000  C:\\WINDOWS\\system32\\PSAPI.DLL  
0x6d730000 - 0x6d743000  C:\\PROGRA~1\\KASPER~1\\KASPER~1\\mzvkbd3.dll  
0x6d020000 - 0x6d035000  C:\\PROGRA~1\\KASPER~1\\KASPER~1\\adialhk.dll  
0x77f40000 - 0x77fb6000  C:\\WINDOWS\\system32\\SHLWAPI.dll  
0x6d4c0000 - 0x6d4c6000  C:\\PROGRA~1\\KASPER~1\\KASPER~1\\kloehk.dll  
0x00960000 - 0x00afe000  \*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*

0x76b10000 - 0x76b3a000  C:\\WINDOWS\\system32\\WINMM.dll  
0x6d290000 - 0x6d298000 \*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*

0x6d610000 - 0x6d61c000  \*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*

0x6d310000 - 0x6d32d000 \*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*

0x6d630000 - 0x6d63f000  \*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*

0x10000000 - 0x1004e000  \*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*

VM Arguments:  
java\_command: com.sy.test.TestNative  
Launcher Type: SUN\_STANDARD

Environment Variables:  
JAVA\_HOME=\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*

CLASSPATH=\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*  
PATH=\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*\*  
USERNAME=user  
OS=Windows\_NT  
PROCESSOR\_IDENTIFIER=x86 Family 6 Model 14 Stepping 8, GenuineIntel

\---------------  S Y S T E M  ---------------

OS: Windows XP Build 2600 Service Pack 2

CPU:total 1 (cores per cpu 1, threads per core 1) family 6 model 14 stepping 8, cmov, cx8, fxsr, mmx, sse, sse2

Memory: 4k page, physical 1300464k(465904k free), swap 3092560k(2157304k free)

vm\_info: Java HotSpot(TM) Client VM (1.5.0\_14-b03) for windows-x86, built on Oct  5 2007 01:21:52 by "java\_re" with MS VC++ 6.0

看到就些错误日志就可以断定是由于我用Java的主函数调用本地dll文件时出了错。

我初步推断是因为我的C++产生Java对象传给Java类后，没有回收。导致内存泄露。

但是因为我是初学者，所以对C++控制Java还不熟，所以经过调试，C++编译通不过。由于最近考试压力

实在太大，迫使我先暂且放下这个问题。

但是不解决心里不爽，于是开始了搜索》》》》》》》》》》》》》》》漫长的信息检索》》》》》》》

发现新大陆》》》》》》》》》

以下内容，转载自[http://developers.sun.com.cn/blog/yutoujava/][http_developers.sun.com.cn_blog_yutoujava]

\-----------------------------------------------------------------------------------------------------

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\]  
...<more frames>...  
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”。

但是，将栈的尺寸调大，也意味着在有限的内存资源中，能打开的最大线程数会减少。

（\*\*\*\*\*\*俺的栈剩余还有free space=254k，显然不符，于是乎我决定假期再解决，o(∩\_∩)o...\*\*\*\*\*\*）

结论：

我觉得还是C++建立Java对象后，没有回收-----------------鉴定完毕

不知园子里可否有人有其他的建议。欢迎提出！

这个问题发到两个JavaQQ群都没动静，于是决定自己解决，看来越往后越要靠自己了。

[http_java.sun.com_webapps_bugreport_crash.jsp]: http://java.sun.com/webapps/bugreport/crash.jsp
[http_developers.sun.com.cn_blog_yutoujava]: http://developers.sun.com.cn/blog/yutoujava/