Big Data Integration ServerV1.5.0 検索編導入・運用ガイド

G.2.2 directorの性能ログ

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

• 共通情報

• 個別情報

• 出力例

  
  

共通情報

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

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

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

個別情報

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

依頼種別（出力項目5）	依頼内容
Connect	APIからdirectorへ直接接続
SetShunsakuFile	ShunsakuFileからの切替え（directorに切替え依頼が発生した場合のみ出力）
GetHitCount	検索（ShunGetHitCount）
Search	検索（ShunSearch）
SearchRecId	検索（ShunSearchRecId）
SearchKey	ダイレクトアクセスキー検索（ShunSearchKey）
Insert / Update / Delete	挿入、更新、削除
UpdateKey / DeleteKey	ダイレクトアクセスキー更新、ダイレクトアクセスキー削除
Commit / Rollback	挿入、更新、削除（コミット／ロールバック）
Command	コマンド
BulkTransfer	searcherへのデータ転送処理

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

4	5	6	7	8	9	10	11	12	13	14	15	16	17
SearcherReqNo	RequestKind （依頼種別）	OutputType	OutputKind	RequestTime	Response	Result	Client	TransactionId	Records	RetRecords	RetDataSize	MultiCount	DetailInfo
－	Connect	0	START	○	－	－	－	－	－	－	－	－	－
－		1	SUMMARY	○	○	○	－	－	－	－	－	－	－
－	SetShunsakuFile	0	START	○	－	－	－	－	－	－	－	－	－
－		1	SUMMARY	○	○	○	－	－	－	－	－	－	－
－	GetHitCount	0	START	○	－	－	－	－	－	－	－	－	－
○		1	SUMMARY	○	○	○	－	－	○	－	－	○	ReqWaitTime=依頼待ち時間（秒）
－		2	SEARCH-EXP	－	－	－	－	－	－	－	－	－	検索式
○		4	SEARCHER-PROCESS	○	○	○	－	－	○	－	－	○	Id=searcher識別子名、HostName=ホスト名,Port=ポート番号,SearcherTime=searcher実検索時間（秒）
○		5	SEARCHER-INFO	○	○	○	－	－	○ (注1)	－	－	○	Target=対象searcher数,AvgTime=平均実検索時間（秒）,MaxTime=searcher実検索時間（秒）,MaxSearcher=ホスト名,ポート番号, searcher識別子名
－	Search	0	START	○	－	－	－	－	－	－	－	－	－
○		1	SUMMARY	○	○	○	－	－	○	○	○	○	ReqWaitTime=依頼待ち時間（秒）
－		2	SEARCH-EXP	－	－	－	－	－	－	－	－	－	検索式
－		2	RETURN-EXP	－	－	－	－	－	－	－	－	－	リターン式
－		2	SORT-EXP	－	－	－	－	－	－	－	－	－	ソート式
○		3	SEARCH-PHASE	○	○	○	－	－	○	－	－	－	ReqWaitTime=依頼待ち時間（秒）
○		4	SEARCHER-PROCESS	○	○	○	－	－	○	－	－	○	Id=searcher識別子名、HostName=ホスト名,Port=ポート番号,SearcherTime=searcher実検索時間（秒）
○		5	SEARCHER-INFO	○	○	○	－	－	○ (注1)	－	－	○	Target=対象searcher数,AvgTime=平均実検索時間（秒）,MaxTime=searcher実検索時間（秒）,MaxSearcher=ホスト名,ポート番号, searcher識別子名
－		3	GETREC-PHASE	○	○	○	－	－	－	○	○	－	－
－	SearchRecId	0	START	○	－	－	－	－	－	－	－	－	－
－		1	SUMMARY	○	○	○	－	－	○	○	○	－	－
－	SearchKey	0	START	○	－	－	－	－	－	－	－	－	－
－		1	SUMMARY	○	○	○	－	－	○	○	○	－	－
－		2	SEARCHKEY	－	－	－	－	－	－	－	－	－	ダイレクトアクセスキー名="キー値","キー値"・・・
－		2	RETURN-EXP	－	－	－	－	－	－	－	－	－	リターン式
－	Insert/ Update/ Delete	0	START	○	－	－	－	－	－	－	－	－	－
○		1	SUMMARY	○	○	○	－	○	○	○	○	○	ReqWaitTime=依頼待ち時間（秒）
○		4	SEARCHER-PROCESS	○	○	○	－	○	－	○	○	○	Id=searcher識別子名、HostName=ホスト名,Port=ポート番号,SearcherTime=searcher実更新時間（秒）
○		5	SEARCHER-INFO	○	○	○	－	○	－	○ (注2)	○ (注2)	○	Target=対象searcher数,AvgTime=平均実更新時間（秒）,MaxTime=searcher実更新時間（秒）,MaxSearcher=ホスト名,ポート番号, searcher識別子名
－	UpdateKey/ DeleteKey	0	START	○	－	－	－	－	－	－	－	－	－
○		1	SUMMARY	○	○	○	－	○	○	○	○	○	ReqWaitTime=依頼待ち時間（秒）
－		2	SEARCHKEY	－	－	－	－	－	－	－	－	－	ダイレクトアクセスキー名="キー値","キー値"・・・
○		4	SEARCHER-PROCESS	○	○	○	－	○	－	○	○	○	Id=searcher識別子名、HostName=ホスト名,Port=ポート番号,SearcherTime=searcher実更新時間（秒）
○		5	SEARCHER-INFO	○	○	○	－	○	－	○ (注2)	○ (注2)	○	Target=対象searcher数,AvgTime=平均実更新時間（秒）,MaxTime=searcher実更新時間（秒）,MaxSearcher=ホスト名,ポート番号, searcher識別子名
－	Commit/ Rollback	0	START	○	－	－	－	－	－	－	－	－	－
○		1	SUMMARY	○	○	○	－	○	－	－	－	○	ReqWaitTime=依頼待ち時間（秒）
○		4	SEARCHER-PROCESS	○	○	○	－	○	－	－	－	○	Id=searcher識別子名、HostName=ホスト名,Port=ポート番号,SearcherTime=searcher実更新時間（秒）
○		5	SEARCHER-INFO	○	○	○	－	○	－	－	－	○	Target=対象searcher数,AvgTime=平均実更新時間（秒）,MaxTime=searcher実更新時間（秒）,MaxSearcher=ホスト名,ポート番号, searcher識別子名
－	Command	0	START	○	－	－	－	－	－	－	－	－	command=コマンド名
－		1	SUMMARY	○	○	○	－	■	■	■	■	－	command=コマンド名
－		2	SEARCH-EXP (注3)	－	－	－	－	－	－	－	－	－	検索式
○		4	SEARCHER-PROCESS (注3)	○	○	○	－	－	○	－	－	○	Id=searcher識別子名, HostName=ホスト名, Port=ポート名, SearcherTime=searcher実検索時間（秒）
○		5	SEARCHER-INFO(注3)	○	○	○	－	－	○ (注1)	－	－	○	Target=対象searcher数,AvgTime=平均実検索時間（秒）,MaxTime=searcher実検索時間（秒）,MaxSearcher=ホスト名,ポート番号, searcher識別子名
－	BulkTransfer	0	START	○	－	－	－	－	－	－	－	－	(注6)
－		1	SUMMARY	○	○	○	－	○	○	○ (注5)	○ (注5)	－	(注6)
○		6	SUBREQ-START	○	－	－	－	－	－	－	－	－	(注6)
○		7	SUBREQ-SUMMARY	○	○	○	－	－	○	○ (注5)	○ (注5)	－	(注6)
○		8	SUBREQ-SEARCHER	○	○	○	－	－	○	○ (注5)	○ (注5)	○	(注6)
○		9	SUBREQ-TARGET	－	－	－	－	－	○	－	－	－	(注6)

