5.11.4　出力レベルがverboseの場合に出力される内容

(a)　出力の契機

Explicitメモリブロックの初期化です。

(b)　出力形式

[EVO]<ctime>[Created]["<EM_NAME>" eid=<EID>(<EM_PTR>)/<EM_TYPE>]

(c)　出力項目

「(b)　出力形式」で示した各項目について説明します。

(d)　出力例

出力例を示します。

[EVO]<Tue Jul 24 01:23:51 2007>[Created]["BasicExplicitMemory-2" eid=2(0x1234568)/B]

この出力例では次の内容が確認できます。

• 出力契機は2007年7月24日(火)1時23分51秒に実行されたExplicitメモリブロックの初期化です。

• 初期化されたExplicitメモリブロックの名称は「BasicExplicitMemory-2」です。

(a)　出力の契機

Explicitメモリブロックの上限に達して，Explicitメモリブロックの初期化に失敗した場合です。

(b)　出力形式

[EVO]<ctime>[Creation failed][EH: <EH_USED>(<EH_GARB>)/<EH_TOTAL>/<EH_MAX>][E/F/D: <AC_NUM>/<FL_NUM>/<DA_NUM>][Thread: <TH_PTR>]
[EVO][Thread: <TH_PTR>] at <FRAME><SOURCE>
…

(c)　出力項目

「(b)　出力形式」で示した各項目について説明します。

(d)　出力例

出力例を示します。

[EVO]<Tue Jul 24 01:23:51 2007>[Creation failed][EH: 12000K(0K)/15000K/30000K][E/F/D: 65535/0/0][Thread: 0x00035a60]
[EVO][Thread: 0x00035a60] at ExplicitMemory.registerExplicitMemory(Native Method)
[EVO][Thread: 0x00035a60] at BasicExplicitMemory.<init>(Unknown Source)
[EVO][Thread: 0x00035a60] at AllocTest.test(AllocTest.java:64)
[EVO][Thread: 0x00035a60] at java.lang.Thread.run(Thread.java:2312)

この出力例では次の内容が確認できます。

• 出力契機は2007年7月24日(火)1時23分51秒に発生したExplicitメモリブロックの初期化失敗です。最大数である65535個のExplicitメモリブロックがすでに存在しているため，新たなExplicitメモリブロックの初期化に失敗しました。

• AllocTest.javaの64行目で，BasicExplicitMemoryクラスのコンストラクタを実行して，Explicitメモリブロックの初期化を試みています。

(a)　出力の契機

Explicitメモリブロックのサブ状態がDisableになり，そのExplicitメモリブロックにオブジェクトを配置できなくなった場合です。

(b)　出力形式

[EVO]<ctime>[Alloc failed(Disable)][EH: <EH_USED>(<EH_GARB>)/<EH_TOTAL>/<EH_MAX>][E/F/D: <AC_NUM>/<FL_NUM>/<DA_NUM>][cause:<CAUSE>]\
["<EM_NAME>" eid=<EID>/<EM_TYPE>: <EM_USED>(<EM_GARB>)/<EM_TOTAL>][Thread: <TH_PTR>]
[EVO][Thread: <TH_PTR>] at <FRAME><SOURCE>
…

注

下線部分はNewの場合にだけ出力されます。

(c)　出力項目

「(b)　出力形式」で示した各項目について説明します。

(d)　出力例

出力例を示します。

[EVO]<Tue Jul 24 01:23:51 2007>[Alloc failed(Disable)][EH: 12000K(1258K)/15000K/30000K][E/F/D: 321/0/1][cause:GC]\
["ReferenceExplicitMemory-3" eid=3/R: 108K(20K)/108K]

この出力例では次の内容が確認できます。

• 出力契機は，2007年7月24日(火)1時23分51秒にExplicitメモリブロックのサブ状態がDisableになったことです。

• Explicitヒープは，12000Kが利用済み，15000Kが確保済みです。

• Explicitヒープの最大サイズは30000Kです。

• Explicitヒープ全体で有効なExplicitメモリブロックは322個です。このうち，サブ状態がEnableのものは321個，Disableのものは1個あります。

• サブ状態がDisableになった原因の処理は，GC発生時のExplicitメモリブロックへのオブジェクトの移動です。

