[pgsql-jp: 36553] Re: JDBC+pgpool利用時の不具合(!?)について

[YAMADA Toshio]

山田です。
早速の返信ありがとうございます。

Date: Wed, 14 Dec 2005 13:22:29 +0900 (JST)
From: Tatsuo Ishii <ishii ＠ sraoss.co.jp>
> そこで、pgpoolのデバッグ出力を有効にして、その結果を見せていただけない
> でしょうか?

大変失礼いたしました。
一番重要なデータを付するのを忘れておりました。

> サンプルプログラムを1回流した結果だけで結構です。

下記の通りです。先のメールの
LOCK→SELECT→INSERT→COMMITの1回の処理分です。

それでは、よろしくお願いいたします。

■(7)pgpoolのDEBUG出力
----------------------------------------------------------------
2005-12-14 14:11:28 DEBUG: pid 32575: I am 32575 accept fd 6
2005-12-14 14:11:28 DEBUG: pid 32575: Protocol Major: 3 Minor: 0 database: voguedb user: postgres
2005-12-14 14:11:28 DEBUG: pid 32575: pool_read_message_length: lenghth: 8
2005-12-14 14:11:28 DEBUG: pid 32575: pool_read_message_length2: master lenghth: 28
2005-12-14 14:11:28 DEBUG: pid 32575: name: client_encoding value: UNICODE
2005-12-14 14:11:28 DEBUG: pid 32575: secondary name: client_encoding value: UNICODE
2005-12-14 14:11:28 DEBUG: pid 32575: pool_read_message_length2: master lenghth: 23
2005-12-14 14:11:28 DEBUG: pid 32575: name: DateStyle value: ISO, MDY
2005-12-14 14:11:28 DEBUG: pid 32575: secondary name: DateStyle value: ISO, MDY
2005-12-14 14:11:28 DEBUG: pid 32575: pool_read_message_length2: master lenghth: 26
2005-12-14 14:11:28 DEBUG: pid 32575: name: integer_datetimes value: off
2005-12-14 14:11:28 DEBUG: pid 32575: secondary name: integer_datetimes value: off
2005-12-14 14:11:28 DEBUG: pid 32575: pool_read_message_length2: master lenghth: 20
2005-12-14 14:11:28 DEBUG: pid 32575: name: is_superuser value: on
2005-12-14 14:11:28 DEBUG: pid 32575: secondary name: is_superuser value: on
2005-12-14 14:11:28 DEBUG: pid 32575: pool_read_message_length2: master lenghth: 27
2005-12-14 14:11:28 DEBUG: pid 32575: name: server_encoding value: EUC_JP
2005-12-14 14:11:28 DEBUG: pid 32575: secondary name: server_encoding value: EUC_JP
2005-12-14 14:11:28 DEBUG: pid 32575: pool_read_message_length2: master lenghth: 25
2005-12-14 14:11:28 DEBUG: pid 32575: name: server_version value: 8.1.0
2005-12-14 14:11:28 DEBUG: pid 32575: secondary name: server_version value: 8.1.0
2005-12-14 14:11:28 DEBUG: pid 32575: pool_read_message_length2: master lenghth: 35
2005-12-14 14:11:28 DEBUG: pid 32575: name: session_authorization value: postgres
2005-12-14 14:11:28 DEBUG: pid 32575: secondary name: session_authorization value: postgres
2005-12-14 14:11:28 DEBUG: pid 32575: pool_read_message_length2: master lenghth: 36
2005-12-14 14:11:28 DEBUG: pid 32575: name: standard_conforming_strings value: off
2005-12-14 14:11:28 DEBUG: pid 32575: secondary name: standard_conforming_strings value: off
2005-12-14 14:11:28 DEBUG: pid 32575: pool_read_message_length2: master lenghth: 19
2005-12-14 14:11:28 DEBUG: pid 32575: name: TimeZone value: Japan
2005-12-14 14:11:28 DEBUG: pid 32575: secondary name: TimeZone value: Japan
2005-12-14 14:11:28 DEBUG: pid 32575: pool_read_message_length: lenghth: 12
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend pending data Z len: 5 po: 270
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: Z
2005-12-14 14:11:28 DEBUG: pid 32575: pool_read_message_length: lenghth: 5
2005-12-14 14:11:28 DEBUG: pid 32575: ReadyForQuery: message length: 5
2005-12-14 14:11:28 DEBUG: pid 32575: ReadyForQuery: transaction state: I
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend P(50)
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend B(42)
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend E(45)
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend P(50)
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend B(42)
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend D(44)
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend E(45)
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend S(53)
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend 1
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: waiting for secondary for data ready
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: 1
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend pending data 2 len: 52 po: 5
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: 2
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend pending data C len: 47 po: 10
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: C
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend pending data 1 len: 36 po: 21
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: 1
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend pending data 2 len: 31 po: 26
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: 2
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend pending data n len: 26 po: 31
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: n
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend pending data C len: 21 po: 36
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: C
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend pending data Z len: 5 po: 52
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: Z
2005-12-14 14:11:28 DEBUG: pid 32575: pool_read_message_length: lenghth: 5
2005-12-14 14:11:28 DEBUG: pid 32575: ReadyForQuery: message length: 5
2005-12-14 14:11:28 DEBUG: pid 32575: ReadyForQuery: transaction state: T
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend P(50)
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend B(42)
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend D(44)
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend E(45)
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend S(53)
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend 1
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: waiting for secondary for data ready
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: 1
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend pending data 2 len: 70 po: 5
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: 2
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend pending data T len: 65 po: 10
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: T
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend pending data D len: 33 po: 42
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: D
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend pending data C len: 17 po: 58
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: C
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend pending data Z len: 5 po: 70
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: Z
2005-12-14 14:11:28 DEBUG: pid 32575: pool_read_message_length: lenghth: 5
2005-12-14 14:11:28 DEBUG: pid 32575: ReadyForQuery: message length: 5
2005-12-14 14:11:28 DEBUG: pid 32575: ReadyForQuery: transaction state: T
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend P(50)
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend B(42)
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend D(44)
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend E(45)
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend S(53)
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend 1
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: waiting for secondary for data ready
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: 1
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend pending data 2 len: 31 po: 5
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: 2
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend pending data n len: 26 po: 10
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: n
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend pending data C len: 21 po: 15
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: C
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend pending data Z len: 5 po: 31
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: Z
2005-12-14 14:11:28 DEBUG: pid 32575: pool_read_message_length: lenghth: 5
2005-12-14 14:11:28 DEBUG: pid 32575: ReadyForQuery: message length: 5
2005-12-14 14:11:28 DEBUG: pid 32575: ReadyForQuery: transaction state: T
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend P(50)
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend B(42)
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend E(45)
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend S(53)
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend 1
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: waiting for secondary for data ready
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: 1
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend pending data 2 len: 22 po: 5
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: 2
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend pending data C len: 17 po: 10
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: C
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from backend pending data Z len: 5 po: 22
2005-12-14 14:11:28 DEBUG: pid 32575: pool_process_query: kind from backend: Z
2005-12-14 14:11:28 DEBUG: pid 32575: pool_read_message_length: lenghth: 5
2005-12-14 14:11:28 DEBUG: pid 32575: ReadyForQuery: message length: 5
2005-12-14 14:11:28 DEBUG: pid 32575: ReadyForQuery: transaction state: I
2005-12-14 14:11:28 DEBUG: pid 32575: read kind from frontend X(58)
----------------------------------------------------------------

-- 
by YAMADA Toshio <toshi ＠ mx1.freemail.ne.jp>