○：出力対象の項目
■：コマンドの種類により出力される場合と出力されない場合がある項目（注4）
－：出力しない項目

注1) 対象searcherのヒット件数の総和です。
注2) 更新対象searcherの更新データ件数、更新データサイズの総和です。
注3) shundexportコマンドのqオプション指定において、searcherに検索依頼を行った場合のみ出力されます。
注4) director用コマンドによって、DetailInfoおよび■の箇所は、以下のように出力されます。

	12	13	14	15	17
コマンド名	TransactionId	Records	RetRecords	RetDataSize	DetailInfo
shundimportコマンド	○	○	○	○	command=shundimportでの指定オプション
shundclearコマンド	○	－	－	－	command=shundclear
shundresendコマンド	○	－	－	－	command=shundresend
shundcdsコマンド	－	－	－	－	command=shundcds
shundexportコマンド	－	○	○	○	command=shundexport -o command=shundexport -q -o
	○	○	○	－	command=shundexport -q -d [-n] command=shundexport -d [-n]
shundbackupコマンド	－	－	－	－	command=shundbackupでの指定オプション
shundrecoverコマンド	－	－	－	－	command=shundrecover -b command=shundrecover -c command=shundrecover -l
	○	－	－	－	command=shundrecover -e command=shundrecover -e -p

