[Pgpool-hackers] make_persistent_db_connection: s_do_auth failed

Fri Aug 5 17:43:04 UTC 2011

On Fri, Aug 5, 2011 at 7:00 PM, Tatsuo Ishii <ishii at sraoss.co.jp> wrote:
> You need to allow health_check_user access postgres database with no
> password for judging primary/standby and replication delay checking.

Strictly, should be empty password.
And I also found that in 3.1 you are trying to fix this problem but
not fixed all the place use this prolem, in main.c, this problem is
still there.

And I also have a question about this. Is it necessary to per-node
setting for this?

> --
> 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
>