[Pgpool-hackers] make_persistent_db_connection: s_do_auth failed

Tue Aug 2 06:37:30 UTC 2011

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)