18.6.3 DABroker Libraryのスプール情報の内容
DABroker Libraryのスプール情報の調査方法について説明します。
DABroker Libraryのスプール情報には,次の種類があります。これらの情報は,<DABroker Libraryの運用ディレクトリ>\spool(Windowsの場合),または<DABroker Libraryの運用ディレクトリ>/spool(UNIXの場合)に出力されています。
スプール情報の種類 |
対応するファイル |
---|---|
メッセージログ |
dabsv.log dabsv.logold |
拡張データベースアクセストレース |
db_accessディレクトリ下のファイル |
エラートレース |
dabevttrcerr1.txt(ログ出力用ファイル) dabevttrcerr2.txt(バックアップ用ファイル)※1 |
Exceptionトレースログ |
J2EEサーバ名称+MTrc.logおよびJ2EEサーバ名称+MTrcOLD.log |
イベントトレース※2 |
dabevttrcXXX1およびdabevttrcXXX2 |
XAトレース |
db_xainfo.log |
それぞれの情報について説明します。
また,このほか,DABroker Libraryのログとしては,次のJDBCドライバのトレースが取得できます。これらのトレースは,アプリケーション開発時に設定されている場合だけ出力されます。
-
JDBCドライバのトレース(JDBCインタフェースメソッドトレース)
-
JDBCドライバのトレース(DABroker Libraryとのイベントトレース)
- 〈この項の構成〉
(2) 拡張データベースアクセストレース
データベースへの接続から切断までのアクセス情報を出力したトレースです。データベースへの接続ごとに出力されます。
拡張データベースアクセストレースは,次のように利用できます。
-
データベースのアクセス中に発生したトラブルの迅速な切り分け
-
24時間稼働に対応するJ2EEアプリケーションの稼働状態でのトラブル調査
-
マルチスレッド環境でJ2EEアプリケーションが動作している場合のスレッドごとのデータベースアクセス履歴取得
-
コネクションプーリングなどの機能を使用している場合のデータベースへの接続ごとのデータベースアクセス履歴取得
-
トラブルが再現調査できない場合の常時トレース取得
なお,出力するイベントの種類や,トレースファイルの最大容量などは,DABroker Libraryの[環境設定ユティリティ],またはDABroker Library動作環境定義ファイルで定義しておきます。DABroker Libraryの動作環境の設定については,Windowsの場合は「18.3.1(3) DABroker Libraryの動作環境の設定(Windowsの場合)」,UNIXの場合は「18.3.2(4) DABroker Libraryの動作環境の設定(UNIXの場合)」を参照してください。DABroker Library動作環境定義ファイルの詳細については,「18.24.1 DABroker Library動作環境定義ファイル」を参照してください。
(a) 出力形式
拡張データベースアクセストレースのファイル出力形式を次に示します。斜体は可変の情報です。拡張データベースアクセストレースは,次の2種類の情報で構成されます。
-
ヘッダ情報
-
個々のトレース情報
個々のトレース情報は,出力項目を示すタイトル行の下に出力されます。
-
Windowsの場合
*-------------------------------------------------------* *- DataBase Access Information (DRV) -* *- Date YYYY/MM/DD hh:mm:ss.nnnnnn -* *-------------------------------------------------------* DABroker Connect ID : AAAAA(BBBBB) Process ID : CCCCC UserID : DDDDD Client Name : EEEEE (DB別出力情報) Lang Mode : GGGGG THREAD-ID CID EVT START-TIME END-TIME RETCODE BLOCKCNT (Windows QueryPerformance Counter) HiRDB_CONNECTION_ID (トレース情報) (SQL)SS…SS (トレース情報)
-
UNIXの場合
*-------------------------------------------------------* *- DataBase Access Information (DRV) -* *- Date YYYY/MM/DD hh:mm:ss.nnnnnn -* *-------------------------------------------------------* DABroker Connect ID : AAAAA(BBBBB) Process ID : CCCCC UserID : DDDDD Client Name : EEEEE (DB別出力情報) Lang Mode : GGGGG THREAD-ID CID EVT START-TIME END-TIME RETCODE BLOCKCNT HiRDB_CONNECTION_ID (トレース情報) (SQL)SS…SS (トレース情報)
(b) 出力項目
拡張データベースアクセストレースの出力項目について,次の表に示します。斜体は可変の情報です。
(c) ファイル名称規則
拡張データベースアクセストレースのファイルは,次のディレクトリに作成されます。
-
Windowsの場合
<DABroker Library運用ディレクトリ>\spool\db_access
-
UNIXの場合
<DABroker Library運用ディレクトリ>/spool/db_access
拡張データベースアクセストレースのファイル名称は,次の規則に従います。斜体は可変の情報です。
spdb_XXXXXXXX_YYYYYYYY_HHHHHHHHHHHHHHHH_<接続データベース名称>ZZZZZ.IIIIII ファイル名称中の文字列について次に示します。
(3) エラートレース
エラートレースは,ログファイルに出力されるDABroker Libraryのエラー情報です。
エラートレースとして出力される情報を次に示します。
-
エラー事象を示す情報
エラーメッセージなどが出力されます。
-
データベース接続情報
データベース接続に関連する情報が出力されます。
-
履歴情報
エラーが発生する前に要求を受けた情報,前回コミットまたはロールバックが実行された時間,SQL文の実行要求処理中の実行中カーソルでの要求ごとの履歴情報などが出力されます。
(a) エラー事象を示す情報
エラー事象を示す情報の出力形式および出力項目について説明します。
- 出力形式
-
エラー事象を示す情報のファイル出力形式を次に示します。斜体は可変の情報です。
YYYY/MM/DD hh:mm:ss.sss TID=AAAA SS..SS エラー事象を示す情報の出力例を次に示します。
2004/05/07 13:30:09.555 TID=4040 KFPA11561-E Specified authorization identifier has no connect privilege
- 出力項目
-
エラー事象を示す情報の出力項目について,次の表に示します。
表18‒22 エラートレースのエラー事象を示す情報の出力項目 項目
意味
YYYY/MM/DD hh:mm:ss.sss
エラー発生日時が出力されます。
YYYY:西暦年,MM:月,DD:日
hh:時,mm:分,ss:秒,sss:ミリ秒
AAAA
エラー発生スレッドID(16進数値)が出力されます。
SS...SS
エラーメッセージが出力されます。
(b) データベース接続情報
データベース接続情報の出力形式および出力項目について説明します。
- 出力形式
-
エラートレースのデータベース接続情報のファイル出力形式を次に示します。斜体は可変の情報です。
YYYY/MM/DD hh:mm:ss.sss PID=BBBB InitID=CCCC ConID=DDDD CName=EEEE DBID=FFFF CID=GGGG(HHHH,IIII)
エラートレースのデータベース接続情報の出力例を次に示します。
2004/05/07 13:30:21.232 PID=1752 InitID=701 ConID=10701 CName=DABLIBAP DBID=1 CID=1(0,0)
- 出力項目
-
エラートレースのデータベース接続情報のファイル出力項目について,次の表に示します。
(c) 履歴情報
履歴情報の出力形式および出力項目について説明します。
- 出力形式
-
エラートレースの履歴情報のファイル出力形式を次に示します。
ヘッダ「START-TIME EXEC-TIME TID COUNT REQ-CODE DATA」の下に,次に示す形式でトレース情報が出力されます。斜体は可変の情報です。
YYYY/MM/DD hh:mm:ss.sss mmmm:ss.sss JJJJJJJJ KKKKKKKK LLLL MMMM NNNNNNNN OOOOOOOO PP..PP
エラートレースの履歴情報の出力例を以下に示します。
START-TIME EXEC-TIME TID COUNT REQ-CODE DATA 2004/05/07 15:37:16.733 0:00.030 1712 7 1022 NOW 00000000 00000001 2004/05/07 15:36:18.369 0:00.000 1712 6 1022 OLD 00000001 00000002 2004/05/07 15:35:59.191 0:00.281 1712 2 1021 PRE 00000000 00000002 INSERT INTO "HS 2004/05/07 15:36:18.369 0:00.000 1712 6 1022 EXE 00000001 00000002
- 出力項目
-
エラートレースの履歴情報のファイル出力項目について,次の表に示します。
表18‒24 エラートレースの履歴情報の出力項目 項目
意味
YYYY/MM/DD hh:mm:ss.sss
要求開始日時が出力されます。
YYYY:西暦年,MM:月,DD:日
hh:時,mm:分,ss:秒,sss:ミリ秒
mmmm:ss.sss
要求実行時間が出力されます。
mmmm:分,ss:秒,sss:ミリ秒
JJJJJJJJ
要求元スレッドID(16進数値)が出力されます。
KKKKKKKK
コネクト要求からの累計要求カウント数(16進数値)が出力されます。FETの場合は,SQL文実行時のFETの要求回数0xFFFFFFFFを超えた場合は,0から循環します。
LLLL
要求コード(10進数値)要求コードの詳細は,「表18-25 要求コード・応答コード一覧」を参照してください。
MMMM
大きくわけて次の3種類に分類されます。
-
共通履歴情報
NOW:実行中の情報
OLD:一つ前の要求の情報
-
SQL文の実行要求時に指定されたカーソルの履歴情報
SPRE:SQL PREPARE処理
EXE:SQL EXECUTE処理
FET1:SQL FETCH処理初回
FET:SQL FETCH処理
CLS:SQL CLOSE処理
-
コミット/ロールバック情報
CMT:コミット処理
RBK:ロールバック処理
詳細は,「表18-26 各履歴種別によるDATA内容」を参照してください。
NNNNNNNN
情報1(16進数値)
詳細は,「表18-26 各履歴種別によるDATA内容」を参照してください。
OOOOOOOO
情報2(16進数値)
詳細は,「表18-26 各履歴種別によるDATA内容」を参照してください。
PP..PP
詳細情報(任意文字列)
詳細は,「表18-26 各履歴種別によるDATA内容」を参照してください。
-
データベース接続情報に出力される,要求コードおよび応答コードの一覧を次の表に示します。
要求コード・応答コード (LLLL) |
種別 |
---|---|
1001 |
コネクション解放 |
1002 |
データベース接続 |
1003 |
データベース切り離し |
1004 |
Commit |
1005 |
Rollback |
1006 |
表一覧取得準備 |
1007 |
表一覧取得 |
1008 |
表一覧取得後処理 |
1009 |
列一覧取得準備 |
1010 |
列一覧取得 |
1011 |
列一覧取得後処理 |
1012 |
索引一覧取得準備 |
1013 |
索引一覧取得 |
1014 |
索引一覧取得後処理 |
1015 |
表権限一覧取得準備 |
1016 |
表権限一覧取得 |
1017 |
表権限一覧取得後処理 |
1018 |
列権限一覧取得準備 |
1019 |
列権限一覧取得 |
1020 |
列権限一覧取得後処理 |
1021 |
SQL実行準備 |
1022 |
SQL実行 |
1023 |
SQL実行結果取得 |
1024 |
SQL実行後処理 |
1035 |
SQL実行結果取得オプション |
1036 |
BLOBファイル削除 |
1037 |
プライマリキー一覧取得準備 |
1038 |
プライマリキー一覧取得 |
1039 |
プライマリキー一覧取得後処理 |
1040 |
BLOB,CLOBおよびBFILE分割取得 |
1041 |
自動Commit設定 |
1042 |
自動Commit解除 |
1050 |
XAトランザクション制御 |
1501 |
ストアドプロシジャ一覧取得準備 |
1502 |
ストアドプロシジャ一覧取得 |
1503 |
ストアドプロシジャ一覧取得後処理 |
1504 |
ストアドプロシジャ列一覧取得準備 |
1505 |
ストアドプロシジャ列一覧取得 |
1506 |
ストアドプロシジャ列一覧取得後処理 |
1507 |
ストアドプロシジャパラメタ定義情報取得 |
1508 |
ストアドプロシジャ実行準備 |
1509 |
ストアドプロシジャ実行 |
1510 |
ストアドプロシジャ拡張実行 |
2001 |
正常応答 |
2002 |
異常応答 |
データベース接続情報に出力される,各履歴種別によるDATA内容の一覧を次の表に示します。
履歴種別(MMMM) |
情報1(NNNNNNNN) |
情報2(OOOOOOOO) |
詳細情報(PP..PP) |
---|---|---|---|
PRE |
検索カラム数 |
?パラメタ定義数 |
SQLの先頭16バイト(マルチバイト文字コードの途中の場合はその文字は出力しません) |
EXE |
SQL実行件数※ |
?パラメタ指定数 |
− |
FET1 |
要求時検索件数 |
最大検索件数 |
− |
FET |
累計検索件数 |
要求時検索件数 |
− |
CLS |
0固定 |
0固定 |
− |
CMT |
− |
− |
− |
RBK |
− |
− |
− |
NOW |
今回の要求コードで取得した情報 |
− |
− |
OLD |
前回の要求コードで取得した情報 |
− |
− |
(4) Exceptionトレースログ
Exceptionトレースログは,メソッド実行時に障害が発生した場合に障害要因を分析するためのトレース情報です。障害発生時の問題個所の切り分けをするときに有効です。メソッドの呼び出し情報,メソッドの戻り値の情報,および例外(Exception)の情報が,製品名称などが出力されたヘッダと合わせて出力されます。
Exceptionトレースログとして出力される情報を次に示します。
Exceptionトレースログは,SQLException,BatchUpdateException,またはXAExceptionが発生したときに,環境変数に設定した設定情報を基に,Exceptionがthrowされる前にファイルに出力されます。Exceptionトレースを出力するメソッドと,ログ出力に関連する環境変数については,「18.6.2 DABroker Libraryで取得できるログの種類と出力先」を参照してください。
(a) 出力形式と出力項目
Exceptionトレースログの出力形式を,トレース取得レベル5の場合の出力例を基に説明します。
出力される内容について次の表で示します。項番は出力例中の番号と対応しています。
(b) 解析方法
ここでは,(1)で示した出力例を使用して,Exceptionトレースログの解析方法について説明します。
Exceptionトレースログは,次の手順で解析します。
-
解析するログの通番から,ログ情報を抜き出します。
-
ログ情報を,Thread識別情報で分類して,ログごとに分割します。
-
ログの取得時間によって,ログ情報を時系列に並べます。
手順1.〜3.を実施すると,次のような表を作成できます。
時間
スレッド1
スレッド2
スレッド3
スレッド4
Thread[main,5,main]
@1259414
Thread[Thread-0,5,main]
@30090737
Thread[Thread-1,5,main]
@5462872
Thread[Thread-2,5,main]
@25253977
2004/07/06 23:07:09.129
JdbcDbpsvConnection
@82c01f.createStatement(1)
−
−
−
2004/07/06 23:07:09.160
JdbcDbpsvStatement
@1e4cbc4.execute
−
−
−
2004/07/06 23:07:14.285
JdbcDbpsvConnection
@82c01f.commit
−
−
−
2004/07/06 23:07:14.301
JdbcDbpsvConnection
@82c01f.prepareStatement(1)
−
−
−
2004/07/06 23:07:26.567
JdbcDbpsvConnection
@82c01f.commit
−
−
−
2004/07/06 23:07:26.567
JdbcDbpsvStatement
@1e4cbc4.executeQuery
−
−
−
2004/07/06 23:07:26.567
JdbcDbpsvStatement
@1e4cbc4.execute
−
−
−
2004/07/06 23:07:28.332
JdbcDbpsvResultSet
@3eca90.close
JdbcDbpsvConnection
@82c01f.prepareStatement(1)
−
−
2004/07/06 23:07:28.332
JdbcDbpsvConnection
@82c01f.commit
−
−
−
2004/07/06 23:07:28.348
−
−
JdbcDbpsvConnection
@82c01f.prepareStatement(1)
−
2004/07/06 23:07:30.332
−
−
−
JdbcDbpsvConnection
@82c01f.rollback
2004/07/06 23:07:42.098
−
−
−
JdbcDbpsvConnection
@82c01f.close
2004/07/06 23:07:42.535
−
−
SQLException発生
KFDJ00002-E Statement or Connection is closed.
−
-
Exceptionエラーの内容を確認します。
2004/07/06 23:07:42.535のスレッド3でSQLExceptionが発生しています。メッセージの内容から,この時点でStatementオブジェクト,またはConnectionオブジェクトがすでにクローズされていることによるエラーだとわかります。
-
時系列にオブジェクトの流れを確認します。
2004/07/06 23:07:09.129のスレッド1,2004/07/06 23:07:28.332のスレッド2,2004/07/06 23:07:28.348のスレッド3,2004/07/06 23:07:30.332のスレッド4のConnectionオブジェクトのオブジェクトIDが同じであることから,四つのスレッドが同一コネクションで処理されていることがわかります。
-
エラーの原因となっている個所を探します。
四つのスレッドが同一コネクションであることがわかったため,Statement.closeメソッド,またはConnection.closeメソッドを実行している個所を探します。この例の場合は,スレッド4が2004/07/06 23:07:42.098でConnection.closeメソッドを実行しています。
このことから,2004/07/06 23:07:42.535のスレッド3で発生したSQLExceptionの原因は,スレッド4が2004/07/06 23:07:42.098でConnection.closeメソッドを実行していたためであることが,わかります。
(c) トレース取得レベルごとの出力例
トレース取得レベルごとの出力例を次に示します。
トレース取得レベル1の場合の出力例
[1] Cosminexus DABroker for Java , DABroker for Java Version 2 02-11-/D 2004/07/21 17:59:41.001 Thread[main,5,main]@2113601537 [E][JdbcDbpsvXADataSource@59920001.getXAConnection(1)] HANDLE(0) : SID(0) 2004/07/21 17:59:41.020 Thread[main,5,main]@2113601537 [E][JdbcDbpsvXAConnection@8bf8002.getXAResource] HANDLE(0) : SID(0) 2004/07/21 17:59:41.554 Thread[main,5,main]@2113601537 [R][JdbcDbpsvXAConnection@8bf8002.getXAResource] HANDLE(0) : SID(0) Return=JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvXAResource@33690002 2004/07/21 17:59:41.554 Thread[main,5,main]@2113601537 [R][JdbcDbpsvXADataSource@59920001.getXAConnection(1)] HANDLE(0) : SID(0) Return=JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvXAConnection@8bf8002 2004/07/21 17:59:41.557 Thread[main,5,main]@2113601537 [E][JdbcDbpsvXAConnection@8bf8002.getXAResource] HANDLE(0) : SID(0) 2004/07/21 17:59:41.557 Thread[main,5,main]@2113601537 [R][JdbcDbpsvXAConnection@8bf8002.getXAResource] HANDLE(0) : SID(0) Return=JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvXAResource@33690002 2004/07/21 17:59:41.561 Thread[main,5,main]@2113601537 [E][JdbcDbpsvXAConnection@8bf8002.getConnection] HANDLE(0) : SID(0) 2004/07/21 17:59:41.649 Thread[main,5,main]@2113601537 [R][JdbcDbpsvXAConnection@8bf8002.getConnection] HANDLE(883163352) : SID(0) Return=JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvConnection@78688002 2004/07/21 17:59:41.649 Thread[main,5,main]@2113601537 [E][JdbcDbpsvConnection@78688002.prepareStatement(1)] HANDLE(883163352) : SID(0) sql=INSERT INTO TRC_ex VALUES(?) 2004/07/21 17:59:41.819 Thread[main,5,main]@2113601537 [R][JdbcDbpsvConnection@78688002.prepareStatement(1)] HANDLE(883163352) : SID(1) Return=JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvPreparedStatement@d334002 2004/07/21 17:59:41.977 Thread[main,5,main]@2113601537 [E][JdbcDbpsvConnection@78688002.close] HANDLE(883163352) : SID(0) 2004/07/21 17:59:42.886 Thread[main,5,main]@2113601537 [R][JdbcDbpsvConnection@78688002.close] HANDLE(883163352) : SID(0) 2004/07/21 17:59:42.945 Thread[main,5,main]@2113601537 [E][JdbcDbpsvConnection@78688002.createStatement(1)] HANDLE(883163352) : SID(0) 2004/07/21 17:59:42.946 Thread[main,5,main]@2113601537:JP.co.Hitachi.soft.DBPSV_Driver.SQLException: KFDJ00006-E Connection is closed.[JdbcDbpsvConnection.createStatement(1)] at JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvConnection.createStatement(JdbcDbpsvConnection.java:529) at TRC_ex.main(TRC_ex.java:81)
トレース取得レベル2の場合の出力例
[1] Cosminexus DABroker for Java , DABroker for Java Version 2 02-11-/D 2004/07/21 19:00:56.992 Thread[main,5,main]@2113866091 [E][JdbcDbpsvXADataSource@5996096b.getXAConnection(1)] HANDLE(0) : SID(0) 2004/07/21 19:00:57.012 Thread[main,5,main]@2113866091 [E][JdbcDbpsvXAConnection@8bb8968.getXAResource] HANDLE(0) : SID(0) 2004/07/21 19:00:57.561 Thread[main,5,main]@2113866091 [R][JdbcDbpsvXAConnection@8bb8968.getXAResource] HANDLE(0) : SID(0) Return=JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvXAResource@336d0968 2004/07/21 19:00:57.561 Thread[main,5,main]@2113866091 [R][JdbcDbpsvXADataSource@5996096b.getXAConnection(1)] HANDLE(0) : SID(0) Return=JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvXAConnection@8bb8968 2004/07/21 19:00:57.565 Thread[main,5,main]@2113866091 [E][JdbcDbpsvXAConnection@8bb8968.getXAResource] HANDLE(0) : SID(0) 2004/07/21 19:00:57.565 Thread[main,5,main]@2113866091 [R][JdbcDbpsvXAConnection@8bb8968.getXAResource] HANDLE(0) : SID(0) Return=JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvXAResource@336d0968 2004/07/21 19:00:57.569 Thread[main,5,main]@2113866091 [E][JdbcDbpsvXAConnection@8bb8968.getConnection] HANDLE(0) : SID(0) 2004/07/21 19:00:57.660 Thread[main,5,main]@2113866091 [R][JdbcDbpsvXAConnection@8bb8968.getConnection] HANDLE(883163368) : SID(0) Return=JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvConnection@7862c968 2004/07/21 19:00:57.660 Thread[main,5,main]@2113866091 [E][JdbcDbpsvConnection@7862c968.prepareStatement(1)] HANDLE(883163368) : SID(0) sql=INSERT INTO TRC_ex VALUES(?) 2004/07/21 19:00:57.779 Thread[main,5,main]@2113866091 [R][JdbcDbpsvConnection@7862c968.prepareStatement(1)] HANDLE(883163368) : SID(1) Return=JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvPreparedStatement@d4dc968 2004/07/21 19:00:57.840 Thread[main,5,main]@2113866091 [E][JdbcDbpsvPreparedStatement@d4dc968.executeUpdate(1)] HANDLE(883163368) : SID(1) sql=INSERT INTO TRC_ex VALUES(?) 2004/07/21 19:00:57.854 Thread[main,5,main]@2113866091 [R][JdbcDbpsvPreparedStatement@d4dc968.executeUpdate(1)] HANDLE(883163368) : SID(1) Return=1 2004/07/21 19:00:57.854 Thread[main,5,main]@2113866091 [E][JdbcDbpsvConnection@7862c968.close] HANDLE(883163368) : SID(0) 2004/07/21 19:00:57.886 Thread[main,5,main]@2113866091 [R][JdbcDbpsvConnection@7862c968.close] HANDLE(883163368) : SID(0) 2004/07/21 19:00:57.916 Thread[main,5,main]@2113866091 [E][JdbcDbpsvConnection@7862c968.createStatement(1)] HANDLE(883163368) : SID(0) 2004/07/21 19:00:57.917 Thread[main,5,main]@2113866091:JP.co.Hitachi.soft.DBPSV_Driver.SQLException: KFDJ00006-E Connection is closed.[JdbcDbpsvConnection.createStatement(1)] at JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvConnection.createStatement(JdbcDbpsvConnection.java:529) at TRC_ex.main(TRC_ex.java:81)
- (凡例)
-
下線は,トレース取得レベル1ではトレースが出力されないメソッドの情報です。
トレースレベル3の場合の出力例
[1] Cosminexus DABroker for Java , DABroker for Java Version 2 02-11-/D 2004/07/21 19:01:28.755 Thread[main,5,main]@2113866075 [E][JdbcDbpsvXADataSource@5996095b.getXAConnection(1)] HANDLE(0) : SID(0) 2004/07/21 19:01:28.774 Thread[main,5,main]@2113866075 [E][JdbcDbpsvXAConnection@8bb8958.getXAResource] HANDLE(0) : SID(0) 2004/07/21 19:01:29.329 Thread[main,5,main]@2113866075 [R][JdbcDbpsvXAConnection@8bb8958.getXAResource] HANDLE(0) : SID(0) Return=JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvXAResource@336d0958 2004/07/21 19:01:29.329 Thread[main,5,main]@2113866075 [R][JdbcDbpsvXADataSource@5996095b.getXAConnection(1)] HANDLE(0) : SID(0) Return=JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvXAConnection@8bb8958 2004/07/21 19:01:29.332 Thread[main,5,main]@2113866075 [E][JdbcDbpsvXAConnection@8bb8958.getXAResource] HANDLE(0) : SID(0) 2004/07/21 19:01:29.332 Thread[main,5,main]@2113866075 [R][JdbcDbpsvXAConnection@8bb8958.getXAResource] HANDLE(0) : SID(0) Return=JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvXAResource@336d0958 2004/07/21 19:01:29.333 Thread[main,5,main]@2113866075 [E][JdbcDbpsvXAResource@336d0958.start] HANDLE(0) : SID(0) xid=MyXid@5ffe4958 flags=0 2004/07/21 19:01:29.372 Thread[main,5,main]@2113866075 [R][JdbcDbpsvXAResource@336d0958.start] HANDLE(0) : SID(0) 2004/07/21 19:01:29.389 Thread[main,5,main]@2113866075 [E][JdbcDbpsvXAConnection@8bb8958.getConnection] HANDLE(0) : SID(0) 2004/07/21 19:01:29.477 Thread[main,5,main]@2113866075 [R][JdbcDbpsvXAConnection@8bb8958.getConnection] HANDLE(883163368) : SID(0) Return=JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvConnection@78b60958 2004/07/21 19:01:29.478 Thread[main,5,main]@2113866075 [E][JdbcDbpsvConnection@78b60958.prepareStatement(1)] HANDLE(883163368) : SID(0) sql=INSERT INTO TRC_ex VALUES(?) 2004/07/21 19:01:29.571 Thread[main,5,main]@2113866075 [R][JdbcDbpsvConnection@78b60958.prepareStatement(1)] HANDLE(883163368) : SID(1) Return=JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvPreparedStatement@13934958 2004/07/21 19:01:29.614 Thread[main,5,main]@2113866075 [E][JdbcDbpsvPreparedStatement@13934958.executeUpdate(1)] HANDLE(883163368) : SID(1) sql=INSERT INTO TRC_ex VALUES(?) 2004/07/21 19:01:29.624 Thread[main,5,main]@2113866075 [R][JdbcDbpsvPreparedStatement@13934958.executeUpdate(1)] HANDLE(883163368) : SID(1) Return=1 2004/07/21 19:01:29.624 Thread[main,5,main]@2113866075 [E][JdbcDbpsvConnection@78b60958.close] HANDLE(883163368) : SID(0) 2004/07/21 19:01:29.656 Thread[main,5,main]@2113866075 [R][JdbcDbpsvConnection@78b60958.close] HANDLE(883163368) : SID(0) 2004/07/21 19:01:29.656 Thread[main,5,main]@2113866075 [E][JdbcDbpsvXAResource@336d0958.end] HANDLE(0) : SID(0) xid=MyXid@5ffe4958 flags=67108864 2004/07/21 19:01:29.658 Thread[main,5,main]@2113866075 [R][JdbcDbpsvXAResource@336d0958.end] HANDLE(0) : SID(0) 2004/07/21 19:01:29.659 Thread[main,5,main]@2113866075 [E][JdbcDbpsvXAResource@336d0958.prepare] HANDLE(0) : SID(0) xid=MyXid@5ffe4958 2004/07/21 19:01:29.675 Thread[main,5,main]@2113866075 [R][JdbcDbpsvXAResource@336d0958.prepare] HANDLE(0) : SID(0) Return=0 2004/07/21 19:01:29.675 Thread[main,5,main]@2113866075 [E][JdbcDbpsvXAResource@336d0958.commit] HANDLE(0) : SID(0) xid=MyXid@5ffe4958 onePhase=false 2004/07/21 19:01:29.681 Thread[main,5,main]@2113866075 [R][JdbcDbpsvXAResource@336d0958.commit] HANDLE(0) : SID(0) 2004/07/21 19:01:29.681 Thread[main,5,main]@2113866075 [E][JdbcDbpsvConnection@78b60958.createStatement(1)] HANDLE(883163368) : SID(0) 2004/07/21 19:01:29.682 Thread[main,5,main]@2113866075:JP.co.Hitachi.soft.DBPSV_Driver.SQLException: KFDJ00006-E Connection is closed.[JdbcDbpsvConnection.createStatement(1)] at JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvConnection.createStatement(JdbcDbpsvConnection.java:529) at TRC_ex.main(TRC_ex.java:81)
- (凡例)
-
背景色付きの太字は,トレース取得レベル1ではトレースが出力されないメソッドの情報です。
下線は,トレース取得レベル2ではトレースが出力されないメソッドの情報です。
トレース取得レベル5の場合の出力例
[1] Cosminexus DABroker for Java , DABroker for Java Version 2 02-11-/D 2004/07/21 18:58:47.122 Thread[main,5,main]@2113866026 [E][JdbcDbpsvXADataSource@5996092a.getXAConnection(1)] HANDLE(0) : SID(0) 2004/07/21 18:58:47.141 Thread[main,5,main]@2113866026 [E][JdbcDbpsvXAConnection@8bb8929.getXAResource] HANDLE(0) : SID(0) 2004/07/21 18:58:47.705 Thread[main,5,main]@2113866026 [R][JdbcDbpsvXAConnection@8bb8929.getXAResource] HANDLE(0) : SID(0) Return=JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvXAResource@336d0929 2004/07/21 18:58:47.706 Thread[main,5,main]@2113866026 [R][JdbcDbpsvXADataSource@5996092a.getXAConnection(1)] HANDLE(0) : SID(0) Return=JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvXAConnection@8bb8929 2004/07/21 18:58:47.709 Thread[main,5,main]@2113866026 [E][JdbcDbpsvXAConnection@8bb8929.getXAResource] HANDLE(0) : SID(0) 2004/07/21 18:58:47.709 Thread[main,5,main]@2113866026 [R][JdbcDbpsvXAConnection@8bb8929.getXAResource] HANDLE(0) : SID(0) Return=JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvXAResource@336d0929 2004/07/21 18:58:47.710 Thread[main,5,main]@2113866026 [E][JdbcDbpsvXAResource@336d0929.start] HANDLE(0) : SID(0) xid=MyXid@5ffe4929 flags=0 2004/07/21 18:58:47.750 Thread[main,5,main]@2113866026 [R][JdbcDbpsvXAResource@336d0929.start] HANDLE(0) : SID(0) 2004/07/21 18:58:47.770 Thread[main,5,main]@2113866026 [E][JdbcDbpsvXAConnection@8bb8929.getConnection] HANDLE(0) : SID(0) 2004/07/21 18:58:47.858 Thread[main,5,main]@2113866026 [R][JdbcDbpsvXAConnection@8bb8929.getConnection] HANDLE(883163368) : SID(0) Return=JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvConnection@78454929 2004/07/21 18:58:47.858 Thread[main,5,main]@2113866026 [E][JdbcDbpsvConnection@78454929.prepareStatement(1)] HANDLE(883163368) : SID(0) sql=INSERT INTO TRC_ex VALUES(?) 2004/07/21 18:58:47.952 Thread[main,5,main]@2113866026 [R][JdbcDbpsvConnection@78454929.prepareStatement(1)] HANDLE(883163368) : SID(1) Return=JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvPreparedStatement@d2ec929 2004/07/21 18:58:47.994 Thread[main,5,main]@2113866026 [E][JdbcDbpsvPreparedStatement@d2ec929.executeUpdate(1)] HANDLE(883163368) : SID(1) sql=INSERT INTO TRC_ex VALUES(?) 2004/07/21 18:58:47.994 Thread[main,5,main]@2113866026 [E][JdbcDbpsvPreparedStatement@d2ec929.execute(1)] HANDLE(883163368) : SID(1) sql=INSERT INTO TRC_ex VALUES(?) 2004/07/21 18:58:48.004 Thread[main,5,main]@2113866026 [R][JdbcDbpsvPreparedStatement@d2ec929.execute(1)] HANDLE(883163368) : SID(1) Return=false 2004/07/21 18:58:48.004 Thread[main,5,main]@2113866026 [R][JdbcDbpsvPreparedStatement@d2ec929.executeUpdate(1)] HANDLE(883163368) : SID(1) Return=1 2004/07/21 18:58:48.004 Thread[main,5,main]@2113866026 [E][JdbcDbpsvConnection@78454929.close] HANDLE(883163368) : SID(0) 2004/07/21 18:58:48.037 Thread[main,5,main]@2113866026 [R][JdbcDbpsvConnection@78454929.close] HANDLE(883163368) : SID(0) 2004/07/21 18:58:48.037 Thread[main,5,main]@2113866026 [E][JdbcDbpsvXAResource@336d0929.end] HANDLE(0) : SID(0) xid=MyXid@5ffe4929 flags=67108864 2004/07/21 18:58:48.039 Thread[main,5,main]@2113866026 [R][JdbcDbpsvXAResource@336d0929.end] HANDLE(0) : SID(0) 2004/07/21 18:58:48.039 Thread[main,5,main]@2113866026 [E][JdbcDbpsvXAResource@336d0929.prepare] HANDLE(0) : SID(0) xid=MyXid@5ffe4929 2004/07/21 18:58:48.053 Thread[main,5,main]@2113866026 [R][JdbcDbpsvXAResource@336d0929.prepare] HANDLE(0) : SID(0) Return=0 2004/07/21 18:58:48.053 Thread[main,5,main]@2113866026 [E][JdbcDbpsvXAResource@336d0929.commit] HANDLE(0) : SID(0) xid=MyXid@5ffe4929 onePhase=false 2004/07/21 18:58:48.059 Thread[main,5,main]@2113866026 [R][JdbcDbpsvXAResource@336d0929.commit] HANDLE(0) : SID(0) 2004/07/21 18:58:48.059 Thread[main,5,main]@2113866026 [E][JdbcDbpsvConnection@78454929.createStatement(1)] HANDLE(883163368) : SID(0) 2004/07/21 18:58:48.060 Thread[main,5,main]@2113866026:JP.co.Hitachi.soft.DBPSV_Driver.SQLException: KFDJ00006-E Connection is closed.[JdbcDbpsvConnection.createStatement(1)] at JP.co.Hitachi.soft.DBPSV_Driver.JdbcDbpsvConnection.createStatement(JdbcDbpsvConnection.java:529) at TRC_ex.main(TRC_ex.java:81)
- (凡例)
-
背景色付きの太字は,トレース取得レベル1ではトレースが出力されないメソッドの情報です。
下線は,トレース取得レベル2ではトレースが出力されないメソッドの情報です。
(5) イベントトレース
イベントトレースは,DABroker for C++をインストールしている場合に出力されます。
DABroker for C++の機能を使ってデータベースにアクセスする場合のイベントトレースが出力されます。イベントトレースは,次のファイルに取得されます。
-
Windowsの場合
<DABroker Library運用ディレクトリ>\spool\dabevttrccpp1
-
UNIXの場合
<DABroker Library運用ディレクトリ>/spool/dabevttrccpp1
なお,イベントトレースファイルが満杯になった場合,ラップアラウンドでトレースが出力されます。この場合は,バックアップファイル(dabevttrccpp2)が作成され,1世代だけ(dabevttrccpp1)が管理されます。
取得するイベントトレースの設定については,「18.7.4 データベース接続の設定」を参照してください。
(a) 出力形式
イベントトレースの出力形式について説明します。
ヘッダ「LEVEL VPID PID TID DATE TIME EVENT」の下に,次に示す形式でトレース情報が出力されます。
レベル 仮想プロセスID プロセスID スレッドID 日付 時刻 動作結果
イベントトレースの出力例を次に示します。
LEVEL VPID PID TID DATE TIME EVENT 40000010 00120000 711a 0001 2004/06/01 11:38:29.300000 イベント1 40000010 00120000 711a 0002 2004/06/01 11:38:30.100000 イベント2 40000010 00120000 711b 0001 2004/06/01 11:38:30.150000 イベント3 40000010 00120000 711b 0002 2004/06/01 11:38:30.250000 イベント4
(6) XAトレースの取得形式
XAインタフェースでのトラブルシュート情報は,XAトレースファイルとして次のファイルに出力されます。
-
Windowsの場合
<DABroker Library運用ディレクトリ>\spool\db_xainfo.log
-
UNIXの場合
<DABroker Library運用ディレクトリ>/spool/db_xainfo.log
(a) 出力形式
XAトレースファイルの出力形式を次に示します。斜体は可変の情報です。
日付 時刻 プロセスID:スレッドID イベント種別:XA関数名 rmid=rrrr flags=ffff RMName="nnnn" xa_info="iiii" xid=[ffff:gggg:bbbb:dddd] count=cccc return code=rrrr
XAトレースファイルの出力例を次に示します。
(b) 出力項目
XAトレースファイルの出力項目について,次の表に示します。
項目名 |
説明 |
---|---|
日付 |
出力した年月日(YYYY/MM/DD)が出力されます。
|
時刻 |
出力した時刻(hh:mm:ss:nnnnnn)が表示されます。
|
[プロセスID:スレッドID] |
プロセスIDとスレッドIDが出力されます。 |
[イベント種別:XA関数名] |
イベント種別と,XA関数の名前が出力されます。
|
rmid=rrrr |
RMIDが出力されます。 |
flags=ffff |
フラグパラメタが出力されます。 |
RMName="nnnn" |
RM名称が出力されます。 |
xa_info="iii " |
XA文字列が出力されます。 この項目は,XA関数が,xa_open(),またはxa_close()の場合だけ,出力されます。 |
xid=[ffff:gggg:bbbb:dddd] |
XIDが出力されます。 この項目は,XA関数が,xa_open(),またはxa_close()以外の場合だけ,出力されます。 ffff:フォーマットIDが出力されます。 gggg:グローバルトランザクションID長が出力されます。 bbbb:ブランチ修飾子長が出力されます。 dddd:XIDデータが出力されます。 |
count=cccc |
リカバリ情報取得カウントが出力されます。 この項目は,XA関数がxa_recover()の場合だけ,出力されます。 |
return code = rrrr |
XA関数の戻り値が出力されます。 この項目は,イベント種別が"end"の場合だけ出力されます。 |
(7) JDBCドライバのトレース(JDBCインタフェースメソッドトレース)
J2EEアプリケーションまたはバッチアプリケーション開発時に,DABroker LibraryのAPIを使用して,JDBCドライバのトレースを出力するように設定できます。
ここでは,JDBCドライバのトレースのうち,JDBCインタフェースメソッドのトレースについて説明します。
(a) 出力形式
JDBCインタフェースメソッドのトレースの出力形式を次に示します。斜体は可変の情報です。
[DABroker for Java Version 2] [JDBC Interface メソッド状態][JdbcDbpsvクラス名.メソッド名] 引数または戻り値=値
JDBCインタフェースメソッドのトレースの出力例を次の図に示します。
(b) 出力項目
JDBCインタフェースメソッドのトレースの出力項目について,次の表に示します。
(8) JDBCドライバのトレース(DABroker Libraryとのイベントトレース)
J2EEアプリケーションまたはバッチアプリケーション開発時に,DABroker LibraryのAPIを使用して,JDBCドライバのトレースを出力するように設定できます。
ここでは,JDBCドライバとDABroker Libraryとのイベントトレースについて説明します。
(a) 出力形式
DABroker Libraryとのイベントトレースの出力形式を次に示します。
[DABroker for Java Version 2] [Server Event イベント] 送信データ種別
DABroker Libraryとのイベントトレースの出力例を次に示します。
(b) 出力項目
DABroker Libraryとのイベントトレースの出力項目について,次の表に示します。
項目 |
説明 |
|
---|---|---|
[DABroker for Java Version 2] |
DABroker Libraryのトレースであることを示します。 |
|
[Server Event イベント] |
DABroker Libraryでのイベントを示します。 |
|
送信データ種別 |
送受信データ種別が,aa…aa <bb…bb>(cc…cc,dd…dd)SIZE:XXXの形式で出力されます |
|
aa…aa |
オペレーション種別が出力されます。 表示されるオペレーション種別とその意味は次のとおりです。
|
|
bb…bb |
付加情報が出力されます。 オペレーション種別が,LIST(xxxxx)およびEXT-CMDの場合に表示されます。 |
|
cc…cc |
リターンコードが出力されます。 |
|
dd…dd |
詳細コードが出力されます。 |
|
XXX |
DABroker LibraryでのイベントがPutの場合は,送信したデータのサイズが出力されます。 DABroker LibraryでのイベントがGetの場合は,受け取ったデータのサイズが出力されます。 |