G.2.4 searcherの性能ログ

searcherでは、以下の性能情報が出力されます。

共通情報

依頼種別、出力タイプにかかわらず、以下の情報が出力されます。

出力項目	説明
出力項目1（OutputTime）	性能ログ出力時刻
出力項目2（Id）	searcher識別子名
出力項目4（SearcherReqNo）	searcher依頼番号
出力項目5（RequestKind）	依頼種別（個別情報の表の値）
出力項目6（OutputType）	出力タイプ（個別情報の表の出力項目6）
出力項目7（OutputKind）	出力種別（個別情報の表の出力項目7）
出力項目18（ErrorInfo）	エラー発生時のエラーメッセージ

備考. 出力項目18は、エラー発生時のみ出力されます。

個別情報

個別情報は、依頼種別（RequestKind：出力項目5）、出力タイプ（OutputType：出力項目6）、出力種別（OutputKind：出力項目7）によって出力される情報が異なります。

依頼種別（出力項目5）	依頼内容
Search	検索（ShunSearch）
Update	更新（追加、更新、削除）
Switch	切替（コミット、ロールバック）
Condense	最適化（サーチデータファイルの最適化）

各依頼種別での出力内容を以下に示します。

3	5	6	7	8	9	10	11	12	13	14	15	16	17
RequestNo	RequestKind （依頼種別）	OutputType	OutputKind	RequestTime	Response	Result	Client	TransactionId	Records	RetRecords	RetDataSize	MultiCount	DetailInfo
－	Search	0	START	○	－	－	－	－	－	－	－	－	－
－		1	SUMMARY	○	○	○	－	－	－	－	－	○	SearchTime=実検索時間（秒）,TransmittingTime=送受信時間（秒）,ReadTime=サーチデータファイルの読込み時間(秒) (注1) SearchRecords=有効レコード件数 (注2), SearchDataSize=有効データ量（バイト）（注2）, AvgSearchTime=スレッドの平均実検索時間（秒）（注3）, MaxSearchTime=スレッドの最大実検索時間（秒）（注3）, SkipRecords=検索対象外としたレコード件数（注4）, SkipDataSize=検索対象外としたデータ量(バイト) (注4) AexprKwdCnt=キーワードから作成される論理式の数（統合前）, AexprKwdCntCnsd=キーワードから作成される論理式の数（統合後）
○		3	SEARCH-PHASE	－	－	○	－	－	○	－	－	－	－
－		4	SEARCHER-THREAD	－	－	○	－	－	－	－	－	○	No=スレッドの通し番号, SearchTime=実検索時間(秒), SearchRecords=有効レコード件数 (注2), SearchDataSize=有効データ量(バイト) （注2）, SkipRecords=検索対象外としたレコード件数（注4）, SkipDataSize=検索対象外としたデータ量（バイト）（注4）
－	Update	0	START	○	－	－	－	－	－	－	－	－	－
－		1	SUMMARY	○	○	○	－	－	－	－	－	○	UpdateTime=実更新時間（秒）,TransmittingTime=送受信時間（秒）,WriteTime=サーチデータファイルへの書込み時間(秒) (注1) ,EncodeTime=XMLデータの加工処理時間（秒）
○		3	UPDATE-PHASE	－	－	○	－	○	－	○	○	－	－
－	Switch	0	START	○	－	－	－	－	－	－	－	－	－
－		1	SUMMARY	○	○	○	－	－	－	－	－	○	UpdateTime=実更新時間（秒）,TransmittingTime=送受信時間（秒）
○		3	SWITCH-PHASE	－	－	○	－	○	－	－	－	－	Kind=COMMIT または ROLLBACK
－	Condense	0	START	○	－	－	－	－	－	－	－	－	－
－		1	SUMMARY	○	○	○	－	－	－	－	－	○	CondenseTime=実更新時間（秒）,TransmittingTime=送受信時間（秒）
○		3	CONDENSE-PHASE	－	－	○	－	○	－	○ (注5)	○ (注5)	－	－

○：出力対象の項目
－：出力しない項目

注1) システム用動作環境ファイルの“Director”に“SearcherType=Disk”もしくは、“SearcherMaxMemorySize”を指定している場合にのみ、以下の情報が出力されます。

ReadTime=サーチデータファイルの読込み時間（秒）
WriteTime=サーチデータファイルへの書込み時間（秒）

注2）searcher用動作環境ファイルの“PfmLogSearchTargetData”に“1”を指定している場合に、以下の情報が出力されます。

SearchRecords=有効レコード件数
SearchDataSize=有効データ量（バイト）

ただし、検索式が示すデータをSearcherが保持していない場合には、OutputKindがSEARCHER-THREADのSearchRecordsおよびSearchDataSizeが 0 となる場合があります。

