[pgpool-hackers: 636] Re: pgpool-II ignores heath check retry
Muhammad Usama
m.usama at gmail.com
Thu Oct 30 20:46:17 JST 2014
Hi Tatsuo,
I have taken care of your valued suggestions and committed the patch
to master branch.
Thanks and regards,
Muhammad Usama
On Thu, Oct 30, 2014 at 6:29 AM, Tatsuo Ishii <ishii at postgresql.org> wrote:
> Hi Usama,
>
> The code looks good except small point:
>
> if(ret > 0) /* Retries are exhausted, reset the counter */
>
> You do not put a space between "if" and "(", which does not follow
> PostgreSQL/pgpool-II coding style.
>
> Best regards,
> --
> Tatsuo Ishii
> SRA OSS, Inc. Japan
> English: http://www.sraoss.co.jp/index_en.php
> Japanese:http://www.sraoss.co.jp
>
>> Hi Tatsuo,
>>
>> I have looked into this. The problem was only with the log message and
>> setting health_check_max_retries = 0 does not
>> Proceeds to retry, but does prints an invalid LOG message.
>> Unfortunately the problem still persists with your fix, since
>> "retrycnt" is incremented before calling
>> process_backend_health_check_failure() function. And with first health
>> check failure the retrycnt becomes 1 and if(retrycnt <=
>> health_check_max_retries) is enough to detect if retries are
>> exhausted or not for health_check_max_retries = 0.
>>
>> I have tried to fix the problem by resetting the retrycnt if
>> process_backend_health_check_failure() informs retry count is
>> exhausted.
>> Can you please have a look at the attached patch.
>>
>> Thanks and regards
>> Usama
>>
>> On Wed, Oct 29, 2014 at 6:32 AM, Tatsuo Ishii <ishii at postgresql.org> wrote:
>>> Unfortunately with the fix even health_check_max_retries = 0,
>>> pgpool-II tries to retry at least once. Will fix.
>>>
>>> Best regards,
>>> --
>>> Tatsuo Ishii
>>> SRA OSS, Inc. Japan
>>> English: http://www.sraoss.co.jp/index_en.php
>>> Japanese:http://www.sraoss.co.jp
>>>
>>>> Hi Usama,
>>>>
>>>> Great! Thanks. Tomorrow I'm going to release RC1.
>>>>
>>>> Best regards,
>>>> --
>>>> Tatsuo Ishii
>>>> SRA OSS, Inc. Japan
>>>> English: http://www.sraoss.co.jp/index_en.php
>>>> Japanese:http://www.sraoss.co.jp
>>>>
>>>>> Hi Tatsuo,
>>>>> Thanks for pointing this out. I have committed the fix in master branch.
>>>>>
>>>>> The reason was a typo in process_backend_health_check_failure() function
>>>>> where the function tries to figure out the max health check retry count for
>>>>> current pgpool-II configuration. Instead of using
>>>>> pool_config->health_check_max_retries configuration for max retry count
>>>>> the function was mistakenly considering
>>>>> pool_config->health_check_retry_delay for the purpose.
>>>>>
>>>>> Kind regards,
>>>>> Muhammad Usama
>>>>>
>>>>>
>>>>> On Tue, Oct 28, 2014 at 5:31 AM, Tatsuo Ishii <ishii at postgresql.org> wrote:
>>>>>
>>>>>> Hi Usama,
>>>>>>
>>>>>> It seems master branch pgpool-II ignores health check retry
>>>>>> setting. At least 3.3 stable pgpool-II behaves as expected. I am
>>>>>> afraid this will be the show stopper for 3.5.0.
>>>>>>
>>>>>> Step to reproduce;
>>>>>>
>>>>>> 1) pgpool.conf
>>>>>> fail_over_on_backend_error = off
>>>>>> health_check_period = 5
>>>>>> health_check_timeout = 2
>>>>>> health_check_max_retries = 20
>>>>>> health_check_retry_delay = 1
>>>>>> sr_check_period = 0
>>>>>>
>>>>>> 2) start pgpool-II.
>>>>>>
>>>>>> 3) kill one of postmaster using kill -9. (I know this is bad but
>>>>>> normal shutdown command using pg_ctl does not trigger health check
>>>>>> retry since pgpool-II immediately detaches the node)
>>>>>>
>>>>>> 4) check pgpool log. You will see it reried only once or twice.
>>>>>>
>>>>>> 2014-10-28 09:19:19: pid 9519: LOG: health checking retry count 1
>>>>>> 2014-10-28 09:19:19: pid 9519: LOG: failed to connect to PostgreSQL
>>>>>> server by unix domain socket
>>>>>> 2014-10-28 09:19:19: pid 9519: DETAIL: connect to "/tmp/.s.PGSQL.11001"
>>>>>> failed with error "Connection refused"
>>>>>> 2014-10-28 09:19:19: pid 9519: ERROR: failed to make persistent db
>>>>>> connection
>>>>>> 2014-10-28 09:19:19: pid 9519: DETAIL: connection to host:"/tmp:11001"
>>>>>> failed
>>>>>> 2014-10-28 09:19:19: pid 9519: LOG: setting backend node 1 status to NODE
>>>>>> DOWN
>>>>>> 2014-10-28 09:19:19: pid 9519: LOG: starting degeneration. shutdown host
>>>>>> /tmp(11001)
>>>>>> 2014-10-28 09:19:19: pid 9519: LOG: Restart all children
>>>>>> :
>>>>>> :
>>>>>> :
>>>>>>
>>>>>> I attached full debug log for your convenience.
>>>>>>
>>>>>> Best regards,
>>>>>> --
>>>>>> Tatsuo Ishii
>>>>>> SRA OSS, Inc. Japan
>>>>>> English: http://www.sraoss.co.jp/index_en.php
>>>>>> Japanese:http://www.sraoss.co.jp
>>>>>>
>>>>>> 2014-10-28 09:29:39: pid 9955: LOG: Backend status file
>>>>>> /home/t-ishii/work/pgpool-II/current/aaa/log/pgpool_status discarded
>>>>>> 2014-10-28 09:29:39: pid 9955: LOG: Setting up socket for 127.0.0.1:11002
>>>>>> 2014-10-28 09:29:39: pid 9956: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9957: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9958: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9961: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9962: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9960: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9965: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9966: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9959: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9969: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9971: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9973: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9963: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9976: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9977: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9980: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9981: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9984: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9985: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9955: LOG: pgpool-II successfully started.
>>>>>> version 3.4beta2 (tataraboshi)
>>>>>> 2014-10-28 09:29:39: pid 9955: LOG: find_primary_node: checking
>>>>>> bac2014-10-28 09:29:39: pid 9988: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9988: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: SSL is requested but SSL support is
>>>>>> not available
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: I am 9989
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: SSL is requested but SSL support is
>>>>>> not available
>>>>>> 2014-10-28 09:29:39: pid 9987: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9967: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9986: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9968: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9970: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9964: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9972: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: do_query: extended:0 query:"SELECT
>>>>>> pg_is_in_recovery()"
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: SSL is requested but SSL support is
>>>>>> not available
>>>>>> 2014-10-28 09:29:39: pid 9974: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9975: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: do_query: kind: 'T'
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: do_query: received ROW DESCRIPTION
>>>>>> ('T')
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: do_query: row description:
>>>>>> num_fileds: 1
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: do_query: kind: 'D'
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: do_query: received DATA ROW ('D')
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: do_query: kind: 'C'
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: do_query: received COMMAND COMPLETE
>>>>>> ('C')
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: do_query: kind: 'Z'
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: do_query: received READY FOR QUERY
>>>>>> ('Z')
>>>>>> 2014-10-28 09:29:39: pid 9978: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9955: LOG: find_primary_node: primary node id is
>>>>>> 0
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: starting health check
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: Backend DB node 0 status is 1
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: Trying to make persistent DB
>>>>>> connection to backend node 0 having status 1
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: SSL is requested but SSL support is
>>>>>> not available
>>>>>> 2014-10-28 09:29:39: pid 9979: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9982: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9983: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: persistent DB connection to backend
>>>>>> node 0 having status 1 is successful
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: Backend DB node 1 status is 1
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: Trying to make persistent DB
>>>>>> connection to backend node 1 having status 1
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: SSL is requested but SSL support is
>>>>>> not available
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: do_query: extended:0 query:"SELECT
>>>>>> pg_current_xlog_location()"
>>>>>> 2014-10-28 09:29:39: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: do_query: kind: 'T'
>>>>>> 2014-10-28 09:29:39: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: do_query: received ROW DESCRIPTION
>>>>>> ('T')
>>>>>> 2014-10-28 09:29:39: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: do_query: row description:
>>>>>> num_fileds: 1
>>>>>> 2014-10-28 09:29:39: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: do_query: kind: 'D'
>>>>>> 2014-10-28 09:29:39: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: do_query: received DATA ROW ('D')
>>>>>> 2014-10-28 09:29:39: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: do_query: kind: 'C'
>>>>>> 2014-10-28 09:29:39: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: do_query: received COMMAND COMPLETE
>>>>>> ('C')
>>>>>> 2014-10-28 09:29:39: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: do_query: kind: 'Z'
>>>>>> 2014-10-28 09:29:39: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: do_query: received READY FOR QUERY
>>>>>> ('Z')
>>>>>> 2014-10-28 09:29:39: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: do_query: extended:0 query:"SELECT
>>>>>> pg_last_xlog_replay_location()"
>>>>>> 2014-10-28 09:29:39: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: persistent DB connection to backend
>>>>>> node 1 having status 1 is successful
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:29:39: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: do_query: kind: 'T'
>>>>>> 2014-10-28 09:29:39: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: do_query: received ROW DESCRIPTION
>>>>>> ('T')
>>>>>> 2014-10-28 09:29:39: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: do_query: row description:
>>>>>> num_fileds: 1
>>>>>> 2014-10-28 09:29:39: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: do_query: kind: 'D'
>>>>>> 2014-10-28 09:29:39: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: do_query: received DATA ROW ('D')
>>>>>> 2014-10-28 09:29:39: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: do_query: kind: 'C'
>>>>>> 2014-10-28 09:29:39: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: do_query: received COMMAND COMPLETE
>>>>>> ('C')
>>>>>> 2014-10-28 09:29:39: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: do_query: kind: 'Z'
>>>>>> 2014-10-28 09:29:39: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:39: pid 9989: DEBUG: do_query: received READY FOR QUERY
>>>>>> ('Z')
>>>>>> 2014-10-28 09:29:39: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:44: pid 9955: DEBUG: starting health check
>>>>>> 2014-10-28 09:29:44: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:29:44: pid 9955: DEBUG: Backend DB node 0 status is 1
>>>>>> 2014-10-28 09:29:44: pid 9955: DEBUG: Trying to make persistent DB
>>>>>> connection to backend node 0 having status 1
>>>>>> 2014-10-28 09:29:44: pid 9955: DEBUG: SSL is requested but SSL support is
>>>>>> not available
>>>>>> 2014-10-28 09:29:44: pid 9955: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:29:44: pid 9955: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:29:44: pid 9955: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:29:44: pid 9955: DEBUG: persistent DB connection to backend
>>>>>> node 0 having status 1 is successful
>>>>>> 2014-10-28 09:29:44: pid 9955: DEBUG: Backend DB node 1 status is 1
>>>>>> 2014-10-28 09:29:44: pid 9955: DEBUG: Trying to make persistent DB
>>>>>> connection to backend node 1 having status 1
>>>>>> 2014-10-28 09:29:44: pid 9955: DEBUG: SSL is requested but SSL support is
>>>>>> not available
>>>>>> 2014-10-28 09:29:44: pid 9955: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:29:44: pid 9955: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:29:44: pid 9955: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:29:44: pid 9955: DEBUG: persistent DB connection to backend
>>>>>> node 1 having status 1 is successful
>>>>>> 2014-10-28 09:29:44: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:29:44: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: SSL is requested but SSL support is
>>>>>> not available
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: SSL is requested but SSL support is
>>>>>> not available
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: do_query: extended:0 query:"SELECT
>>>>>> pg_current_xlog_location()"
>>>>>> 2014-10-28 09:29:49: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: do_query: kind: 'T'
>>>>>> 2014-10-28 09:29:49: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: do_query: received ROW DESCRIPTION
>>>>>> ('T')
>>>>>> 2014-10-28 09:29:49: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: do_query: row description:
>>>>>> num_fileds: 1
>>>>>> 2014-10-28 09:29:49: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: do_query: kind: 'D'
>>>>>> 2014-10-28 09:29:49: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: do_query: received DATA ROW ('D')
>>>>>> 2014-10-28 09:29:49: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: do_query: kind: 'C'
>>>>>> 2014-10-28 09:29:49: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: do_query: received COMMAND COMPLETE
>>>>>> ('C')
>>>>>> 2014-10-28 09:29:49: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: do_query: kind: 'Z'
>>>>>> 2014-10-28 09:29:49: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: do_query: received READY FOR QUERY
>>>>>> ('Z')
>>>>>> 2014-10-28 09:29:49: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: do_query: extended:0 query:"SELECT
>>>>>> pg_last_xlog_replay_location()"
>>>>>> 2014-10-28 09:29:49: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: do_query: kind: 'T'
>>>>>> 2014-10-28 09:29:49: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: do_query: received ROW DESCRIPTION
>>>>>> ('T')
>>>>>> 2014-10-28 09:29:49: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: do_query: row description:
>>>>>> num_fileds: 1
>>>>>> 2014-10-28 09:29:49: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: do_query: kind: 'D'
>>>>>> 2014-10-28 09:29:49: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: do_query: received DATA ROW ('D')
>>>>>> 2014-10-28 09:29:49: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: do_query: kind: 'C'
>>>>>> 2014-10-28 09:29:49: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: do_query: received COMMAND COMPLETE
>>>>>> ('C')
>>>>>> 2014-10-28 09:29:49: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: do_query: kind: 'Z'
>>>>>> 2014-10-28 09:29:49: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:49: pid 9989: DEBUG: do_query: received READY FOR QUERY
>>>>>> ('Z')
>>>>>> 2014-10-28 09:29:49: pid 9989: CONTEXT: while checking replication time
>>>>>> lag
>>>>>> 2014-10-28 09:29:49: pid 9955: DEBUG: starting health check
>>>>>> 2014-10-28 09:29:49: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:29:49: pid 9955: DEBUG: Backend DB node 0 status is 1
>>>>>> 2014-10-28 09:29:49: pid 9955: DEBUG: Trying to make persistent DB
>>>>>> connection to backend node 0 having status 1
>>>>>> 2014-10-28 09:29:49: pid 9955: DEBUG: SSL is requested but SSL support is
>>>>>> not available
>>>>>> 2014-10-28 09:29:49: pid 9955: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:29:49: pid 9955: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:29:49: pid 9955: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:29:49: pid 9955: DEBUG: persistent DB connection to backend
>>>>>> node 0 having status 1 is successful
>>>>>> 2014-10-28 09:29:49: pid 9955: DEBUG: Backend DB node 1 status is 1
>>>>>> 2014-10-28 09:29:49: pid 9955: DEBUG: Trying to make persistent DB
>>>>>> connection to backend node 1 having status 1
>>>>>> 2014-10-28 09:29:49: pid 9955: DEBUG: SSL is requested but SSL support is
>>>>>> not available
>>>>>> 2014-10-28 09:29:49: pid 9955: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:29:49: pid 9955: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:29:49: pid 9955: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:29:49: pid 9955: DEBUG: persistent DB connection to backend
>>>>>> node 1 having status 1 is successful
>>>>>> 2014-10-28 09:29:49: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:29:49: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:29:54: pid 9955: DEBUG: starting health check
>>>>>> 2014-10-28 09:29:54: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:29:54: pid 9955: DEBUG: Backend DB node 0 status is 1
>>>>>> 2014-10-28 09:29:54: pid 9955: DEBUG: Trying to make persistent DB
>>>>>> connection to backend node 0 having status 1
>>>>>> 2014-10-28 09:29:54: pid 9955: DEBUG: SSL is requested but SSL support is
>>>>>> not available
>>>>>> 2014-10-28 09:29:54: pid 9955: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:29:54: pid 9955: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:29:54: pid 9955: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:29:54: pid 9955: DEBUG: persistent DB connection to backend
>>>>>> node 0 having status 1 is successful
>>>>>> 2014-10-28 09:29:54: pid 9955: DEBUG: Backend DB node 1 status is 1
>>>>>> 2014-10-28 09:29:54: pid 9955: DEBUG: Trying to make persistent DB
>>>>>> connection to backend node 1 having status 1
>>>>>> 2014-10-28 09:29:54: pid 9955: LOG: failed to connect to PostgreSQL
>>>>>> server by unix domain socket
>>>>>> 2014-10-28 09:29:54: pid 9955: DETAIL: connect to "/tmp/.s.PGSQL.11001"
>>>>>> failed with error "Connection refused"
>>>>>> 2014-10-28 09:29:54: pid 9955: ERROR: failed to make persistent db
>>>>>> connection
>>>>>> 2014-10-28 09:29:54: pid 9955: DETAIL: connection to host:"/tmp:11001"
>>>>>> failed
>>>>>> 2014-10-28 09:29:54: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:29:54: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:29:54: pid 9955: DEBUG: Backend DB node 1 status is 1
>>>>>> 2014-10-28 09:29:54: pid 9955: DEBUG: Trying to make persistent DB
>>>>>> connection to backend node 1 having status 1
>>>>>> 2014-10-28 09:29:54: pid 9955: LOG: failed to connect to PostgreSQL
>>>>>> server by unix domain socket
>>>>>> 2014-10-28 09:29:54: pid 9955: DETAIL: connect to "/tmp/.s.PGSQL.11001"
>>>>>> failed with error "Connection refused"
>>>>>> 2014-10-28 09:29:54: pid 9955: ERROR: failed to make persistent db
>>>>>> connection
>>>>>> 2014-10-28 09:29:54: pid 9955: DETAIL: connection to host:"/tmp:11001"
>>>>>> failed
>>>>>> 2014-10-28 09:29:54: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:29:54: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:29:54: pid 9955: DEBUG: Sleeping for 1 seconds from process
>>>>>> backend health check failure
>>>>>> 2014-10-28 09:29:54: pid 9955: DETAIL: health check failed retry no is 1
>>>>>> while max retries are 1
>>>>>> 2014-10-28 09:29:55: pid 9955: LOG: health checking retry count 1
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: Backend DB node 1 status is 1
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: Trying to make persistent DB
>>>>>> connection to backend node 1 having status 1
>>>>>> 2014-10-28 09:29:55: pid 9955: LOG: failed to connect to PostgreSQL
>>>>>> server by unix domain socket
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: connect to "/tmp/.s.PGSQL.11001"
>>>>>> failed with error "Connection refused"
>>>>>> 2014-10-28 09:29:55: pid 9955: ERROR: failed to make persistent db
>>>>>> connection
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: connection to host:"/tmp:11001"
>>>>>> failed
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:29:55: pid 9955: LOG: setting backend node 1 status to NODE
>>>>>> DOWN
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler called
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: starting to select new master node
>>>>>> 2014-10-28 09:29:55: pid 9955: LOG: starting degeneration. shutdown host
>>>>>> /tmp(11001)
>>>>>> 2014-10-28 09:29:55: pid 9955: LOG: Restart all children
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9956
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9957
>>>>>> 2014-10-28 09:29:55: pid 9957: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9959
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9960
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9961
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9962
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9963
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9964
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9965
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9966
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9967
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9968
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9969
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9970
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9971
>>>>>> 2014-10-28 09:29:55: pid 9960: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9972
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9973
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9974
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9975
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9976
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9977
>>>>>> 2014-10-28 09:29:55: pid 9961: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9979
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9980
>>>>>> 2014-10-28 09:29:55: pid 9965: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9963: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9964: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9966: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9969: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9968: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9971: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9973: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9981
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9982
>>>>>> 2014-10-28 09:29:55: pid 9982: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9976: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9985
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9986
>>>>>> 2014-10-28 09:29:55: pid 9955: DEBUG: failover handler
>>>>>> 2014-10-28 09:29:55: pid 9955: DETAIL: kill process with PID:9987
>>>>>> 2014-10-28 09:29:55: pid 9955: LOG: execute command:
>>>>>> /home/t-ishii/work/pgpool-II/current/aaa/etc/failover.sh 1 /tmp 11001
>>>>>> /home/t-ishii/work/pgpool-II/current/aaa/data1 0 0 /tmp 0 11000
>>>>>> /home/t-ishii/work/pgpool-II/current/aaa/data0
>>>>>> 2014-10-28 09:29:55: pid 9977: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9980: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9986: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9983: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9981: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9979: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9987: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9985: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9978: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9984: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9967: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9970: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9972: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9975: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9974: LOG: child process received shutdown
>>>>>> request signal 3
>>>>>> 2014-10-28 09:29:55: pid 9955: LOG: failover: set new primary node: 0
>>>>>> 2014-10-28 09:29:55: pid 9955: LOG: failover: set new master node: 0
>>>>>> 2014-10-28 09:29:55: pid 10008: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10009: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10012: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10011: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10013: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10010: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10015: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10014: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10016: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10019: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10017: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10029: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10018: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10023: DEBUG: initializing backend status
>>>>>> failover done. shutdown host /tmp(11001)2014-10-28 09:29:55: pid 9989:
>>>>>> LOG: worker process received restart request
>>>>>> 2014-10-28 09:29:55: pid 10021: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 9955: LOG: failover done. shutdown host
>>>>>> /tmp(11001)
>>>>>> 2014-10-28 09:29:55: pid 10024: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 9988: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10022: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10020: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10026: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10027: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10034: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10035: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10028: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10036: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10030: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10037: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10031: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10038: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10032: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10039: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:55: pid 10033: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:56: pid 9988: LOG: restart request received in pcp child
>>>>>> process
>>>>>> 2014-10-28 09:29:56: pid 9955: LOG: PCP child 9988 exits with status 256
>>>>>> in failover()
>>>>>> 2014-10-28 09:29:56: pid 9955: LOG: fork a new PCP child pid 10040 in
>>>>>> failover()
>>>>>> 2014-10-28 09:29:56: pid 9955: LOG: health checking retry count 2
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: Backend DB node 0 status is 1
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: Trying to make persistent DB
>>>>>> connection to backend node 0 having status 1
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: SSL is requested but SSL support is
>>>>>> not available
>>>>>> 2014-10-28 09:29:56: pid 10040: DEBUG: I am PCP child with pid:10040
>>>>>> 2014-10-28 09:29:56: pid 10040: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:56: pid 10040: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: persistent DB connection to backend
>>>>>> node 0 having status 1 is successful
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: Backend DB node 1 status is 3
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9956
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9957
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9958
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9959
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9960
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9961
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9962
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9963
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9964
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9965
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9966
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9967
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9968
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9969
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9970
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9971
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9972
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9973
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9974
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9975
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9976
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9977
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9978
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9979
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9980
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9981
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9982
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9983
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9984
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9985
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9986
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: child with PID:9987
>>>>>> exits with status 0
>>>>>> 2014-10-28 09:29:56: pid 9955: LOG: worker child 9989 exits with status
>>>>>> 256
>>>>>> 2014-10-28 09:29:56: pid 9955: LOG: fork a new worker child pid 10042
>>>>>> 2014-10-28 09:29:56: pid 9955: DEBUG: reaper handler: exiting normally
>>>>>> 2014-10-28 09:29:56: pid 10042: DEBUG: I am 10042
>>>>>> 2014-10-28 09:29:56: pid 10042: DEBUG: initializing backend status
>>>>>> 2014-10-28 09:29:56: pid 10042: DEBUG: SSL is requested but SSL support
>>>>>> is not available
>>>>>> 2014-10-28 09:29:56: pid 10042: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:29:56: pid 10042: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:29:56: pid 10042: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:30:01: pid 9955: DEBUG: starting health check
>>>>>> 2014-10-28 09:30:01: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:01: pid 9955: DEBUG: Backend DB node 0 status is 1
>>>>>> 2014-10-28 09:30:01: pid 9955: DEBUG: Trying to make persistent DB
>>>>>> connection to backend node 0 having status 1
>>>>>> 2014-10-28 09:30:01: pid 9955: DEBUG: SSL is requested but SSL support is
>>>>>> not available
>>>>>> 2014-10-28 09:30:01: pid 9955: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:30:01: pid 9955: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:30:01: pid 9955: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:30:01: pid 9955: DEBUG: persistent DB connection to backend
>>>>>> node 0 having status 1 is successful
>>>>>> 2014-10-28 09:30:01: pid 9955: DEBUG: Backend DB node 1 status is 3
>>>>>> 2014-10-28 09:30:01: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:01: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:06: pid 10042: DEBUG: SSL is requested but SSL support
>>>>>> is not available
>>>>>> 2014-10-28 09:30:06: pid 10042: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:30:06: pid 10042: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:30:06: pid 10042: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:30:06: pid 9955: DEBUG: starting health check
>>>>>> 2014-10-28 09:30:06: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:06: pid 9955: DEBUG: Backend DB node 0 status is 1
>>>>>> 2014-10-28 09:30:06: pid 9955: DEBUG: Trying to make persistent DB
>>>>>> connection to backend node 0 having status 1
>>>>>> 2014-10-28 09:30:06: pid 9955: DEBUG: SSL is requested but SSL support is
>>>>>> not available
>>>>>> 2014-10-28 09:30:06: pid 9955: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:30:06: pid 9955: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:30:06: pid 9955: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:30:06: pid 9955: DEBUG: persistent DB connection to backend
>>>>>> node 0 having status 1 is successful
>>>>>> 2014-10-28 09:30:06: pid 9955: DEBUG: Backend DB node 1 status is 3
>>>>>> 2014-10-28 09:30:06: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:06: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:11: pid 9955: DEBUG: starting health check
>>>>>> 2014-10-28 09:30:11: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:11: pid 9955: DEBUG: Backend DB node 0 status is 1
>>>>>> 2014-10-28 09:30:11: pid 9955: DEBUG: Trying to make persistent DB
>>>>>> connection to backend node 0 having status 1
>>>>>> 2014-10-28 09:30:11: pid 9955: DEBUG: SSL is requested but SSL support is
>>>>>> not available
>>>>>> 2014-10-28 09:30:11: pid 9955: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:30:11: pid 9955: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:30:11: pid 9955: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:30:11: pid 9955: DEBUG: persistent DB connection to backend
>>>>>> node 0 having status 1 is successful
>>>>>> 2014-10-28 09:30:11: pid 9955: DEBUG: Backend DB node 1 status is 3
>>>>>> 2014-10-28 09:30:11: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:11: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:16: pid 10042: DEBUG: SSL is requested but SSL support
>>>>>> is not available
>>>>>> 2014-10-28 09:30:16: pid 10042: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:30:16: pid 10042: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:30:16: pid 10042: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:30:16: pid 9955: DEBUG: starting health check
>>>>>> 2014-10-28 09:30:16: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:16: pid 9955: DEBUG: Backend DB node 0 status is 1
>>>>>> 2014-10-28 09:30:16: pid 9955: DEBUG: Trying to make persistent DB
>>>>>> connection to backend node 0 having status 1
>>>>>> 2014-10-28 09:30:16: pid 9955: DEBUG: SSL is requested but SSL support is
>>>>>> not available
>>>>>> 2014-10-28 09:30:16: pid 9955: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:30:16: pid 9955: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:30:16: pid 9955: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:30:16: pid 9955: DEBUG: persistent DB connection to backend
>>>>>> node 0 having status 1 is successful
>>>>>> 2014-10-28 09:30:16: pid 9955: DEBUG: Backend DB node 1 status is 3
>>>>>> 2014-10-28 09:30:16: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:16: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:21: pid 9955: DEBUG: starting health check
>>>>>> 2014-10-28 09:30:21: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:21: pid 9955: DEBUG: Backend DB node 0 status is 1
>>>>>> 2014-10-28 09:30:21: pid 9955: DEBUG: Trying to make persistent DB
>>>>>> connection to backend node 0 having status 1
>>>>>> 2014-10-28 09:30:21: pid 9955: DEBUG: SSL is requested but SSL support is
>>>>>> not available
>>>>>> 2014-10-28 09:30:21: pid 9955: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:30:21: pid 9955: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:30:21: pid 9955: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:30:21: pid 9955: DEBUG: persistent DB connection to backend
>>>>>> node 0 having status 1 is successful
>>>>>> 2014-10-28 09:30:21: pid 9955: DEBUG: Backend DB node 1 status is 3
>>>>>> 2014-10-28 09:30:21: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:21: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:26: pid 10042: DEBUG: SSL is requested but SSL support
>>>>>> is not available
>>>>>> 2014-10-28 09:30:26: pid 10042: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:30:26: pid 10042: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:30:26: pid 10042: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:30:26: pid 9955: DEBUG: starting health check
>>>>>> 2014-10-28 09:30:26: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:26: pid 9955: DEBUG: Backend DB node 0 status is 1
>>>>>> 2014-10-28 09:30:26: pid 9955: DEBUG: Trying to make persistent DB
>>>>>> connection to backend node 0 having status 1
>>>>>> 2014-10-28 09:30:26: pid 9955: DEBUG: SSL is requested but SSL support is
>>>>>> not available
>>>>>> 2014-10-28 09:30:26: pid 9955: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:30:26: pid 9955: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:30:26: pid 9955: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:30:26: pid 9955: DEBUG: persistent DB connection to backend
>>>>>> node 0 having status 1 is successful
>>>>>> 2014-10-28 09:30:26: pid 9955: DEBUG: Backend DB node 1 status is 3
>>>>>> 2014-10-28 09:30:26: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:26: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:31: pid 9955: DEBUG: starting health check
>>>>>> 2014-10-28 09:30:31: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:31: pid 9955: DEBUG: Backend DB node 0 status is 1
>>>>>> 2014-10-28 09:30:31: pid 9955: DEBUG: Trying to make persistent DB
>>>>>> connection to backend node 0 having status 1
>>>>>> 2014-10-28 09:30:31: pid 9955: DEBUG: SSL is requested but SSL support is
>>>>>> not available
>>>>>> 2014-10-28 09:30:31: pid 9955: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:30:31: pid 9955: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:30:31: pid 9955: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:30:31: pid 9955: DEBUG: persistent DB connection to backend
>>>>>> node 0 having status 1 is successful
>>>>>> 2014-10-28 09:30:31: pid 9955: DEBUG: Backend DB node 1 status is 3
>>>>>> 2014-10-28 09:30:31: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:31: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:36: pid 10042: DEBUG: SSL is requested but SSL support
>>>>>> is not available
>>>>>> 2014-10-28 09:30:36: pid 10042: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:30:36: pid 10042: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:30:36: pid 10042: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:30:36: pid 9955: DEBUG: starting health check
>>>>>> 2014-10-28 09:30:36: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:36: pid 9955: DEBUG: Backend DB node 0 status is 1
>>>>>> 2014-10-28 09:30:36: pid 9955: DEBUG: Trying to make persistent DB
>>>>>> connection to backend node 0 having status 1
>>>>>> 2014-10-28 09:30:36: pid 9955: DEBUG: SSL is requested but SSL support is
>>>>>> not available
>>>>>> 2014-10-28 09:30:36: pid 9955: DEBUG: authenticate kind = 0
>>>>>> 2014-10-28 09:30:36: pid 9955: DEBUG: authenticate backend: key data
>>>>>> received
>>>>>> 2014-10-28 09:30:36: pid 9955: DEBUG: authenticate backend: transaction
>>>>>> state: I
>>>>>> 2014-10-28 09:30:36: pid 9955: DEBUG: persistent DB connection to backend
>>>>>> node 0 having status 1 is successful
>>>>>> 2014-10-28 09:30:36: pid 9955: DEBUG: Backend DB node 1 status is 3
>>>>>> 2014-10-28 09:30:36: pid 9955: DEBUG: health check: clearing alarm
>>>>>> 2014-10-28 09:30:36: pid 9955: DEBUG: health check: clearing alarm
>>>>>>
>>>>>> _______________________________________________
>>>>>> pgpool-hackers mailing list
>>>>>> pgpool-hackers at pgpool.net
>>>>>> http://www.pgpool.net/mailman/listinfo/pgpool-hackers
>>>>>>
>>>>>>
>>>> _______________________________________________
>>>> pgpool-hackers mailing list
>>>> pgpool-hackers at pgpool.net
>>>> http://www.pgpool.net/mailman/listinfo/pgpool-hackers
More information about the pgpool-hackers
mailing list