[pgpool-general: 7666] Re: Problems with response time
Bo Peng
pengbo at sraoss.co.jp
Mon Aug 16 11:11:30 JST 2021
Hello,
Thank you for your log.
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DEBUG: creating new connection to backend
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DETAIL: connecting 0 backend
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: LOCATION: pool_connection_pool.c:878
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: creating new connection to backend
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: connecting 1 backend
>From the log, I found that the connection created to node0 takes 5s.
I checked the source code and a possible cause might be that
pgpool was waiting for "connect_timeout".
https://www.pgpool.net/docs/latest/en/html/runtime-config-health-check.html#GUC-CONNECT-TIMEOUT
What's your configuration of "connect_timeout" paramater?
To identify cause, please check if the response time
changes by setting "connect_timeout=1000".
Regards,
> Here is the debug log (filtered on only the nagios check process):
>
> Aug 5 09:50:41 xxxxxx pgpool: 2021-08-05 09:50:41: pid 4811: LOG: fork a new child process with pid: 11242
> Aug 5 09:50:41 xxxxxx pgpool: 2021-08-05 09:50:41: pid 11242: DEBUG: initializing backend status
> Aug 5 09:50:41 xxxxxx pgpool: 2021-08-05 09:50:41: pid 11242: LOCATION: child.c:1411
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DEBUG: I am 11242 accept fd 7
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: LOCATION: child.c:1831
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: LOG: new connection received
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DETAIL: connecting host=192.168.153.5 port=52882
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: LOCATION: child.c:1844
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DEBUG: reading startup packet
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DETAIL: Protocol Major: 1234 Minor: 5679 database: user:
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: LOCATION: child.c:744
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DEBUG: selecting backend connection
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DETAIL: SSLRequest from client
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: LOCATION: child.c:1909
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DEBUG: reading startup packet
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DETAIL: Protocol Major: 3 Minor: 0 database: template1 user: nagios
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: LOCATION: child.c:744
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DEBUG: md5 authentication successful with frontend
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: LOCATION: pool_auth.c:1609
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DEBUG: creating new connection to backend
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DETAIL: connecting 0 backend
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: LOCATION: pool_connection_pool.c:878
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: creating new connection to backend
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: connecting 1 backend
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_connection_pool.c:878
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: creating new connection to backend
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: connecting 2 backend
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_connection_pool.c:878
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: authentication backend
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: auth kind:5
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_auth.c:420
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: authentication backend: 0
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: trying md5 authentication
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_auth.c:543
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: authentication backend: 1
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: trying md5 authentication
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_auth.c:543
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: authentication backend: 2
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: trying md5 authentication
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_auth.c:543
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:0 name:"application_name" value:""
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:1 name:"application_name" value:""
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:2 name:"application_name" value:""
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:0 name:"client_encoding" value:"UTF8"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:1 name:"client_encoding" value:"UTF8"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:2 name:"client_encoding" value:"UTF8"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:0 name:"DateStyle" value:"ISO, MDY"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:1 name:"DateStyle" value:"ISO, MDY"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:2 name:"DateStyle" value:"ISO, MDY"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:0 name:"integer_datetimes" value:"on"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:1 name:"integer_datetimes" value:"on"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:2 name:"integer_datetimes" value:"on"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:0 name:"IntervalStyle" value:"postgres"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:1 name:"IntervalStyle" value:"postgres"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:2 name:"IntervalStyle" value:"postgres"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:0 name:"is_superuser" value:"off"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:1 name:"is_superuser" value:"off"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:2 name:"is_superuser" value:"off"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:0 name:"server_encoding" value:"UTF8"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:1 name:"server_encoding" value:"UTF8"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:2 name:"server_encoding" value:"UTF8"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:0 name:"server_version" value:"12.7"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:1 name:"server_version" value:"12.7"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:2 name:"server_version" value:"12.7"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:0 name:"session_authorization" value:"nagios"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:1 name:"session_authorization" value:"nagios"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:2 name:"session_authorization" value:"nagios"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:0 name:"standard_conforming_strings" value:"on"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:1 name:"standard_conforming_strings" value:"on"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:2 name:"standard_conforming_strings" value:"on"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:0 name:"TimeZone" value:"Europe/Rome"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:1 name:"TimeZone" value:"Europe/Rome"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:2 name:"TimeZone" value:"Europe/Rome"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: authentication backend
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: cp->info[i]:0x7f5d669eec08 pid:8102
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_auth.c:777
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: authentication backend
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: cp->info[i]:0x7f5d669eec90 pid:18031
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_auth.c:777
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: authentication backend
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: cp->info[i]:0x7f5d669eed18 pid:31076
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_auth.c:777
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: sending backend key data
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: send pid 31076 to frontend
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_auth.c:917
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: selecting load balance node
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: selected backend id is 2
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_pg_utils.c:462
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: connecting to memcached on Host:"localhost:11211"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_memqcache.c:157
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOG: DB node id: 0 backend pid: 8102 statement: SELECT version()
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_proto_modules.c:3486
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: memcache encode key
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: username: "nagios" database_name: "template1"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: CONTEXT: while searching system catalog, When relcache is missed
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_memqcache.c:569
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: memcache encode key
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: query: "SELECT version()"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: CONTEXT: while searching system catalog, When relcache is missed
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_memqcache.c:576
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: memcache encode key
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: `nagiosSELECT version()template1' -> `30c2be92aabe22c2857721765bbe2c7a'
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: CONTEXT: while searching system catalog, When relcache is missed
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_memqcache.c:587
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: fetching from cache storage
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: search key "30c2be92aabe22c2857721765bbe2c7a"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: CONTEXT: while searching system catalog, When relcache is missed
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_memqcache.c:471
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: fetching from cache storage
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: query="SELECT version()" len:115
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: CONTEXT: while searching system catalog, When relcache is missed
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_memqcache.c:543
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: hit query cache
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: query:SELECT version()
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: CONTEXT: while searching system catalog, When relcache is missed
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_relcache.c:268
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: decide where to send the query
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: destination = 3 for query= " DISCARD ALL"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_query_context.c:496
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOG: DB node id: 0 backend pid: 8102 statement: DISCARD ALL
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_proto_modules.c:3486
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: waiting for query response
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: waiting for backend:0 to complete the query
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:532
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOG: DB node id: 2 backend pid: 31076 statement: DISCARD ALL
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_proto_modules.c:3486
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: waiting for query response
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: waiting for backend:2 to complete the query
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:532
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: setting backend connection close timer
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: close time 1628150146
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_connection_pool.c:348
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOG: frontend disconnection: session time: 0:00:05.087 user=nagios database=template1 host=192.168.153.5 port=52882
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: child.c:2060
> Aug 5 10:00:46 xxxxxx pgpool: 2021-08-05 10:00:46: pid 11242: DEBUG: child life 300 seconds expired
> Aug 5 10:00:46 xxxxxx pgpool: 2021-08-05 10:00:46: pid 11242: LOCATION: child.c:340
> Aug 5 10:00:46 xxxxxx pgpool: 2021-08-05 10:00:46: pid 4811: LOG: child process with pid: 11242 exits with status 256
>
> As you can see, there is a hole from 09:55:41 to 09:55:46.
> And this is the complete log from 09:55:41 to 09:55:46 with alla processes:
>
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DEBUG: I am 11242 accept fd 7
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: LOCATION: child.c:1831
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: LOG: new connection received
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DETAIL: connecting host=192.168.153.5 port=52882
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: LOCATION: child.c:1844
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DEBUG: reading startup packet
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DETAIL: Protocol Major: 1234 Minor: 5679 database: user:
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: LOCATION: child.c:744
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DEBUG: selecting backend connection
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DETAIL: SSLRequest from client
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: LOCATION: child.c:1909
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DEBUG: reading startup packet
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DETAIL: Protocol Major: 3 Minor: 0 database: template1 user: nagios
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: LOCATION: child.c:744
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DEBUG: md5 authentication successful with frontend
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: LOCATION: pool_auth.c:1609
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DEBUG: creating new connection to backend
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: DETAIL: connecting 0 backend
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11242: LOCATION: pool_connection_pool.c:878
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 10924: DEBUG: child life 300 seconds expired
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 10924: LOCATION: child.c:340
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4811: DEBUG: reaper handler
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4811: LOCATION: pgpool_main.c:2269
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4811: LOG: child process with pid: 10924 exits with status 256
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4811: LOCATION: pgpool_main.c:2338
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4811: LOG: fork a new child process with pid: 11557
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4811: LOCATION: pgpool_main.c:2458
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4811: DEBUG: reaper handler: exiting normally
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4811: LOCATION: pgpool_main.c:2472
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11557: DEBUG: initializing backend status
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 11557: LOCATION: child.c:1411
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4870: DEBUG: health check: clearing alarm
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4870: LOCATION: health_check.c:369
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4870: DEBUG: authenticate kind = 0
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4870: LOCATION: pool_auth.c:144
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4870: DEBUG: authenticate backend: key data received
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4870: LOCATION: pool_auth.c:254
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4870: DEBUG: authenticate backend: transaction state: I
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4870: LOCATION: pool_auth.c:287
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4870: DEBUG: health check: clearing alarm
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4870: LOCATION: health_check.c:386
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4870: DEBUG: health check: clearing alarm
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4870: LOCATION: health_check.c:386
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4872: DEBUG: health check: clearing alarm
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4872: LOCATION: health_check.c:369
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4872: DEBUG: authenticate kind = 0
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4872: LOCATION: pool_auth.c:144
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4872: DEBUG: authenticate backend: key data received
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4872: LOCATION: pool_auth.c:254
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4872: DEBUG: authenticate backend: transaction state: I
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4872: LOCATION: pool_auth.c:287
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4872: DEBUG: health check: clearing alarm
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4872: LOCATION: health_check.c:386
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4872: DEBUG: health check: clearing alarm
> Aug 5 09:55:41 xxxxxx pgpool: 2021-08-05 09:55:41: pid 4872: LOCATION: health_check.c:386
> Aug 5 09:55:44 xxxxxx pgpool: 2021-08-05 09:55:44: pid 29464: DEBUG: authenticate kind = 0
> Aug 5 09:55:44 xxxxxx pgpool: 2021-08-05 09:55:44: pid 29464: LOCATION: pool_auth.c:144
> Aug 5 09:55:44 xxxxxx pgpool: 2021-08-05 09:55:44: pid 29464: DEBUG: authenticate backend: key data received
> Aug 5 09:55:44 xxxxxx pgpool: 2021-08-05 09:55:44: pid 29464: LOCATION: pool_auth.c:254
> Aug 5 09:55:44 xxxxxx pgpool: 2021-08-05 09:55:44: pid 29464: DEBUG: authenticate backend: transaction state: I
> Aug 5 09:55:44 xxxxxx pgpool: 2021-08-05 09:55:44: pid 29464: LOCATION: pool_auth.c:287
> Aug 5 09:55:44 xxxxxx pgpool: 2021-08-05 09:55:44: pid 29464: DEBUG: authenticate kind = 0
> Aug 5 09:55:44 xxxxxx pgpool: 2021-08-05 09:55:44: pid 29464: LOCATION: pool_auth.c:144
> Aug 5 09:55:44 xxxxxx pgpool: 2021-08-05 09:55:44: pid 29464: DEBUG: authenticate backend: key data received
> Aug 5 09:55:44 xxxxxx pgpool: 2021-08-05 09:55:44: pid 29464: LOCATION: pool_auth.c:254
> Aug 5 09:55:44 xxxxxx pgpool: 2021-08-05 09:55:44: pid 29464: DEBUG: authenticate backend: transaction state: I
> Aug 5 09:55:44 xxxxxx pgpool: 2021-08-05 09:55:44: pid 29464: LOCATION: pool_auth.c:287
> Aug 5 09:55:45 xxxxxx pgpool: 2021-08-05 09:55:45: pid 4871: DEBUG: health check: clearing alarm
> Aug 5 09:55:45 xxxxxx pgpool: 2021-08-05 09:55:45: pid 4871: LOCATION: health_check.c:369
> Aug 5 09:55:45 xxxxxx pgpool: 2021-08-05 09:55:45: pid 4871: DEBUG: authenticate kind = 0
> Aug 5 09:55:45 xxxxxx pgpool: 2021-08-05 09:55:45: pid 4871: LOCATION: pool_auth.c:144
> Aug 5 09:55:45 xxxxxx pgpool: 2021-08-05 09:55:45: pid 4871: DEBUG: authenticate backend: key data received
> Aug 5 09:55:45 xxxxxx pgpool: 2021-08-05 09:55:45: pid 4871: LOCATION: pool_auth.c:254
> Aug 5 09:55:45 xxxxxx pgpool: 2021-08-05 09:55:45: pid 4871: DEBUG: authenticate backend: transaction state: I
> Aug 5 09:55:45 xxxxxx pgpool: 2021-08-05 09:55:45: pid 4871: LOCATION: pool_auth.c:287
> Aug 5 09:55:45 xxxxxx pgpool: 2021-08-05 09:55:45: pid 4871: DEBUG: health check: clearing alarm
> Aug 5 09:55:45 xxxxxx pgpool: 2021-08-05 09:55:45: pid 4871: LOCATION: health_check.c:386
> Aug 5 09:55:45 xxxxxx pgpool: 2021-08-05 09:55:45: pid 4871: DEBUG: health check: clearing alarm
> Aug 5 09:55:45 xxxxxx pgpool: 2021-08-05 09:55:45: pid 4871: LOCATION: health_check.c:386
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: creating new connection to backend
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: connecting 1 backend
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_connection_pool.c:878
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: creating new connection to backend
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: connecting 2 backend
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_connection_pool.c:878
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: authentication backend
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: auth kind:5
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_auth.c:420
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: authentication backend: 0
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: trying md5 authentication
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_auth.c:543
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: authentication backend: 1
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: trying md5 authentication
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_auth.c:543
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: authentication backend: 2
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: trying md5 authentication
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_auth.c:543
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:0 name:"application_name" value:""
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:1 name:"application_name" value:""
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:2 name:"application_name" value:""
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:0 name:"client_encoding" value:"UTF8"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:1 name:"client_encoding" value:"UTF8"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:2 name:"client_encoding" value:"UTF8"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:0 name:"DateStyle" value:"ISO, MDY"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:1 name:"DateStyle" value:"ISO, MDY"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:2 name:"DateStyle" value:"ISO, MDY"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:0 name:"integer_datetimes" value:"on"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:1 name:"integer_datetimes" value:"on"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:2 name:"integer_datetimes" value:"on"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:0 name:"IntervalStyle" value:"postgres"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:1 name:"IntervalStyle" value:"postgres"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:2 name:"IntervalStyle" value:"postgres"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:0 name:"is_superuser" value:"off"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:1 name:"is_superuser" value:"off"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:2 name:"is_superuser" value:"off"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:0 name:"server_encoding" value:"UTF8"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:1 name:"server_encoding" value:"UTF8"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:2 name:"server_encoding" value:"UTF8"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:0 name:"server_version" value:"12.7"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:1 name:"server_version" value:"12.7"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:2 name:"server_version" value:"12.7"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:0 name:"session_authorization" value:"nagios"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:1 name:"session_authorization" value:"nagios"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:2 name:"session_authorization" value:"nagios"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:0 name:"standard_conforming_strings" value:"on"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:1 name:"standard_conforming_strings" value:"on"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:2 name:"standard_conforming_strings" value:"on"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:0 name:"TimeZone" value:"Europe/Rome"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:1 name:"TimeZone" value:"Europe/Rome"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: process parameter status
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: backend:2 name:"TimeZone" value:"Europe/Rome"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:928
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: authentication backend
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: cp->info[i]:0x7f5d669eec08 pid:8102
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_auth.c:777
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: authentication backend
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: cp->info[i]:0x7f5d669eec90 pid:18031
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_auth.c:777
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: authentication backend
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: cp->info[i]:0x7f5d669eed18 pid:31076
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_auth.c:777
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: sending backend key data
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: send pid 31076 to frontend
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_auth.c:917
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: selecting load balance node
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: selected backend id is 2
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_pg_utils.c:462
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: connecting to memcached on Host:"localhost:11211"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_memqcache.c:157
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOG: DB node id: 0 backend pid: 8102 statement: SELECT version()
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_proto_modules.c:3486
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: memcache encode key
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: username: "nagios" database_name: "template1"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: CONTEXT: while searching system catalog, When relcache is missed
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_memqcache.c:569
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: memcache encode key
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: query: "SELECT version()"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: CONTEXT: while searching system catalog, When relcache is missed
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_memqcache.c:576
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: memcache encode key
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: `nagiosSELECT version()template1' -> `30c2be92aabe22c2857721765bbe2c7a'
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: CONTEXT: while searching system catalog, When relcache is missed
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_memqcache.c:587
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: fetching from cache storage
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: search key "30c2be92aabe22c2857721765bbe2c7a"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: CONTEXT: while searching system catalog, When relcache is missed
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_memqcache.c:471
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: fetching from cache storage
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: query="SELECT version()" len:115
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: CONTEXT: while searching system catalog, When relcache is missed
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_memqcache.c:543
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: hit query cache
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: query:SELECT version()
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: CONTEXT: while searching system catalog, When relcache is missed
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_relcache.c:268
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: decide where to send the query
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: destination = 3 for query= " DISCARD ALL"
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_query_context.c:496
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOG: DB node id: 0 backend pid: 8102 statement: DISCARD ALL
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_proto_modules.c:3486
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: waiting for query response
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: waiting for backend:0 to complete the query
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:532
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOG: DB node id: 2 backend pid: 31076 statement: DISCARD ALL
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_proto_modules.c:3486
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: waiting for query response
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: waiting for backend:2 to complete the query
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_process_query.c:532
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DEBUG: setting backend connection close timer
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: DETAIL: close time 1628150146
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: pool_connection_pool.c:348
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOG: frontend disconnection: session time: 0:00:05.087 user=nagios database=template1 host=192.168.153.5 port=52882
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 11242: LOCATION: child.c:2060
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 4870: DEBUG: health check: clearing alarm
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 4870: LOCATION: health_check.c:369
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 4870: DEBUG: authenticate kind = 0
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 4870: LOCATION: pool_auth.c:144
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 4870: DEBUG: authenticate backend: key data received
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 4870: LOCATION: pool_auth.c:254
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 4870: DEBUG: authenticate backend: transaction state: I
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 4870: LOCATION: pool_auth.c:287
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 4870: DEBUG: health check: clearing alarm
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 4870: LOCATION: health_check.c:386
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 4870: DEBUG: health check: clearing alarm
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 4870: LOCATION: health_check.c:386
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 4872: DEBUG: health check: clearing alarm
> Aug 5 09:55:46 xxxxxx pgpool: 2021-08-05 09:55:46: pid 4872: LOCATION: health_check.c:369
>
--
Bo Peng <pengbo at sraoss.co.jp>
SRA OSS, Inc. Japan
http://www.sraoss.co.jp/
More information about the pgpool-general
mailing list