注3）searcher用動作環境ファイルの“PfmLogParallelThread”に“1”を指定している場合に、以下の情報が出力されます。

AvgSearchTime=平均実検索時間（秒）
MaxSearchTime=最大実検索時間（秒）

注4）searcher用動作環境ファイルの“PfmLogSearchTargetData”に“1”を指定している場合に出力されます。それぞれ、有効レコード件数（SearchRecords）、有効データ量（SearchDataSize）のうち、検索スキップ機能によって検索対象外となったデータの情報が出力されます。

SkipRecords=検索対象外としたレコード件数
SkipDataSize=検索対象外としたデータ量(バイト)

ただし、検索式が示すデータをSearcherが保持していない場合には、OutputKindがSEARCHER-THREADのSkipReacordsおよびSkipDataSizeが 0 となる場合があります。

注5) ここでは、サーチデータの再配置を実施した結果、データの格納先がディスクからメモリに変更となったデータの情報が出力されます。

出力例

"OutputTime","Id","RequestNo","SearcherReqNo","RequestKind","OutputType","OutputKind","RequestTime","Response","Result","Client","TransactionId","Records","RetRecords","RetDataSize","MultiCount","DetailInfo","ErrorInfo"
"2007/03/11 17:44:10.290","shuns01",,"84128d9b000005b0","Update",0,"START","17:44:10.290",,,,,,,,,,
"2007/03/11 17:44:10.291","shuns01","c4128d9b00000001","84128d9b000005b0","Update",3,"UPDATE-PHASE",,,0,,"f4128d9b-c0000001",,304,622592,,,
"2007/03/11 17:44:10.291","shuns01",,"84128d9b000005b0","Update",1,"SUMMARY","17:44:10.291",0.024,0,,,,,,1,"UpdateTime=0.000, TransmittingTime=0.024, EncodeTime=0.008",
"2007/03/11 17:44:10.304","shuns01",,"84128d9b000005b1","Update",0,"START","17:44:10.304",,,,,,,,,,
"2007/03/11 17:44:10.305","shuns01","c4128d9b00000001","84128d9b000005b1","Update",3,"UPDATE-PHASE",,,0,,"f4128d9b-c0000001",,182,85378,,,
"2007/03/11 17:44:10.305","shuns01",,"84128d9b000005b1","Update",1,"SUMMARY","17:44:10.305",0.006,0,,,,,,1,"UpdateTime=0.000, TransmittingTime=0.006, EncodeTime=0.008",
"2007/03/11 17:44:10.328","shuns01",,"84128d9b000005b2","Switch",0,"START","17:44:10.328",,,,,,,,,,
"2007/03/11 17:44:10.352","shuns01","c4128d9b00000001","84128d9b000005b2","Switch",3,"SWITCH-PHASE",,,0,,"f4128d9b-c0000001",,,,,"Kind=COMMIT",
"2007/03/11 17:44:10.352","shuns01",,"84128d9b000005b2","Switch",1,"SUMMARY","17:44:10.352",0.024,0,,,,,,1,"UpdateTime=0.024, TransmittingTime=0.000",
"2007/03/11 17:49:58.247","shuns01",,"84128d9b000005c2","Search",0,"START","17:49:58.247",,,,,,,,,,
"2007/03/11 17:49:59.189","shuns01","44128da100000022","84128d9b000005c2","Search",3,"SEARCH-PHASE",,,0,,,0,,,,,
"2007/03/11 17:49:59.189","shuns01","44128da100000024","84128d9b000005c2","Search",3,"SEARCH-PHASE",,,0,,,0,,,,,
"2007/03/11 17:49:59.189","shuns01","44128da100000026","84128d9b000005c2","Search",3,"SEARCH-PHASE",,,0,,,0,,,,,
"2007/03/11 17:49:59.189","shuns01","44128da100000028","84128d9b000005c2","Search",3,"SEARCH-PHASE",,,0,,,0,,,,,
"2007/03/11 17:49:59.189","shuns01",,"84128d9b000005c2","Search",1,"SUMMARY","17:49:59.189",0.942,0,,,,,,4,"SearchTime=0.941, TransmittingTime=0.000",
"2007/03/11 17:50:12.908","shuns01",,"84128d9b000005c3","Search",0,"START","17:50:12.908",,,,,,,,,,
"2007/03/11 17:50:13.786","shuns01","44128da10000002a","84128d9b000005c3","Search",3,"SEARCH-PHASE",,,0,,,0,,,,,
"2007/03/11 17:50:13.786","shuns01",,"84128d9b000005c3","Search",1,"SUMMARY","17:50:13.786",0.878,0,,,,,,1,"SearchTime=0.878, TransmittingTime=0.000",