FJVMでは「Java VM異常終了時のログ出力機能」の強化を行っています。
何らかの原因でJavaプロセスが異常終了した場合、Java VM異常終了時のログとしてFJVMログが出力されます。
Javaプロセスが異常終了した原因の調査のために、このFJVMログを活用することができます。
FJVMログの出力先
FJVMログは、Javaプロセスのカレントディレクトリに、以下のファイル名で出力されます。
fjvm_pid***.log (***は異常終了したJavaプロセスのプロセスID) |
IJServerクラスタ使用時のカレントディレクトリの詳細は、“Java EE 7 設計・構築・運用ガイド”の“IJServerクラスタ”を参照してください。
IJServer使用時のカレントディレクトリの詳細は、“J2EE ユーザーズガイド(旧版互換)”の“J2EEアプリケーションが運用される環境(IJServer)”を参照してください。
FJVMログの調査
FJVMログとしてJavaプロセス異常終了時の各種情報が格納されますが、その中から以下の情報を原因調査用の情報として使用することができます。
異常終了箇所に関する情報
異常終了箇所に関する情報が確認できます。
|
調査手順
「異常終了箇所に関する情報」の1.~3.の情報で異常終了した関数を特定し、実行しているJavaアプリケーションから呼び出す関数かどうかを確認します。ただし、2.の異常終了した関数名として出力される名前は、異常終了したアドレスに一番近いシンボル名情報であるため、実際に異常終了した関数とは別の名前が出力されている場合がありますので注意してください。
そして、実行しているJavaアプリケーションが使用する関数の場合には、当該関数使用に際して何らかの問題がないか確認します。
実行しているJavaアプリケーションで使用していない関数の場合には、4.のスタックトレースを調査します。
スタックトレース情報の最初のメソッドがネイティブメソッドだった場合はJNI処理に関係した問題である可能性が高いため、スタックトレース情報で出力された処理のJNI処理に関わる制御で何らかの問題がないか確認します。
また異常終了した関数を含むライブラリ名が利用者作成のライブラリである場合は、利用者側作成のライブラリ内の問題である可能性が高いため、当該ライブラリ内の処理および当該ライブラリを呼び出すJNI処理に何らかの問題がないか確認します。
スタックトレースの調査方法は、“10.5.1 スタックトレース”を参照してください。
スタックオーバーフローの検出
「異常終了箇所に関する情報」の1.の異常終了時に発生した例外に関する情報に、以下のシグナルコードの表記がある場合、例外が発生したスレッドでスタックオーバーフローが発生した( a)、b)の表記)、または発生した可能性がある(c)、d)の表記)ことを示しています。
この場合、例外が発生したスレッドに対するスタックのサイズを大きくすることで問題が解決する可能性があります。
スタックオーバーフロー発生の原因が、Java APIで生成されたスレッドに対するスタックのサイズにある場合は、“10.4.2 スタックのチューニング”を参照して、Java APIで生成されるスレッドに対するスタックのサイズをチューニングしてください。
スタックオーバーフローを示すシグナルコード
|
注意
Windowsエラー報告(Windows Error Reporting(WER))の分析
スタックオーバーフローが原因で発生した異常終了の場合、OS側からFJVM側の処理へ制御が渡らず、そのままWindowsエラー報告へ制御が渡されることがあります。この場合は、FJVMログが出力されないため、Windowsエラー報告を確認してください。
Windowsエラー報告に以下の例外番号が出力されている場合には、スタックオーバーフローが原因と考えられます。
なお、Windowsエラー報告の説明は、“10.5.8.1 クラッシュダンプ”を参照してください。
スタックオーバーフローを示す例外番号
c00000fd (スタックオーバーフロー) |
Java VMの実行制御で必要となる“表10.9 Java VMの制御で必要となるシグナル”の各シグナルに対するシグナルハンドラ情報が確認できます。
Solaris版Java VM | Linux版Java VM |
---|---|
SIGSEGV | SIGSEGV |
-Xrsオプションで操作対象となるシグナルです。
Java VMでリザーブしているシグナルです。
Java VMで使用されているシグナルです。
シグナルハンドラ情報として、以下の情報が出力されています。
登録されているシグナルハンドラのアドレス
sa_maskおよびsa_flags
“表10.9 Java VMの制御で必要となるシグナル”のシグナルハンドラがJava VM以外の処理で登録されていた場合、Java VMは正常に動作しません。この場合、当該シグナルハンドラを登録しないようにアプリケーションを修正してください。
異常終了時のJavaヒープの使用状況が確認できます。
Javaヒープのサイズによる異常終了の場合、異常終了時にどのJavaヒープの枯渇により異常終了が発生したかが確認できます。
出力例を元に説明します。
-------------------------------------------------------------------------------- # 1.A fatal error has been detected by the Java Runtime Environment: # 2. SIGSEGV (0xb) at pc=0xffffffff1df00684, pid=7131, nid=2, tid=0x000000010010f800 # 3.JRE version: Java(TM) SE Runtime Environment (8.0_152-b16) (build 1.8.0_152-b16_Fujitsu_02-09-2018) 4.Java VM: Java HotSpot(TM) 64-Bit Server VM (1.8.0_152_FUJITSU_MODIFIED-B10[25.152.16] mixed mode solaris-sparc compressed oops) # 5.Problematic frame: 6.C [libfjapp.so+0x684] Java_com_fujitsu_jdk_ap_GW00D00100_calcStatus+0x14 # 7.Core dump written. Default location: /tmp/core or core.7131 #
(1) 異常終了箇所の情報 異常終了箇所に関する情報が確認できます。 |
Unexpected Signal : SIGSEGV [0xb] occurred at PC=0xffffffff1df00684, pid=7131, nid=2 An unexpected exception has been detected in native code outside the VM. Function=Java_com_fujitsu_jdk_ap_GW00D00100_calcStatus+0x14 Library=/tmp/libfjapp.so+0x684 Current Java thread: at com.fujitsu.jdk.ap.GW00D00100.calcStatus(Native Method) at com.fujitsu.jdk.ap.AL00C00253.jni_calc(AL00A00001.java:29) at com.fujitsu.jdk.ap.AL00B00221.execute(AL00A00001.java:21) at com.fujitsu.jdk.ap.AL00A00001.main(AL00A00001.java:13) Local Time = Fri Feb 23 10:13:19 2018 Elapsed Time = 0 ~~~~~~~ 略 ~~~~~~~ ##>> Process: ~~~~~~~ 略 ~~~~~~~
(2)異常終了時のJavaヒープ領域に関する情報 異常終了時のJavaヒープ領域およびメタスペースに関する情報が確認できます。 |
Heap: PSYoungGen total 2048K, used 467K [0xffffffff67980000, 0xffffffff67c00000, 0xffffffff6a400000) eden space 1536K, 30% used [0xffffffff67980000,0xffffffff679f4c68,0xffffffff67b00000) from space 512K, 0% used [0xffffffff67b80000,0xffffffff67b80000,0xffffffff67c00000) to space 512K, 0% used [0xffffffff67b00000,0xffffffff67b00000,0xffffffff67b80000) ParOldGen total 5632K, used 0K [0xffffffff62400000, 0xffffffff62980000, 0xffffffff67980000) object space 5632K, 0% used [0xffffffff62400000,0xffffffff62400000,0xffffffff62980000) Metaspace used 2609K, capacity 4486K, committed 4864K, reserved 1056768K class space used 276K, capacity 386K, committed 512K, reserved 1048576K
(3)異常終了時のシグナルハンドラ情報 異常終了時のシグナルハンドラに関する情報が確認できます。 |
Signal Handlers: SIGSEGV: [libjvm.so+0xc57e54], sa_mask0=11111111011111111111110111111111, sa_flags=SA_ONSTACK|SA_RESTART|SA_SIGINFO SIGBUS: [libjvm.so+0xc57e54], sa_mask0=11111111011111111111110111111111, sa_flags=SA_RESTART|SA_SIGINFO SIGFPE: [libjvm.so+0xa2ef80], sa_mask0=11111111011111111111110111111111, sa_flags=SA_RESTART|SA_SIGINFO SIGPIPE: [libjvm.so+0xa2ef80], sa_mask0=11111111011111111111110111111111, sa_flags=SA_RESTART|SA_SIGINFO SIGXFSZ: [libjvm.so+0xa2ef80], sa_mask0=11111111011111111111110111111111, sa_flags=SA_RESTART|SA_SIGINFO SIGILL: [libjvm.so+0xa2ef80], sa_mask0=11111111011111111111110111111111, sa_flags=SA_RESTART|SA_SIGINFO INTERRUPT_SIGNAL: SIG_DFL, sa_mask0=00000000000000000000000000000000, sa_flags=none ASYNC_SIGNAL: SIG_DFL, sa_mask0=00000000000000000000000000000000, sa_flags=none SIGQUIT: [libjvm.so+0xa298f0], sa_mask0=11111111011111111111110111111111, sa_flags=SA_RESTART SIGHUP: [libjvm.so+0xa298f0], sa_mask0=11111111011111111111110111111111, sa_flags=SA_RESTART SIGINT: [libjvm.so+0xa298f0], sa_mask0=11111111011111111111110111111111, sa_flags=SA_RESTART SIGTERM: [libjvm.so+0xa298f0], sa_mask0=11111111011111111111110111111111, sa_flags=SA_RESTART ALT_INTERRUPT_SIGNAL: [libjvm.so+0xa2efd0], sa_mask0=00000000000000000000000000000000, sa_flags=SA_SIGINFO ALT_ASYNC_SIGNAL: [libjvm.so+0xa2ef80], sa_mask0=11111111011111111111110111111111, sa_flags=SA_RESTART|SA_SIGINFO