• サブ状態がDisableになったのは，IDが"3"，名称が"ReferenceExplicitMemory-3"のExplicitメモリブロックです。

• "ReferenceExplicitMemory-3"では，108kのメモリを利用済みです。

(a)　出力の契機

Explicitメモリブロックにオブジェクトが生成された時です。

(b)　出力形式

[EVS]<ctime>[EH: <EH_USED_BF>-><EH_USED_AF>(<EH_TOTAL>/<EH_MAX>)][E/F/D: <AC_NUM>/<FL_NUM>/<DA_NUM>][cause:<CAUSE>]\
["<EM_NAME>" eid=<EID>/<EM_TYPE>: <EM_USED_BF>-><EM_USED_AF>(<EM_TOTAL>)]

(c)　出力項目

「(b)　出力形式」で示した各項目について説明します。

(d)　出力例

出力例を示します。

[EVS]<Thu Oct 21 14:55:50 2007>[EH: 150528K->150529K(150532K/1048576K)][E/F/D: 200/0/0][cause:New]["BEM" eid=2/B: 30K->31K(32K)]

この出力例では次の内容が確認できます。

• 出力契機は，2007年10月21日(木)14時55分50秒に実行された，Explicitメモリブロックへのオブジェクトの生成です。

• Explicitヒープにオブジェクトが生成されたため，Explicitヒープの利用済みサイズが150528Kから150529Kに変化しました。

• Explicitヒープへのオブジェクト生成後のExplicitヒープの確保済みサイズは150532Kです。最大サイズは1048576Kです。

• Explicitヒープへのオブジェクト生成後に，サブ状態がEnableであるExplicitメモリブロックの数は200個です。

• "BEM"という名称のメモリブロックの利用済みサイズが，30Kから31Kに変化しました。オブジェクト生成後のBEMの確保済みサイズは32Kです。

(a)　出力の契機

GC発生による，Explicitメモリブロックへのオブジェクトの移動です。

(b)　出力形式

<GC発生時のExplicitヒープ利用状況>※
[EVS]{["<EM_NAME>" eid=<EID>/<EM_TYPE>: <EM_USED_BF>-><EM_USED_AF>(<EM_TOTAL>)]}{1,5}
...

注

Explicitメモリブロックの情報が5個出力されるごとに改行されます。

注※

出力項目については，「5.11.3(1)　GC発生（Explicitヒープ利用状況出力）」を参照してください。

(c)　出力項目

「(b)　出力形式」で示した各項目について説明します。

(d)　出力例

出力例を示します。

[ENS]<Thu Oct 21 14:55:50 2007>[EH: 150528K->162816K(162816K/1048576K)][E/F/D: 200/0/0][cause:GC][CF: 0]
[EVS]["REM2" eid=2/R: 0K->88K(128K)]["REM3" eid=3/R: 30K->230K(256K)]["REM6" eid=6/R: 30K->2030K(2048K)]\
["Session1" eid=8/R: 30K->2530K(2548K)]["Session2" eid=10/R: 30K->2530K(2548K)]
[EVS]["Session3" eid=12/R: 30K->5030K(5048K)]

この出力例では次の内容が確認できます。

• 出力契機は2007年10月21日(木)14時55分50秒に発生したGCでの，Explicitヒープへのオブジェクトの移動です。

• Explicitヒープの利用済みサイズが，150528Kから162816Kに変化しました。

• GC実行後のExplicitヒープの確保済みサイズは162816Kです。最大サイズは1048576Kです。

• GC実行後に，サブ状態がEnableであるExplicitメモリブロックは200個あります。

• GCの種類はCopyGCです。

• 移動先になったExplicitメモリブロックは5個以上あります。このため，5個分出力されたところで1回改行されています。

• Explicitヒープへの2288K(162816K-150528K)移動の内訳は次のとおりです。

  • Explicitメモリブロック"REM2"(eid=2)に88K移動。

  • Explicitメモリブロック"REM3"(eid=3)に200K移動。

  • Explicitメモリブロック"REM6"(eid=6)に2000K移動。

  • Explicitメモリブロック"Session1"(eid=8)に2500K移動。

  • Explicitメモリブロック"Session2"(eid=10)に2500K移動。

  • Explicitメモリブロック"Session3"(eid=12)に5000K移動。

  なお，移動先は，すべてアプリケーションサーバの内部で利用されているExplicitメモリブロックです。