注5) searcherに転送した件数、およびデータサイズを表します。
注6) DetailInfoにはOutputKindに応じて以下の情報が出力されます。

OutputKind	DetailInfo
START	Trigger=サーチデータ復元処理のきっかけ（注7）, TransferMode=転送方式（注8）, ParallelDegree=並列度,PageSize=ページサイズ（kB）,ReadAheadSize=先読みサイズ（kB）,BufferSize=バッファサイズ（kB）,BlockSize=ブロックサイズ（kB）,no.Searchers=有効searcher数
SUMMARY	no.SubRequests=サブリクエストの数,no.Targets=処理対象の数,PreTransferPhase=転送前処理時間（秒）,TranferPhase=転送処理時間（秒）,PostTransferPhase=転送後処理時間（秒）, BufferSizeUsed=使用バッファサイズ（kB）
SUBREQ-START	SN=サブリクエストの通番,Target=処理対象の識別子
SUBREQ-SUMMARY	Progress=進捗率（%）, SN=サブリクエストの通番,Target=処理対象の識別子, PreTransferPhase=転送前処理時間（秒）,TranferPhase=転送処理時間（秒）,MaxQueueLength=最大キュー長
SUBREQ-SEARCHER	Id=searcher識別子,HostName=ホスト名,Port=ポート番号,SN=サブリクエストの通番,ReadTime=読込み時間（秒）,ReadAheadTime=先読み時間（秒）,SplitReadTime=分割読込み時間（秒）,WaitTime=待ち時間（秒）,SendTime=送信時間（秒）,SearcherPhase=searcher処理時間（秒）,RecvTime=受信時間（秒）,no.Reads=読込み回数,no.SplitReads=分割読込み回数,SplitReadSize=分割読込みサイズ（B）
SUBREQ-TARGET	SN=サブリクエストの通番,Target=処理対象の識別子,ReadTime=読込み時間（秒）,ReadAheadTime=先読み時間（秒）,SplitReadTime=分割読込み時間（秒）,no.TargetPages=対象ページ数,no.SkipPages=非対象ページ数

注7)サーチデータ復元処理のきっかけとなった事象に応じて以下の値が出力されます。

事象	値
起動	searcher-recovery
縮退	searcher-fallback
切換え	searcher-failover
リカバリ	searcher-recovery

注8)転送方式には、“Bulk”が出力されます。

注意

• 以下の場合、○がついている項目であっても出力されません。

  • searcherに処理依頼を行わなかった場合

    • SEARCHER-PROCESSの情報

    • SEARCHER-INFOの情報

    • SearcherReqNoの情報

    • MultiCountの情報

    • SUMMARYのReqWaitTimeの情報

  • APIからdirectorへ直接接続した場合

    • SEARCH-PHASEの情報

    • GETREC-PHASEの情報

  • directorの処理途中でエラーを検出した場合

    • GETREC-PHASEの情報

• director用コマンドの性能情報は、コマンドが途中でエラーとなった時、性能情報が出力されない、または○がついている項目が出力されない場合があります。

• director用動作環境ファイルのPreSortFileパラメタを指定している場合、プレソート機能を使用したソート処理およびsorterを使用したソート/集計処理に関わらず、directorの性能ログには、必ずソート式が出力されます。

出力例

PfmLogStyleパラメタに0を指定した場合

