[pgsql-jp: 37414] Re: pgpool 縮退多発
Ito Shinichi
shinichi ＠ enkai.gr.jp
2006年 8月 11日 (金) 14:54:19 JST
いとうです。
From: Tatsuo Ishii <ishii ＠ sraoss.co.jp>
Subject: [pgsql-jp: 37410] Re: pgpool 縮退多発
Date: Fri, 11 Aug 2006 10:13:46 +0900 (JST)
...
> pgpoolに接続しているクライアントから特にSQLが発行されていないのに縮退
> するということですか？だとするとhealth checkが関係ありそう？
はい、そうです。
> いずれにしてもこれだけの情報ではなんとも言えないので，-d -n オプション
> を有効にして，縮退した前後のログを見せていただけませんか？
ちょっと量が多いですが、pgpool/PostgreSQLのログを以下に示します。
--------8<--------8<--------8<--------8<--------8<--------
[pgpool]
Jul 18 21:01:01 Server pgpool: 2006-07-18 21:01:01 LOG: pid 17333: starting health checking
Jul 18 21:01:01 Server pgpool: 2006-07-18 21:01:01 DEBUG: pid 17333: connecting postmaster Unix domain socket: /tmp/.s.PGSQL.5431
Jul 18 21:01:01 Server pgpool: 2006-07-18 21:01:01 DEBUG: pid 17333: connected to postmaster Unix domain socket: /tmp/.s.PGSQL.5431 fd: 6
Jul 18 21:01:01 Server pgpool: 2006-07-18 21:01:01 DEBUG: pid 17333: connecting postmaster Unix domain socket: /tmp/.s.PGSQL.5433
Jul 18 21:01:01 Server pgpool: 2006-07-18 21:01:01 DEBUG: pid 17333: connected to postmaster Unix domain socket: /tmp/.s.PGSQL.5433 fd: 6
Jul 18 21:01:11 Server pgpool: 2006-07-18 21:01:11 LOG: pid 17333: starting health checking
Jul 18 21:01:11 Server pgpool: 2006-07-18 21:01:11 DEBUG: pid 17333: connecting postmaster Unix domain socket: /tmp/.s.PGSQL.5431
Jul 18 21:01:11 Server pgpool: 2006-07-18 21:01:11 DEBUG: pid 17333: connected to postmaster Unix domain socket: /tmp/.s.PGSQL.5431 fd: 6
Jul 18 21:01:11 Server pgpool: 2006-07-18 21:01:11 DEBUG: pid 17333: connecting postmaster Unix domain socket: /tmp/.s.PGSQL.5433
Jul 18 21:01:11 Server pgpool: 2006-07-18 21:01:11 DEBUG: pid 17333: connected to postmaster Unix domain socket: /tmp/.s.PGSQL.5433 fd: 6
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: I am 20235 accept fd 6
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: Protocol Major: 3 Minor: 0 database: postgres user: postgres
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: connecting postmaster Unix domain socket: /tmp/.s.PGSQL.5431
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: connected to postmaster Unix domain socket: /tmp/.s.PGSQL.5431 fd: 7
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: connecting postmaster Unix domain socket: /tmp/.s.PGSQL.5433
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: connected to postmaster Unix domain socket: /tmp/.s.PGSQL.5433 fd: 8
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_read_message_length: lenghth: 8
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_read_message_length2: master lenghth: 30
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: name: client_encoding value: SQL_ASCII
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: secondary name: client_encoding value: SQL_ASCII
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_read_message_length2: master lenghth: 23
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: name: DateStyle value: ISO, MDY
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: secondary name: DateStyle value: ISO, MDY
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_read_message_length2: master lenghth: 26
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: name: integer_datetimes value: off
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: secondary name: integer_datetimes value: off
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_read_message_length2: master lenghth: 20
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: name: is_superuser value: on
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: secondary name: is_superuser value: on
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_read_message_length2: master lenghth: 30
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: name: server_encoding value: SQL_ASCII
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: secondary name: server_encoding value: SQL_ASCII
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_read_message_length2: master lenghth: 25
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: name: server_version value: 8.1.0
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: secondary name: server_version value: 8.1.0
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_read_message_length2: master lenghth: 35
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: name: session_authorization value: postgres
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: secondary name: session_authorization value: postgres
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_read_message_length2: master lenghth: 36
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: name: standard_conforming_strings value: off
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: secondary name: standard_conforming_strings value: off
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_read_message_length2: master lenghth: 19
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: name: TimeZone value: Japan
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: secondary name: TimeZone value: Japan
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_read_message_length: lenghth: 12
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_send_auth_ok: send pid 24512 to frontend
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from backend pending data Z len: 5 po: 275
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_process_query: kind from backend: Z
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_read_message_length: lenghth: 5
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: ReadyForQuery: message length: 5
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: ReadyForQuery: transaction state: I
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from frontend Q(51)
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: statement: begin
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: waiting for master completing the query
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from backend C
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from secondary backend C
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_process_query: kind from backend: C
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from backend pending data Z len: 5 po: 11
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_process_query: kind from backend: Z
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_read_message_length: lenghth: 5
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: ReadyForQuery: message length: 5
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: ReadyForQuery: transaction state: T
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from frontend Q(51)
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: statement: select datname,usesysid,usename from pg_stat_activity where usename='pgweb'
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: waiting for master completing the query
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from backend T
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from secondary backend T
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_process_query: kind from backend: T
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from backend pending data D len: 50 po: 86
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_process_query: kind from backend: D
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from backend pending data C len: 17 po: 119
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_process_query: kind from backend: C
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from backend pending data Z len: 5 po: 131
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_process_query: kind from backend: Z
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_read_message_length: lenghth: 5
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: ReadyForQuery: message length: 5
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: ReadyForQuery: transaction state: T
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from frontend Q(51)
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: statement: rollback
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: waiting for master completing the query
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from backend C
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from secondary backend C
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_process_query: kind from backend: C
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from backend pending data Z len: 5 po: 14
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_process_query: kind from backend: Z
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_read_message_length: lenghth: 5
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: ReadyForQuery: message length: 5
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: ReadyForQuery: transaction state: I
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from frontend X(58)
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: statement: RESET ALL
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: waiting for master completing the query
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from backend C
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from secondary backend C
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_process_query: kind from backend: C
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from backend pending data Z len: 5 po: 11
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_process_query: kind from backend: Z
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_read_message_length: lenghth: 5
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: ReadyForQuery: message length: 5
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: ReadyForQuery: transaction state: I
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: statement: SET SESSION AUTHORIZATION DEFAULT
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: waiting for master completing the query
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from backend S
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from secondary backend S
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_process_query: kind from backend: S
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_read_message_length2: master lenghth: 20
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: name: is_superuser value: on
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: secondary name: is_superuser value: on
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from backend pending data S len: 50 po: 21
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_process_query: kind from backend: S
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_read_message_length2: master lenghth: 35
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: name: session_authorization value: postgres
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: secondary name: session_authorization value: postgres
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from backend pending data C len: 14 po: 57
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_process_query: kind from backend: C
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: read kind from backend pending data Z len: 5 po: 66
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_process_query: kind from backend: Z
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_read_message_length: lenghth: 5
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: ReadyForQuery: message length: 5
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: ReadyForQuery: transaction state: I
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: pool_connection_pool_timer: set close time 1153224072
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: I am 19134 accept fd 6
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: Protocol Major: 3 Minor: 0 database: postgres user: postgres
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: connecting postmaster Unix domain socket: /tmp/.s.PGSQL.5431
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: connected to postmaster Unix domain socket: /tmp/.s.PGSQL.5431 fd: 7
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: connecting postmaster Unix domain socket: /tmp/.s.PGSQL.5433
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: connected to postmaster Unix domain socket: /tmp/.s.PGSQL.5433 fd: 8
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: pool_read_message_length: lenghth: 8
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: pool_read_message_length2: master lenghth: 30
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: name: client_encoding value: SQL_ASCII
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: secondary name: client_encoding value: SQL_ASCII
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: pool_read_message_length2: master lenghth: 23
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: name: DateStyle value: ISO, MDY
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: secondary name: DateStyle value: ISO, MDY
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: pool_read_message_length2: master lenghth: 26
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: name: integer_datetimes value: off
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: secondary name: integer_datetimes value: off
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: pool_read_message_length2: master lenghth: 20
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: name: is_superuser value: on
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: secondary name: is_superuser value: on
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: pool_read_message_length2: master lenghth: 30
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: name: server_encoding value: SQL_ASCII
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: secondary name: server_encoding value: SQL_ASCII
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: pool_read_message_length2: master lenghth: 25
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: name: server_version value: 8.1.0
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: secondary name: server_version value: 8.1.0
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: pool_read_message_length2: master lenghth: 35
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: name: session_authorization value: postgres
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: secondary name: session_authorization value: postgres
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: pool_read_message_length2: master lenghth: 36
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: name: standard_conforming_strings value: off
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: secondary name: standard_conforming_strings value: off
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: pool_read_message_length2: master lenghth: 19
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: name: TimeZone value: Japan
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: secondary name: TimeZone value: Japan
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: pool_read_message_length: lenghth: 12
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: pool_send_auth_ok: send pid 24516 to frontend
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: read kind from backend pending data Z len: 5 po: 275
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: pool_process_query: kind from backend: Z
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: pool_read_message_length: lenghth: 5
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: ReadyForQuery: message length: 5
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: ReadyForQuery: transaction state: I
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: read kind from frontend Q(51)
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: statement: begin
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: waiting for master completing the query
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: read kind from backend C
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: read kind from secondary backend C
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: pool_process_query: kind from backend: C
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: read kind from backend pending data Z len: 5 po: 11
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: pool_process_query: kind from backend: Z
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: pool_read_message_length: lenghth: 5
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: ReadyForQuery: message length: 5
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: ReadyForQuery: transaction state: T
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: read kind from frontend Q(51)
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: statement: select datname,usesysid,usename from pg_stat_activity where usename!='pgweb' and usename!='pgad'
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: waiting for master completing the query
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: read kind from backend T
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: read kind from secondary backend T
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: pool_process_query: kind from backend: T
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: read kind from backend pending data D len: 91 po: 86
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: pool_process_query: kind from backend: D
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: read kind from backend pending data D len: 54 po: 123
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: pool_process_query: kind from backend: D
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 ERROR: pid 19134: pool_process_query: kind does not match between backends master(C) secondary(D)
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: connecting postmaster Unix domain socket: /tmp/.s.PGSQL.5431
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: connected to postmaster Unix domain socket: /tmp/.s.PGSQL.5431 fd: 9
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: connecting postmaster Unix domain socket: /tmp/.s.PGSQL.5433
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19134: connected to postmaster Unix domain socket: /tmp/.s.PGSQL.5433 fd: 9
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 LOG: pid 19134: notice_backend_error: master: 1 fail over request from pid 19134
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: failover_handler called
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 LOG: pid 17333: starting degeneration. shutdown master host (5431)
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 12290
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 17336
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 24249
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 12290: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 17338
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 17339
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 5904
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 17341
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 17342
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 3800
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 17344
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 23484
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 24689
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 24159
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 23194
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 5903
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 24800
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 23960
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 28235
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 18707
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 23746
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 25116
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 24525
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 19138
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 23745
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 19134
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 25117
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 24004
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 20235
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24249: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 20717
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17336: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 5904: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17338: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17339: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 3800: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17341: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24689: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17342: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24800: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17344: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 23484: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 28235: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 25116: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24159: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 23194: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20235: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 25117: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 5903: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 23960: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 24218
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 18707: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 23508
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: kill 22924
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 23746: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24525: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 19138: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 23745: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24004: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 20717: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 22924: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 23508: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24218: child receives shutdown request signal 3
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24520: I am 24520
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24521: I am 24521
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24522: I am 24522
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24523: I am 24523
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24524: I am 24524
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24525: I am 24525
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24526: I am 24526
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24527: I am 24527
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24528: I am 24528
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24529: I am 24529
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24530: I am 24530
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24531: I am 24531
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24532: I am 24532
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24533: I am 24533
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24534: I am 24534
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24535: I am 24535
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24536: I am 24536
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24537: I am 24537
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24538: I am 24538
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24539: I am 24539
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24540: I am 24540
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24541: I am 24541
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24542: I am 24542
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24543: I am 24543
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24544: I am 24544
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24545: I am 24545
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24546: I am 24546
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24547: I am 24547
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24548: I am 24548
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24549: I am 24549
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24550: I am 24550
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 24551: I am 24551
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 LOG: pid 17333: degeneration done. shutdown master host (5431)
Jul 18 21:01:12 Server pgpool: 2006-07-18 21:01:12 DEBUG: pid 17333: reap_handler called
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: I am 24546 accept fd 6
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: Protocol Major: 3 Minor: 0 database: postgres user: postgres
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: connecting postmaster Unix domain socket: /tmp/.s.PGSQL.5433
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: connected to postmaster Unix domain socket: /tmp/.s.PGSQL.5433 fd: 7
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_read_message_length: lenghth: 8
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_read_message_length2: master lenghth: 30
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: name: client_encoding value: SQL_ASCII
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_read_message_length2: master lenghth: 23
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: name: DateStyle value: ISO, MDY
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_read_message_length2: master lenghth: 26
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: name: integer_datetimes value: off
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_read_message_length2: master lenghth: 20
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: name: is_superuser value: on
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_read_message_length2: master lenghth: 30
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: name: server_encoding value: SQL_ASCII
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_read_message_length2: master lenghth: 25
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: name: server_version value: 8.1.0
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_read_message_length2: master lenghth: 35
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: name: session_authorization value: postgres
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_read_message_length2: master lenghth: 36
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: name: standard_conforming_strings value: off
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_read_message_length2: master lenghth: 19
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: name: TimeZone value: Japan
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_read_message_length: lenghth: 12
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_send_auth_ok: send pid 24573 to frontend
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: read kind from backend pending data Z len: 5 po: 275
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_process_query: kind from backend: Z
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_read_message_length: lenghth: 5
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: ReadyForQuery: message length: 5
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: ReadyForQuery: transaction state: I
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: read kind from frontend Q(51)
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: statement: begin
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: read kind from backend C
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_process_query: kind from backend: C
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: read kind from backend pending data Z len: 5 po: 11
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_process_query: kind from backend: Z
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_read_message_length: lenghth: 5
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: ReadyForQuery: message length: 5
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: ReadyForQuery: transaction state: T
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: read kind from frontend Q(51)
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: statement: show pool_status
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: process reporting
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: read kind from frontend X(58)
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: statement: ABORT
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: read kind from backend C
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_process_query: kind from backend: C
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: read kind from backend pending data Z len: 5 po: 14
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_process_query: kind from backend: Z
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_read_message_length: lenghth: 5
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: ReadyForQuery: message length: 5
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: ReadyForQuery: transaction state: I
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: statement: RESET ALL
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: read kind from backend C
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_process_query: kind from backend: C
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: read kind from backend pending data Z len: 5 po: 11
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_process_query: kind from backend: Z
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_read_message_length: lenghth: 5
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: ReadyForQuery: message length: 5
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: ReadyForQuery: transaction state: I
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: statement: SET SESSION AUTHORIZATION DEFAULT
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: read kind from backend S
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_process_query: kind from backend: S
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_read_message_length2: master lenghth: 20
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: name: is_superuser value: on
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: read kind from backend pending data S len: 50 po: 21
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_process_query: kind from backend: S
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_read_message_length2: master lenghth: 35
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: name: session_authorization value: postgres
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: read kind from backend pending data C len: 14 po: 57
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_process_query: kind from backend: C
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: read kind from backend pending data Z len: 5 po: 66
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_process_query: kind from backend: Z
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_read_message_length: lenghth: 5
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: ReadyForQuery: message length: 5
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: ReadyForQuery: transaction state: I
Jul 18 21:01:37 Server pgpool: 2006-07-18 21:01:37 DEBUG: pid 24546: pool_connection_pool_timer: set close time 1153224097
--------8<--------8<--------8<--------8<--------8<--------
[PostgreSQL]
Jul 18 21:01:01 wap901 postgres[24497]: [2-1] master:<@>LOG: autovacuum: processing database "DB1"
Jul 18 21:01:01 wap901 postgres[24499]: [2-1] slave:<@>LOG: autovacuum: processing database "DB1"
Jul 18 21:01:12 wap901 postgres[24512]: [2-1] master:<postgres ＠ postgres>LOG: statement: begin
Jul 18 21:01:12 wap901 postgres[24513]: [2-1] slave:<postgres ＠ postgres>LOG: statement: begin
Jul 18 21:01:12 wap901 postgres[24512]: [3-1] master:<postgres ＠ postgres>LOG: statement: select datname,usesysid,usename from pg_stat_activity where usename='pgweb'
Jul 18 21:01:12 wap901 postgres[24513]: [3-1] slave:<postgres ＠ postgres>LOG: statement: select datname,usesysid,usename from pg_stat_activity where usename='pgweb'
Jul 18 21:01:12 wap901 postgres[24512]: [4-1] master:<postgres ＠ postgres>LOG: statement: rollback
Jul 18 21:01:12 wap901 postgres[24513]: [4-1] slave:<postgres ＠ postgres>LOG: statement: rollback
Jul 18 21:01:12 wap901 postgres[24512]: [5-1] master:<postgres ＠ postgres>LOG: statement: RESET ALL
Jul 18 21:01:12 wap901 postgres[24513]: [5-1] slave:<postgres ＠ postgres>LOG: statement: RESET ALL
Jul 18 21:01:12 wap901 postgres[24512]: [6-1] master:<postgres ＠ postgres>LOG: statement: SET SESSION AUTHORIZATION DEFAULT
Jul 18 21:01:12 wap901 postgres[24513]: [6-1] slave:<postgres ＠ postgres>LOG: statement: SET SESSION AUTHORIZATION DEFAULT
Jul 18 21:01:12 wap901 postgres[24516]: [2-1] master:<postgres ＠ postgres>LOG: statement: begin
Jul 18 21:01:12 wap901 postgres[24517]: [2-1] slave:<postgres ＠ postgres>LOG: statement: begin
Jul 18 21:01:12 wap901 postgres[24516]: [3-1] master:<postgres ＠ postgres>LOG: statement: select datname,usesysid,usename from pg_stat_activity where usename!='pgweb' and
Jul 18 21:01:12 wap901 postgres[24516]: [3-2] usename!='pgad'
Jul 18 21:01:12 wap901 postgres[24517]: [3-1] slave:<postgres ＠ postgres>LOG: statement: select datname,usesysid,usename from pg_stat_activity where usename!='pgweb' and
Jul 18 21:01:12 wap901 postgres[24517]: [3-2] usename!='pgad'
Jul 18 21:01:12 wap901 postgres[24516]: [4-1] master:<postgres ＠ postgres>LOG: unexpected EOF on client connection
Jul 18 21:01:12 wap901 postgres[24517]: [4-1] slave:<postgres ＠ postgres>LOG: unexpected EOF on client connection
Jul 18 21:01:12 wap901 postgres[24214]: [16-1] master:<postgres ＠ postgres>LOG: unexpected EOF on client connection
Jul 18 21:01:12 wap901 postgres[24433]: [6-1] slave:<pgweb ＠ DB1>LOG: unexpected EOF on client connection
Jul 18 21:01:12 wap901 postgres[24512]: [7-1] master:<postgres ＠ postgres>LOG: unexpected EOF on client connection
Jul 18 21:01:12 wap901 postgres[24432]: [7-1] master:<pgweb ＠ DB1>LOG: unexpected EOF on client connection
Jul 18 21:01:12 wap901 postgres[24513]: [7-1] slave:<postgres ＠ postgres>LOG: unexpected EOF on client connection
Jul 18 21:01:12 wap901 postgres[24215]: [16-1] slave:<postgres ＠ postgres>LOG: unexpected EOF on client connection
Jul 18 21:01:37 wap901 postgres[24570]: [2-1] master:<postgres ＠ postgres>LOG: statement: begin
Jul 18 21:01:37 wap901 postgres[24570]: [3-1] master:<postgres ＠ postgres>LOG: statement: select datname from pg_database;
Jul 18 21:01:37 wap901 postgres[24570]: [4-1] master:<postgres ＠ postgres>LOG: statement: rollback
Jul 18 21:01:37 wap901 postgres[24571]: [2-1] slave:<postgres ＠ postgres>LOG: statement: begin
Jul 18 21:01:37 wap901 postgres[24571]: [3-1] slave:<postgres ＠ postgres>LOG: statement: select datname from pg_database;
Jul 18 21:01:37 wap901 postgres[24571]: [4-1] slave:<postgres ＠ postgres>LOG: statement: rollback
Jul 18 21:01:37 wap901 postgres[24573]: [2-1] slave:<postgres ＠ postgres>LOG: statement: begin
Jul 18 21:01:37 wap901 postgres[24573]: [3-1] slave:<postgres ＠ postgres>LOG: statement: ABORT
Jul 18 21:01:37 wap901 postgres[24573]: [4-1] slave:<postgres ＠ postgres>LOG: statement: RESET ALL
Jul 18 21:01:37 wap901 postgres[24573]: [5-1] slave:<postgres ＠ postgres>LOG: statement: SET SESSION AUTHORIZATION DEFAULT
--------8<--------8<--------8<--------8<--------8<--------
----
伊藤 真一
shinichi ＠ enkai.gr.jp
pgsql-jp メーリングリストの案内