View Issue Details

IDProjectCategoryView StatusLast Update
0000308Pgpool-IIBugpublic2017-05-17 04:17
ReporteralpodyAssigned ToMuhammad Usama 
PrioritynormalSeverityblockReproducibilityalways
Status assignedResolutionopen 
Product Version3.6.2 
Target VersionFixed in Version 
Summary0000308: pgpool-II not started
DescriptionI use pgpool-II version 3.6.3 (subaruboshi), with two postgresql-9.6.0-x86_64 nodes on Linux Slackware 14.2x64 4.4.14 0000002 SMP Fri Jun 24 13:38:27 CDT 2016 x86_64 Intel(R) Core(TM) i5-4460 CPU @ 3.20GHz GenuineIntel GNU/Linux

Nodes run inside VirtualBox 5.1.22 r115126 (Qt5.6.2), which running on Windows 7 SP 1.

Debug log has strange lines:
May 10 11:20:48 slacky142x64 pgpool[1287]: [106-2] 2017-05-10 11:20:48: pid 1287: DETAIL: backend 1 weight: 1073741823.500000 flag: 0000, but in pgpool.conf this value set to 1


All Debug log is:

May 10 11:20:48 slacky142x64 pgpool[1287]: [1-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "pid_file_name" = "/var/run/pgpool/pgpool.pid"
May 10 11:20:48 slacky142x64 pgpool[1287]: [1-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [2-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "logdir" = "/var/log/pgpool"
May 10 11:20:48 slacky142x64 pgpool[1287]: [2-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [3-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "connection_cache" = "on"
May 10 11:20:48 slacky142x64 pgpool[1287]: [3-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [4-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "reset_query_list" = "ABORT; DISCARD ALL"
May 10 11:20:48 slacky142x64 pgpool[1287]: [4-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [5-1] 2017-05-10 11:20:48: pid 1287: DEBUG: extracting string tokens from [ABORT; DISCARD ALL] based on ;
May 10 11:20:48 slacky142x64 pgpool[1287]: [5-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2207
May 10 11:20:48 slacky142x64 pgpool[1287]: [6-1] 2017-05-10 11:20:48: pid 1287: DEBUG: initializing pool configuration
May 10 11:20:48 slacky142x64 pgpool[1287]: [6-2] 2017-05-10 11:20:48: pid 1287: DETAIL: extracting string tokens [token[0]: ABORT]
May 10 11:20:48 slacky142x64 pgpool[1287]: [6-3] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2214
May 10 11:20:48 slacky142x64 pgpool[1287]: [7-1] 2017-05-10 11:20:48: pid 1287: DEBUG: initializing pool configuration
May 10 11:20:48 slacky142x64 pgpool[1287]: [7-2] 2017-05-10 11:20:48: pid 1287: DETAIL: extracting string tokens [token[1]: DISCARD ALL]
May 10 11:20:48 slacky142x64 pgpool[1287]: [7-3] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2214
May 10 11:20:48 slacky142x64 pgpool[1287]: [8-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "replication_mode" = "off"
May 10 11:20:48 slacky142x64 pgpool[1287]: [8-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [9-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "replicate_select" = "off"
May 10 11:20:48 slacky142x64 pgpool[1287]: [9-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [10-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "insert_lock" = "on"
May 10 11:20:48 slacky142x64 pgpool[1287]: [10-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [11-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "lobj_lock_table" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [11-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [12-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "replication_stop_on_mismatch" = "off"
May 10 11:20:48 slacky142x64 pgpool[1287]: [12-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [13-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "failover_if_affected_tuples_mismatch" = "off"
May 10 11:20:48 slacky142x64 pgpool[1287]: [13-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [14-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "load_balance_mode" = "on"
May 10 11:20:48 slacky142x64 pgpool[1287]: [14-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [15-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "ignore_leading_white_space" = "on"
May 10 11:20:48 slacky142x64 pgpool[1287]: [15-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [16-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "white_function_list" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [16-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [17-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "black_function_list" = "nextval,setval,nextval,setval"
May 10 11:20:48 slacky142x64 pgpool[1287]: [17-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [18-1] 2017-05-10 11:20:48: pid 1287: DEBUG: extracting string tokens from [nextval,setval,nextval,setval] based on ,
May 10 11:20:48 slacky142x64 pgpool[1287]: [18-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2207
May 10 11:20:48 slacky142x64 pgpool[1287]: [19-1] 2017-05-10 11:20:48: pid 1287: DEBUG: initializing pool configuration
May 10 11:20:48 slacky142x64 pgpool[1287]: [19-2] 2017-05-10 11:20:48: pid 1287: DETAIL: extracting string tokens [token[0]: nextval]
May 10 11:20:48 slacky142x64 pgpool[1287]: [19-3] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2214
May 10 11:20:48 slacky142x64 pgpool[1287]: [20-1] 2017-05-10 11:20:48: pid 1287: DEBUG: initializing pool configuration
May 10 11:20:48 slacky142x64 pgpool[1287]: [20-2] 2017-05-10 11:20:48: pid 1287: DETAIL: extracting string tokens [token[1]: setval]
May 10 11:20:48 slacky142x64 pgpool[1287]: [20-3] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2214
May 10 11:20:48 slacky142x64 pgpool[1287]: [21-1] 2017-05-10 11:20:48: pid 1287: DEBUG: initializing pool configuration
May 10 11:20:48 slacky142x64 pgpool[1287]: [21-2] 2017-05-10 11:20:48: pid 1287: DETAIL: extracting string tokens [token[2]: nextval]
May 10 11:20:48 slacky142x64 pgpool[1287]: [21-3] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2214
May 10 11:20:48 slacky142x64 pgpool[1287]: [22-1] 2017-05-10 11:20:48: pid 1287: DEBUG: initializing pool configuration
May 10 11:20:48 slacky142x64 pgpool[1287]: [22-2] 2017-05-10 11:20:48: pid 1287: DETAIL: extracting string tokens [token[3]: setval]
May 10 11:20:48 slacky142x64 pgpool[1287]: [22-3] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2214
May 10 11:20:48 slacky142x64 pgpool[1287]: [23-1] 2017-05-10 11:20:48: pid 1287: DEBUG: initializing pool configuration
May 10 11:20:48 slacky142x64 pgpool[1287]: [23-2] 2017-05-10 11:20:48: pid 1287: DETAIL: adding regex pattern for "black_function_list" pattern: ^nextval$
May 10 11:20:48 slacky142x64 pgpool[1287]: [23-3] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config.l:173
May 10 11:20:48 slacky142x64 pgpool[1287]: [24-1] 2017-05-10 11:20:48: pid 1287: DEBUG: initializing pool configuration
May 10 11:20:48 slacky142x64 pgpool[1287]: [24-2] 2017-05-10 11:20:48: pid 1287: DETAIL: adding regex pattern for "black_function_list" pattern: ^setval$
May 10 11:20:48 slacky142x64 pgpool[1287]: [24-3] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config.l:173
May 10 11:20:48 slacky142x64 pgpool[1287]: [24-1] 2017-05-10 11:20:48: pid 1287: DEBUG: initializing pool configuration
May 10 11:20:48 slacky142x64 pgpool[1287]: [24-2] 2017-05-10 11:20:48: pid 1287: DETAIL: adding regex pattern for "black_function_list" pattern: ^setval$
May 10 11:20:48 slacky142x64 pgpool[1287]: [24-3] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config.l:173
May 10 11:20:48 slacky142x64 pgpool[1287]: [25-1] 2017-05-10 11:20:48: pid 1287: DEBUG: initializing pool configuration
May 10 11:20:48 slacky142x64 pgpool[1287]: [25-2] 2017-05-10 11:20:48: pid 1287: DETAIL: adding regex pattern for "black_function_list" pattern: ^nextval$
May 10 11:20:48 slacky142x64 pgpool[1287]: [25-3] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config.l:173
May 10 11:20:48 slacky142x64 pgpool[1287]: [26-1] 2017-05-10 11:20:48: pid 1287: DEBUG: initializing pool configuration
May 10 11:20:48 slacky142x64 pgpool[1287]: [26-2] 2017-05-10 11:20:48: pid 1287: DETAIL: adding regex pattern for "black_function_list" pattern: ^setval$
May 10 11:20:48 slacky142x64 pgpool[1287]: [26-3] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config.l:173
May 10 11:20:48 slacky142x64 pgpool[1287]: [27-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "database_redirect_preference_list" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [27-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [28-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "app_name_redirect_preference_list" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [28-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [29-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "allow_sql_comments" = "off"
May 10 11:20:48 slacky142x64 pgpool[1287]: [29-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [30-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "master_slave_mode" = "on"
May 10 11:20:48 slacky142x64 pgpool[1287]: [30-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [31-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "master_slave_sub_mode" = "stream"
May 10 11:20:48 slacky142x64 pgpool[1287]: [31-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [32-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "sr_check_period" = "10"
May 10 11:20:48 slacky142x64 pgpool[1287]: [32-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [33-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "sr_check_user" = "postgres"
May 10 11:20:48 slacky142x64 pgpool[1287]: [33-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [34-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "sr_check_password" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [34-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [35-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "sr_check_database" = "postgres"
May 10 11:20:48 slacky142x64 pgpool[1287]: [35-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [36-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "delay_threshold" = "0"
May 10 11:20:48 slacky142x64 pgpool[1287]: [36-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [37-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "follow_master_command" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [37-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [38-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "health_check_period" = "0"
May 10 11:20:48 slacky142x64 pgpool[1287]: [38-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [39-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "health_check_timeout" = "20"
May 10 11:20:48 slacky142x64 pgpool[1287]: [39-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [40-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "health_check_user" = "nobody"
May 10 11:20:48 slacky142x64 pgpool[1287]: [40-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [41-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "health_check_password" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [41-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [42-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "health_check_database" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [42-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [43-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "health_check_max_retries" = "0"
May 10 11:20:48 slacky142x64 pgpool[1287]: [43-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [44-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "health_check_retry_delay" = "1"
May 10 11:20:48 slacky142x64 pgpool[1287]: [44-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [45-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "connect_timeout" = "10000"
May 10 11:20:48 slacky142x64 pgpool[1287]: [45-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [46-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "failover_command" = "/home/pgpool/etc/failover.sh %d %h %p %D %m %M %H %P %r %R"
May 10 11:20:48 slacky142x64 pgpool[1287]: [46-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [47-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "failback_command" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [47-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [48-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "fail_over_on_backend_error" = "on"
May 10 11:20:48 slacky142x64 pgpool[1287]: [48-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [49-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "search_primary_node_timeout" = "300"
May 10 11:20:48 slacky142x64 pgpool[1287]: [49-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [50-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "recovery_user" = "nobody"
May 10 11:20:48 slacky142x64 pgpool[1287]: [50-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [51-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "recovery_password" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [51-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [52-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "recovery_1st_stage_command" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [52-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [53-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "recovery_2nd_stage_command" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [53-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [54-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "recovery_timeout" = "90"
May 10 11:20:48 slacky142x64 pgpool[1287]: [54-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [55-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "client_idle_limit_in_recovery" = "0"
May 10 11:20:48 slacky142x64 pgpool[1287]: [55-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [56-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "use_watchdog" = "off"
May 10 11:20:48 slacky142x64 pgpool[1287]: [56-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [57-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "trusted_servers" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [57-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [58-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "ping_path" = "/bin"
May 10 11:20:48 slacky142x64 pgpool[1287]: [58-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [59-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "wd_hostname" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [59-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [60-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "wd_port" = "9000"
May 10 11:20:48 slacky142x64 pgpool[1287]: [60-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [61-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "wd_priority" = "1"
May 10 11:20:48 slacky142x64 pgpool[1287]: [61-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [62-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "wd_authkey" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [62-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [63-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "wd_ipc_socket_dir" = "/tmp"
May 10 11:20:48 slacky142x64 pgpool[1287]: [63-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [64-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "delegate_IP" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [64-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [65-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "if_cmd_path" = "/sbin"
May 10 11:20:48 slacky142x64 pgpool[1287]: [65-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [66-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "if_up_cmd" = "ip addr add $_IP_$/24 dev eth0 label eth0:0"
May 10 11:20:48 slacky142x64 pgpool[1287]: [66-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [67-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "if_down_cmd" = "ip addr del $_IP_$/24 dev eth0"
May 10 11:20:48 slacky142x64 pgpool[1287]: [67-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [68-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "arping_path" = "/usr/sbin"
May 10 11:20:48 slacky142x64 pgpool[1287]: [68-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [69-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "arping_cmd" = "arping -U $_IP_$ -w 1"
May 10 11:20:48 slacky142x64 pgpool[1287]: [69-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [70-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "clear_memqcache_on_escalation" = "on"
May 10 11:20:48 slacky142x64 pgpool[1287]: [70-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [71-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "wd_escalation_command" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [71-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [72-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "wd_de_escalation_command" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [72-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [73-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "wd_monitoring_interfaces_list" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [73-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [74-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "wd_lifecheck_method" = "heartbeat"
May 10 11:20:48 slacky142x64 pgpool[1287]: [74-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [75-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "wd_interval" = "10"
May 10 11:20:48 slacky142x64 pgpool[1287]: [75-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [76-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "wd_heartbeat_port" = "9694"
May 10 11:20:48 slacky142x64 pgpool[1287]: [76-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [77-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "wd_heartbeat_keepalive" = "2"
May 10 11:20:48 slacky142x64 pgpool[1287]: [77-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [78-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "wd_heartbeat_deadtime" = "30"
May 10 11:20:48 slacky142x64 pgpool[1287]: [78-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [80-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "heartbeat_destination_port0" = "9694"
May 10 11:20:48 slacky142x64 pgpool[1287]: [80-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [81-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "heartbeat_device0" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [81-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [82-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "wd_life_point" = "3"
May 10 11:20:48 slacky142x64 pgpool[1287]: [82-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [83-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "wd_lifecheck_query" = "SELECT 1"
May 10 11:20:48 slacky142x64 pgpool[1287]: [83-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [84-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "wd_lifecheck_dbname" = "template1"
May 10 11:20:48 slacky142x64 pgpool[1287]: [84-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [85-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "wd_lifecheck_user" = "nobody"
May 10 11:20:48 slacky142x64 pgpool[1287]: [85-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [86-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "wd_lifecheck_password" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [86-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [87-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "relcache_expire" = "0"
May 10 11:20:48 slacky142x64 pgpool[1287]: [87-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [88-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "relcache_size" = "256"
May 10 11:20:48 slacky142x64 pgpool[1287]: [88-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [89-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "check_temp_table" = "on"
May 10 11:20:48 slacky142x64 pgpool[1287]: [89-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [90-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "check_unlogged_table" = "on"
May 10 11:20:48 slacky142x64 pgpool[1287]: [90-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [91-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "memory_cache_enabled" = "off"
May 10 11:20:48 slacky142x64 pgpool[1287]: [91-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [92-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "memqcache_method" = "shmem"
May 10 11:20:48 slacky142x64 pgpool[1287]: [92-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [93-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "memqcache_memcached_host" = "localhost"
May 10 11:20:48 slacky142x64 pgpool[1287]: [93-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [94-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "memqcache_memcached_port" = "11211"
May 10 11:20:48 slacky142x64 pgpool[1287]: [94-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [95-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "memqcache_total_size" = "67108864"
May 10 11:20:48 slacky142x64 pgpool[1287]: [95-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [96-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "memqcache_max_num_cache" = "1000000"
May 10 11:20:48 slacky142x64 pgpool[1287]: [96-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [97-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "memqcache_expire" = "0"
May 10 11:20:48 slacky142x64 pgpool[1287]: [97-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [98-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "memqcache_auto_cache_invalidation" = "on"
May 10 11:20:48 slacky142x64 pgpool[1287]: [98-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [99-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "memqcache_maxcache" = "409600"
May 10 11:20:48 slacky142x64 pgpool[1287]: [99-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [100-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "memqcache_cache_block_size" = "1048576"
May 10 11:20:48 slacky142x64 pgpool[1287]: [100-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [101-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "memqcache_oiddir" = "/var/log/pgpool/oiddir"
May 10 11:20:48 slacky142x64 pgpool[1287]: [101-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [102-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "white_memqcache_table_list" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [102-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [103-1] 2017-05-10 11:20:48: pid 1287: DEBUG: set_config_option "black_memqcache_table_list" = ""
May 10 11:20:48 slacky142x64 pgpool[1287]: [103-2] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:2381
May 10 11:20:48 slacky142x64 pgpool[1287]: [104-1] 2017-05-10 11:20:48: pid 1287: DEBUG: initializing pool configuration
May 10 11:20:48 slacky142x64 pgpool[1287]: [104-2] 2017-05-10 11:20:48: pid 1287: DETAIL: num_backends: 2 total_weight: 2.000000
May 10 11:20:48 slacky142x64 pgpool[1287]: [104-3] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:3536
May 10 11:20:48 slacky142x64 pgpool[1287]: [105-1] 2017-05-10 11:20:48: pid 1287: DEBUG: initializing pool configuration
May 10 11:20:48 slacky142x64 pgpool[1287]: [105-2] 2017-05-10 11:20:48: pid 1287: DETAIL: backend 0 weight: 1073741823.500000 flag: 0000
May 10 11:20:48 slacky142x64 pgpool[1287]: [105-3] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:3554
May 10 11:20:48 slacky142x64 pgpool[1287]: [106-1] 2017-05-10 11:20:48: pid 1287: DEBUG: initializing pool configuration
May 10 11:20:48 slacky142x64 pgpool[1287]: [106-2] 2017-05-10 11:20:48: pid 1287: DETAIL: backend 1 weight: 1073741823.500000 flag: 0000
May 10 11:20:48 slacky142x64 pgpool[1287]: [106-3] 2017-05-10 11:20:48: pid 1287: LOCATION: pool_config_variables.c:3554
May 10 11:20:48 slacky142x64 pgpool[1287]: [107-1] 2017-05-10 11:20:48: pid 1287: DEBUG: shmem_exit(-1): 2 callbacks to make
May 10 11:20:48 slacky142x64 pgpool[1288]: [108-1] 2017-05-10 11:20:48: pid 1288: DEBUG: pool_coninfo_size: num_init_children (32) * max_pool (4) * MAX_NUM_BACKENDS (128) * sizeof(ConnectionInfo) (136) = 2228224 bytes requested for shared memory
May 10 11:20:48 slacky142x64 pgpool[1288]: [108-2] 2017-05-10 11:20:48: pid 1288: LOCATION: pool_process_context.c:116
May 10 11:20:48 slacky142x64 pgpool[1288]: [109-1] 2017-05-10 11:20:48: pid 1288: DEBUG: ProcessInfo: num_init_children (32) * sizeof(ProcessInfo) (32) = 1024 bytes requested for shared memory
May 10 11:20:48 slacky142x64 pgpool[1288]: [109-2] 2017-05-10 11:20:48: pid 1288: LOCATION: pgpool_main.c:3094
May 10 11:20:48 slacky142x64 pgpool[1288]: [110-1] 2017-05-10 11:20:48: pid 1288: DEBUG: Request info are: sizeof(POOL_REQUEST_INFO) 5304 bytes requested for shared memory
May 10 11:20:48 slacky142x64 pgpool[1288]: [110-2] 2017-05-10 11:20:48: pid 1288: LOCATION: pgpool_main.c:3110
May 10 11:20:48 slacky142x64 pgpool[1288]: [111-1] 2017-05-10 11:20:48: pid 1288: DEBUG: Recovery management area: sizeof(int) 4 bytes requested for shared memory
May 10 11:20:48 slacky142x64 pgpool[1288]: [111-2] 2017-05-10 11:20:48: pid 1288: LOCATION: pgpool_main.c:3133
May 10 11:20:48 slacky142x64 pgpool[1288]: [113-1] 2017-05-10 11:20:48: pid 1288: DEBUG: shmem_exit(3): 15 callbacks to make
May 10 11:20:48 slacky142x64 pgpool[1288]: [113-2] 2017-05-10 11:20:48: pid 1288: LOCATION: elog.c:2587
May 10 11:20:48 slacky142x64 pgpool[1288]: [114-1] 2017-05-10 11:20:48: pid 1288: DEBUG: proc_exit(3): 1 callbacks to make
May 10 11:20:48 slacky142x64 pgpool[1288]: [114-2] 2017-05-10 11:20:48: pid 1288: LOCATION: elog.c:2559
May 10 11:20:48 slacky142x64 pgpool[1288]: [115-1] 2017-05-10 11:20:48: pid 1288: DEBUG: exit(3)
May 10 11:20:48 slacky142x64 pgpool[1288]: [115-2] 2017-05-10 11:20:48: pid 1288: LOCATION: elog.c:2513
May 10 11:20:48 slacky142x64 pgpool[1288]: [116-1] 2017-05-10 11:20:48: pid 1288: DEBUG: shmem_exit(-1): 0 callbacks to make
May 10 11:20:48 slacky142x64 pgpool[1288]: [116-2] 2017-05-10 11:20:48: pid 1288: LOCATION: elog.c:2587
May 10 11:20:48 slacky142x64 pgpool[1288]: [117-1] 2017-05-10 11:20:48: pid 1288: DEBUG: proc_exit(-1): 0 callbacks to make
May 10 11:20:48 slacky142x64 pgpool[1288]: [117-2] 2017-05-10 11:20:48: pid 1288: LOCATION: elog.c:2559

TagsNo tags attached.

Activities

alpody

2017-05-10 21:37

reporter  

pgpool.conf (34,731 bytes)

Muhammad Usama

2017-05-11 21:13

developer   ~0001504

This "DEBUG backend 1 weigh..." message is perfectly normal, Although I think the message may need to be removed or be made more elaborate.

Let me try to explanation what is happening:
Basically internally Pgpool-II use the normalised backend weights and map the user provided weight value to the scale of (0 - 0x7fffffff) i.e. (0 - 2147483647)
For example, if two backends are assigned 1.0 backend_weights then each backend will get 2147483647/(1+1) = 1073741823.500000 normalised weight.
But this value is only for the internal consumption of Pgpool-II and is should not be visible to outside world.

alpody

2017-05-11 22:16

reporter   ~0001505

If the message "DEBUG backend 1 weigh..." is normal. How i can detect problem when pgpool not create any processes and log with debug level 5 not contains strange info?

Muhammad Usama

2017-05-11 23:20

developer   ~0001506

Are you using the pgpool-II RPM? or building it by yourself?
It is strange that log contains no error or exit reason, Do you always get the same log? If possible can you redirect the log to the file and share it.

alpody

2017-05-17 04:17

reporter   ~0001516

I not used pgpool-II RPM. I had built with slackbuild script https://github.com/pasquall/slackpkgs/tree/master/pgpool-II. I had started pgpool 5 times. I got same result.

pgpool-slack-debug-0516.log (159,410 bytes)

Issue History

Date Modified Username Field Change
2017-05-10 21:37 alpody New Issue
2017-05-10 21:37 alpody File Added: pgpool.conf
2017-05-11 21:13 Muhammad Usama Note Added: 0001504
2017-05-11 22:16 alpody Note Added: 0001505
2017-05-11 23:20 Muhammad Usama Note Added: 0001506
2017-05-16 09:52 t-ishii Assigned To => Muhammad Usama
2017-05-16 09:52 t-ishii Status new => feedback
2017-05-17 04:17 alpody File Added: pgpool-slack-debug-0516.log
2017-05-17 04:17 alpody Note Added: 0001516
2017-05-17 04:17 alpody Status feedback => assigned