(a)　出力の契機

Explicitメモリブロックの明示解放処理です。

(b)　出力形式

<Explicitメモリブロックの明示解放処理>※
[EVS]{["<EM_NAME>" eid=<EID>/<EM_TYPE>: <EM_TOTAL>]}{1,5}
...

注

Explicitメモリブロックの情報が5個出力されるごとに改行されます。

注※

出力項目については，「5.11.3(2)　Explicitメモリブロックの明示解放処理」を参照してください。

(c)　出力項目

「(b)　出力形式」で示した各項目について説明します。

(d)　出力例

出力例を示します。

[ENS]<Tue Jul 24 01:23:51 2007>[EH: 150528K->149528K(162816K/1048576K), 0.1129602 secs][E/F/D: 523/0/0]\
[DefNew::Eden: 0K->0K(243600K)][DefNew::Survivor: 0K->0K(17400K)][Tenured: 103400K->103400K(556800K)][cause:Reclaim]
[EVS]["REM2" eid=3/R: 300K]["BEM3" eid=5/B: 300K]["BEM1" eid=7/B: 400K]

この出力例では次の内容が確認できます。

• 出力契機は2007年7月24日(火)1時23分51秒に発生したExplicitメモリブロックの明示解放処理です。

• Explicitメモリブロックの明示解放処理によって，Explicitヒープの利用済みサイズが150528Kから149528Kに減少しました。

• Explicitメモリブロックの明示解放処理後のExplicitヒープの確保済みサイズは162816Kです。最大サイズは1048576Kです。

• Explicitメモリブロックの明示解放処理には，0.1129602秒掛かりました。

• Explicitメモリブロックの明示解放処理後，サブ状態がEnableであるExplicitメモリブロックは523個あります。

• Explicitメモリブロックの明示解放処理によって，Javaヒープの各領域のメモリサイズは変化していません。つまり，Javaヒープへのオブジェクトの移動はありませんでした。

• 明示解放処理の対象になったのは，次のExplicitメモリブロックです。

  • 確保済みのメモリサイズが300KだったExplicitメモリブロック"REM2"(eid=3)

  • 確保済みのメモリサイズが300KだったExplicitメモリブロック"BEM3"(eid=5)

  • 確保済みのメモリサイズが400KだったExplicitメモリブロック"BEM1"(eid=7)

  なお，REM2は，アプリケーションサーバの内部で利用されていたExplicitメモリブロックです。BEM3とBEM1は，アプリケーションが利用していたExplicitメモリブロックです。

(a)　出力の契機

ExplicitMemory.finalize()メソッドによってExplicitメモリブロックの解放が予約された場合です。

(b)　出力形式

[EVO]<ctime>[Finalized]["<EM_NAME>" eid=<EID>/<EM_TYPE>: <EM_TOTAL>]

(c)　出力項目

「(b)　出力形式」で示した各項目について説明します。

(d)　出力例

出力例を示します。

[EVO]<Tue Jul 24 01:23:51 2007>[Finalized]["REM1" eid=3/R: 512K]

この出力例では次の内容が確認できます。

• 出力契機は2007年7月24日(火)1時23分51秒にExplicitメモリブロック"REM1"(eid=3)が解放予約されたことです。解放予約は，finalize()によって実行されました。

(a)　出力の契機

指定したクラスが明示管理ヒープ上への自動配置に成功した場合です。

(b)　出力形式

[EVA]<ctime> creation in explicit memory is succeeded. [class=<CLASSNAME>]

(c)　出力項目

「(b)　出力形式」で示した各項目について説明します。

(d)　出力例

出力例を示します。

[EVA]<Tue Jul 24 01:23:51 2007> creation in explicit memory is succeeded. [class=jp.co.sample.Main]

この出力例では次の内容が確認できます。

• 2007年7月24日(火)1時23分51秒に，明示管理ヒープ機能がクラスjp.co.sample.Mainを明示管理ヒープ上への自動配置に成功した場合です。