"OutputTime","Id","RequestNo","SearcherReqNo","RequestKind","OutputType","OutputKind","RequestTime","Response","Result",
"Client","TransactionId","Records","RetRecords","RetDataSize","MultiCount","DetailInfo","ErrorInfo"
"2008/10/15 08:54:14.193","shund1","48eca5200000006b",,"Search",0,"START","08:54:14.193",,,,,,,,,,
"2008/10/15 08:54:15.523","shund1","48eca5200000006b","88f5311e00000001","Search",4,"SEARCHER-PROCESS","08:54:14.194",1.329,0,,,63,,,1,"Id=shuns02,HostName=SchSvr02,Port=33502,SearcherTime=1.329","2008/10/15 08:54:15.557","shund1","48eca5200000006b","88f5311e00000001","Search",4,"SEARCHER-PROCESS","08:54:14.194",1.362,0,,,0,,,1,"Id=shuns01,HostName=SchSvr01,Port=33501,SearcherTime=1.322",
"2008/10/15 08:54:15.560","shund1","48eca5200000006b",,"Search",2,"SEARCH-EXP",,,,,,,,,,"/document {/base/prefecture =='大阪' AND /information/date == '2008年07月18日'}",
"2008/10/15 08:54:15.560","shund1","48eca5200000006b",,"Search",2,"RETURN-EXP",,,,,,,,,,"/document/base/name, /document/base/price",
"2008/10/15 08:54:15.560","shund1","48eca5200000006b",,"Search",2,"SORT-EXP",,,,,,,,,,"/document/base/name/text()",
"2008/10/15 08:54:15.560","shund1","48eca5200000006b","88f5311e00000001","Search",3,"SEARCH-PHASE","08:54:14.193",1.363,0,,,63,,,,"ReqWaitTime=0.000",
"2008/10/1508:54:15.560","shund1","48eca5200000006b",,"Search",3,"GETREC-PHASE","08:54:15.557",0.002,0,,,,63,3843,,,
"2008/10/15 08:54:15.560","shund1","48eca5200000006b","88f5311e00000001","Search",1,"SUMMARY","08:54:14.193",1.366,0,,,63,63,3843,1,"ReqWaitTime=0.000",

PfmLogStyleパラメタに1を指定した場合

"OutputTime","Id","RequestNo","SearcherReqNo","RequestKind","OutputType","OutputKind","RequestTime","Response","Result",
"Client","TransactionId","Records","RetRecords","RetDataSize","MultiCount","DetailInfo","ErrorInfo"
"2008/10/15 08:54:56.637","shund1","48eca52000000072",,"Search",0,"START","08:54:56.637",,,,,,,,,,
"2008/10/15 08:54:58.153","shund1","48eca52000000072","88f5314800000001","Search",5,"SEARCHER-INFO","08:54:56.637",
1.374,0,,,63,,,1,"Target=2,AvgTime=1.333,MaxTime=1.334,MaxSearcher=SchSvr01,33501,shuns01",
"2008/10/15 08:54:58.153","shund1","48eca52000000072",,"Search",2,"SEARCH-EXP",,,,,,,,,,"/document {/base/prefecture =='大阪' AND /information/date == '2008年07月18日'}",
"2008/10/15 08:54:58.153","shund1","48eca52000000072",,"Search",2,"RETURN-EXP",,,,,,,,,,"/document/base/name, /document/base/price",
"2008/10/15 08:54:58.153","shund1","48eca52000000072",,"Search",2,"SORT-EXP",,,,,,,,,,"/document/base/name/text()",
"2008/10/15 08:54:58.153","shund1","48eca52000000072","88f5314800000001","Search",3,"SEARCH-PHASE","08:54:56.637",
1.513,0,,,63,,,,"ReqWaitTime=0.000",
"2008/10/15 08:54:58.153","shund1","48eca52000000072",,"Search",3,"GETREC-PHASE","08:54:58.150",0.002,0,,,,63,3843,,,
"2008/10/15 08:54:58.153","shund1","48eca52000000072","88f5314800000001","Search",1,"SUMMARY","08:54:56.637",
1.516,0,,,63,63,3843,1,"ReqWaitTime=0.000",