[Pgpool-hackers] make_persistent_db_connection: s_do_auth failed

Fri Aug 5 11:00:33 UTC 2011

You need to allow health_check_user access postgres database with no
password for judging primary/standby and replication delay checking.
--
Tatsuo Ishii
SRA OSS, Inc. Japan
English: http://www.sraoss.co.jp/index_en.php
Japanese: http://www.sraoss.co.jp

From: Huang Bambo <bambo.huang at gmail.com>
Subject: [Pgpool-hackers] make_persistent_db_connection: s_do_auth failed
Date: Tue, 2 Aug 2011 14:37:30 +0800
Message-ID: <CAPTvEdrLgeXoqN+fGcBfQfU6NZ3baM2aYu_8q3E=44NQ9JYoDw at mail.gmail.com>

> Hi, everyone.
>     I installed pgpool2 in debian 6.0.2 and everything seems OK (
> client can connect to pgpool and do queries), but the log shows
> there's some error.
>     the following is all the message generated by pgpool2.
> 
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: listen_addresses
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: '*' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: port
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 5433 kind: 2
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: pcp_port
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 9898 kind: 2
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: socket_dir
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: '/var/run/postgresql' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: pcp_socket_dir
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: '/var/run/postgresql' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: backend_socket_dir
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: '/var/run/postgresql' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: pcp_timeout
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 10 kind: 2
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: num_init_children
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 32 kind: 2
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: max_pool
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 4 kind: 2
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: child_life_time
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 300 kind: 2
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: connection_life_time
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 0 kind: 2
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: child_max_connections
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 0 kind: 2
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: client_idle_limit
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 0 kind: 2
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: authentication_timeout
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 60 kind: 2
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: logdir
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: '/var/log/postgresql' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: pid_file_name
> 2011-08-02 14:16:17 DEBUG: pid 4070: value:
> '/var/run/postgresql/pgpool.pid' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: replication_mode
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: false kind: 1
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: load_balance_mode
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: true kind: 1
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: replication_stop_on_mismatch
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: false kind: 1
> 2011-08-02 14:16:17 DEBUG: pid 4070: replication_stop_on_mismatch: 0
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: failover_if_affected_tuples_mismatch
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: true kind: 1
> 2011-08-02 14:16:17 DEBUG: pid 4070: failover_if_affected_tuples_mismatch: 1
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: replicate_select
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: false kind: 1
> 2011-08-02 14:16:17 DEBUG: pid 4070: replicate_select: 0
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: reset_query_list
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 'ABORT; DISCARD ALL' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: extract_string_tokens: token: ABORT
> 2011-08-02 14:16:17 DEBUG: pid 4070: extract_string_tokens: token:  DISCARD ALL
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: white_function_list
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: '' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: black_function_list
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 'nextval,setval' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: extract_string_tokens: token: nextval
> 2011-08-02 14:16:17 DEBUG: pid 4070: extract_string_tokens: token: setval
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: print_timestamp
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: true kind: 1
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: master_slave_mode
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: true kind: 1
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: master_slave_sub_mode
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 'stream' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: delay_threshold
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 100 kind: 2
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: log_standby_delay
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 'if_over_threshold' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: connection_cache
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: true kind: 1
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: health_check_timeout
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 20 kind: 2
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: health_check_period
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 10 kind: 2
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: health_check_user
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 'postgres' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: health_check_password
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: '********' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: failover_command
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: '' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: failback_command
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: '' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: fail_over_on_backend_error
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: true kind: 1
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: insert_lock
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: true kind: 1
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: ignore_leading_white_space
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: true kind: 1
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: log_statement
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: false kind: 1
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: log_per_node_statement
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: false kind: 1
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: log_connections
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: false kind: 1
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: log_hostname
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: false kind: 1
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: parallel_mode
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: false kind: 1
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: enable_query_cache
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: false kind: 1
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: pgpool2_hostname
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: '' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: system_db_hostname
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 'localhost' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: system_db_port
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 5432 kind: 2
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: system_db_dbname
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 'pgpool' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: system_db_schema
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 'pgpool_catalog' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: system_db_user
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 'pgpool' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: system_db_password
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: '' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: backend_hostname0
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 'debian-a' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: backend_port0
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 5432 kind: 2
> 2011-08-02 14:16:17 DEBUG: pid 4070: pool_config: port slot number 0
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: backend_weight0
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 1 kind: 2
> 2011-08-02 14:16:17 DEBUG: pid 4070: pool_config: weight slot number 0
> weight: 1.000000
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: backend_data_directory0
> 2011-08-02 14:16:17 DEBUG: pid 4070: value:
> '/var/lib/postgresql/9.0/main' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: enable_pool_hba
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: true kind: 1
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: recovery_user
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 'postgres' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: recovery_password
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: '********' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: recovery_1st_stage_command
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: '' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: recovery_2nd_stage_command
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: '' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: recovery_timeout
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 90 kind: 2
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: client_idle_limit_in_recovery
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 0 kind: 2
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: lobj_lock_table
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: '' kind: 4
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: ssl
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: false kind: 1
> 2011-08-02 14:16:17 DEBUG: pid 4070: key: debug_level
> 2011-08-02 14:16:17 DEBUG: pid 4070: value: 0 kind: 2
> 2011-08-02 14:16:17 DEBUG: pid 4070: loading
> "/etc/pgpool2/pool_hba.conf" for client authentication configuration
> file
> 2011-08-02 14:16:17 DEBUG: pid 4071: I am 4071
> 2011-08-02 14:16:17 DEBUG: pid 4072: I am 4072
> 2011-08-02 14:16:17 DEBUG: pid 4073: I am 4073
> 2011-08-02 14:16:17 DEBUG: pid 4074: I am 4074
> 2011-08-02 14:16:17 DEBUG: pid 4075: I am 4075
> 2011-08-02 14:16:17 DEBUG: pid 4076: I am 4076
> 2011-08-02 14:16:17 DEBUG: pid 4077: I am 4077
> 2011-08-02 14:16:17 DEBUG: pid 4079: I am 4079
> 2011-08-02 14:16:17 DEBUG: pid 4078: I am 4078
> 2011-08-02 14:16:17 DEBUG: pid 4080: I am 4080
> 2011-08-02 14:16:17 DEBUG: pid 4081: I am 4081
> 2011-08-02 14:16:17 DEBUG: pid 4083: I am 4083
> 2011-08-02 14:16:17 DEBUG: pid 4082: I am 4082
> 2011-08-02 14:16:17 DEBUG: pid 4084: I am 4084
> 2011-08-02 14:16:17 DEBUG: pid 4085: I am 4085
> 2011-08-02 14:16:17 DEBUG: pid 4086: I am 4086
> 2011-08-02 14:16:17 DEBUG: pid 4087: I am 4087
> 2011-08-02 14:16:17 DEBUG: pid 4088: I am 4088
> 2011-08-02 14:16:17 DEBUG: pid 4089: I am 4089
> 2011-08-02 14:16:17 DEBUG: pid 4092: I am 4092
> 2011-08-02 14:16:17 DEBUG: pid 4091: I am 4091
> 2011-08-02 14:16:17 DEBUG: pid 4090: I am 4090
> 2011-08-02 14:16:17 DEBUG: pid 4093: I am 4093
> 2011-08-02 14:16:17 DEBUG: pid 4095: I am 4095
> 2011-08-02 14:16:17 DEBUG: pid 4094: I am 4094
> 2011-08-02 14:16:17 DEBUG: pid 4096: I am 4096
> 2011-08-02 14:16:17 DEBUG: pid 4097: I am 4097
> 2011-08-02 14:16:17 DEBUG: pid 4098: I am 4098
> 2011-08-02 14:16:17 DEBUG: pid 4099: I am 4099
> 2011-08-02 14:16:17 DEBUG: pid 4100: I am 4100
> 2011-08-02 14:16:17 DEBUG: pid 4101: I am 4101
> 2011-08-02 14:16:17 LOG:   pid 4070: pgpool-II successfully started.
> version 3.0.4 (umiyameboshi)
> 2011-08-02 14:16:17 DEBUG: pid 4102: I am 4102
> 2011-08-02 14:16:17 DEBUG: pid 4104: I am 4104
> 2011-08-02 14:16:17 DEBUG: pid 4070: pool_ssl: SSL requested but SSL
> support is not available
> 2011-08-02 14:16:17 DEBUG: pid 4104: pool_ssl: SSL requested but SSL
> support is not available
> 2011-08-02 14:16:17 DEBUG: pid 4104: s_do_auth: auth kind: 5
> 2011-08-02 14:16:17 DEBUG: pid 4070: s_do_auth: auth kind: 5
> 2011-08-02 14:16:17 ERROR: pid 4070: s_do_auth: expecting R got E
> 2011-08-02 14:16:17 ERROR: pid 4070: make_persistent_db_connection:
> s_do_auth failed
> 2011-08-02 14:16:17 ERROR: pid 4070: find_primary_node:
> make_persistent_connection failed
> 2011-08-02 14:16:17 LOG:   pid 4070: find_primary_node: primary node id is 0
> 2011-08-02 14:16:17 DEBUG: pid 4070: starting health checking
> 2011-08-02 14:16:17 DEBUG: pid 4070: health_check: 0 th DB node status: 1
> 2011-08-02 14:16:17 ERROR: pid 4104: s_do_auth: expecting R got E
> 2011-08-02 14:16:17 ERROR: pid 4104: make_persistent_db_connection:
> s_do_auth failed
> 2011-08-02 14:16:27 DEBUG: pid 4104: pool_ssl: SSL requested but SSL
> support is not available
> 2011-08-02 14:16:27 DEBUG: pid 4104: s_do_auth: auth kind: 5
> 2011-08-02 14:16:27 ERROR: pid 4104: s_do_auth: expecting R got E
> 2011-08-02 14:16:27 ERROR: pid 4104: make_persistent_db_connection:
> s_do_auth failed
> ^C2011-08-02 14:16:27 DEBUG: pid 4102: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4101: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4100: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4099: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4098: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4097: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4096: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4095: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4094: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4093: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4092: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4091: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4090: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4089: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4088: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4087: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4086: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4085: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4084: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4083: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4082: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4081: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4080: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4079: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4078: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4077: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4076: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4075: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4074: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4073: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4072: child received shutdown request signal 2
> 2011-08-02 14:16:27 DEBUG: pid 4071: child received shutdown request signal 2
> 2011-08-02 14:16:27 LOG:   pid 4070: received fast shutdown request
> 2011-08-02 14:16:27 DEBUG: pid 4070: shmem_exit(0)
> _______________________________________________
> Pgpool-hackers mailing list
> Pgpool-hackers at pgfoundry.org
> http://pgfoundry.org/mailman/listinfo/pgpool-hackers