View Issue Details

IDProjectCategoryView StatusLast Update
0000724Pgpool-IIBugpublic2022-06-28 11:20
Reporter13709291081@163.com Assigned Topengbo  
PriorityimmediateSeverityminorReproducibilityalways
Status closedResolutionopen 
Product Version4.1.4 
Summary0000724: when start pgpool,it appears error
Description2021-07-28 16:03:33: pid 50562: LOG: Backend status file /usr/local/pgpool_status does not exist
2021-07-28 16:03:33: pid 50562: LOG: memory cache initialized
2021-07-28 16:03:33: pid 50562: DETAIL: memcache blocks :64
2021-07-28 16:03:33: pid 50562: LOG: pool_discard_oid_maps: discarded memqcache oid maps
2021-07-28 16:03:33: pid 50562: LOG: waiting for watchdog to initialize
2021-07-28 16:03:33: pid 50564: LOG: setting the local watchdog node name to "192.168.163.129:9999 Linux master.com"
2021-07-28 16:03:33: pid 50564: LOG: watchdog cluster is configured with 1 remote nodes
2021-07-28 16:03:33: pid 50564: LOG: watchdog remote node:0 on 192.168.163.128:9000
2021-07-28 16:03:33: pid 50564: LOG: interface monitoring is disabled in watchdog
2021-07-28 16:03:33: pid 50564: LOG: watchdog node state changed from [DEAD] to [LOADING]
2021-07-28 16:03:38: pid 50564: LOG: watchdog node state changed from [LOADING] to [JOINING]
[root@master log]# more pgpool.log
2021-07-28 16:03:33: pid 50562: LOG: Backend status file /usr/local/pgpool_status does not exist
2021-07-28 16:03:33: pid 50562: LOG: memory cache initialized
2021-07-28 16:03:33: pid 50562: DETAIL: memcache blocks :64
2021-07-28 16:03:33: pid 50562: LOG: pool_discard_oid_maps: discarded memqcache oid maps
2021-07-28 16:03:33: pid 50562: LOG: waiting for watchdog to initialize
2021-07-28 16:03:33: pid 50564: LOG: setting the local watchdog node name to "192.168.163.129:9999 Linux master.com"
2021-07-28 16:03:33: pid 50564: LOG: watchdog cluster is configured with 1 remote nodes
2021-07-28 16:03:33: pid 50564: LOG: watchdog remote node:0 on 192.168.163.128:9000
2021-07-28 16:03:33: pid 50564: LOG: interface monitoring is disabled in watchdog
2021-07-28 16:03:33: pid 50564: LOG: watchdog node state changed from [DEAD] to [LOADING]
2021-07-28 16:03:38: pid 50564: LOG: watchdog node state changed from [LOADING] to [JOINING]
2021-07-28 16:03:42: pid 50564: LOG: watchdog node state changed from [JOINING] to [INITIALIZING]
2021-07-28 16:03:43: pid 50564: LOG: I am the only alive node in the watchdog cluster
2021-07-28 16:03:43: pid 50564: HINT: skipping stand for coordinator state
2021-07-28 16:03:43: pid 50564: LOG: watchdog node state changed from [INITIALIZING] to [MASTER]
2021-07-28 16:03:43: pid 50564: LOG: I am announcing my self as master/coordinator watchdog node
2021-07-28 16:03:47: pid 50564: LOG: I am the cluster leader node
2021-07-28 16:03:47: pid 50564: DETAIL: our declare coordinator message is accepted by all nodes
2021-07-28 16:03:47: pid 50564: LOG: setting the local node "192.168.163.129:9999 Linux master.com" as watchdog cluster master
2021-07-28 16:03:47: pid 50564: LOG: I am the cluster leader node but we do not have enough nodes in cluster
2021-07-28 16:03:47: pid 50564: DETAIL: waiting for the quorum to start escalation process
2021-07-28 16:03:47: pid 50562: LOG: watchdog process is initialized
2021-07-28 16:03:47: pid 50562: DETAIL: watchdog messaging data version: 1.1
2021-07-28 16:03:47: pid 50564: LOG: new IPC connection received
2021-07-28 16:03:47: pid 50562: LOG: Setting up socket for 0.0.0.0:9999
2021-07-28 16:03:47: pid 50562: LOG: Setting up socket for :::9999
2021-07-28 16:03:47: pid 50562: LOG: find_primary_node_repeatedly: waiting for finding a primary node
2021-07-28 16:03:47: pid 50564: LOG: new IPC connection received
2021-07-28 16:03:47: pid 50580: LOG: 2 watchdog nodes are configured for lifecheck
2021-07-28 16:03:47: pid 50580: LOG: watchdog nodes ID:0 Name:"192.168.163.129:9999 Linux master.com"
2021-07-28 16:03:47: pid 50580: DETAIL: Host:"192.168.163.129" WD Port:9002 pgpool-II port:9999
2021-07-28 16:03:47: pid 50580: LOG: watchdog nodes ID:1 Name:"Not_Set"
2021-07-28 16:03:47: pid 50580: DETAIL: Host:"192.168.163.128" WD Port:9000 pgpool-II port:0
2021-07-28 16:03:47: pid 50562: LOG: find_primary_node: primary node is 0
2021-07-28 16:03:47: pid 50562: WARNING: failed to open status file at: "/usr/local/pgpool_status"
2021-07-28 16:03:47: pid 50562: DETAIL: "Permission denied"
2021-07-28 16:03:47: pid 50562: LOG: pgpool-II successfully started. version 4.1.4 (karasukiboshi)
2021-07-28 16:03:47: pid 50562: LOG: node status[0]: 1
2021-07-28 16:03:47: pid 50562: LOG: node status[1]: 0
2021-07-28 16:03:47: pid 50616: LOG: PCP process: 50616 started
2021-07-28 16:03:47: pid 50617: LOG: get_query_result falied: status: -2
2021-07-28 16:03:47: pid 50617: CONTEXT: while checking replication time lag
2021-07-28 16:03:47: pid 50617: LOG: Replication of node:1 is behind 22822352 bytes from the primary server (node:0)
2021-07-28 16:03:47: pid 50617: CONTEXT: while checking replication time lag
2021-07-28 16:03:48: pid 50614: LOG: set SO_REUSEPORT option to the socket
2021-07-28 16:03:48: pid 50614: LOG: creating watchdog heartbeat receive socket.
2021-07-28 16:03:48: pid 50614: DETAIL: set SO_REUSEPORT
2021-07-28 16:03:48: pid 50615: LOG: set SO_REUSEPORT option to the socket
2021-07-28 16:03:48: pid 50615: LOG: creating socket for sending heartbeat
2021-07-28 16:03:48: pid 50615: DETAIL: set SO_REUSEPORT
2021-07-28 16:03:57: pid 50617: LOG: get_query_result falied: status: -2
2021-07-28 16:03:57: pid 50617: CONTEXT: while checking replication time lag
2021-07-28 16:03:57: pid 50617: LOG: Replication of node:1 is behind 22822352 bytes from the primary server (node:0)
2021-07-28 16:03:57: pid 50617: CONTEXT: while checking replication time lag
2021-07-28 16:04:07: pid 50617: LOG: get_query_result falied: status: -2
2021-07-28 16:04:07: pid 50617: CONTEXT: while checking replication time lag
2021-07-28 16:04:07: pid 50617: LOG: Replication of node:1 is behind 22822352 bytes from the primary server (node:0)
2021-07-28 16:04:07: pid 50617: CONTEXT: while checking replication time lag
Steps To Reproducepgpool configuration is shown in attachment
TagsNo tags attached.

Activities

pengbo

2021-07-30 14:59

developer   ~0003909

It seems pgpool started successfully.

pengbo

2022-06-28 11:20

developer   ~0004072

No response over two weeks.
Close issue.

Issue History

Date Modified Username Field Change
2021-07-28 17:05 13709291081@163.com New Issue
2021-07-28 17:05 13709291081@163.com Issue generated from: 0000723
2021-07-29 09:09 pengbo Assigned To => pengbo
2021-07-29 09:09 pengbo Status new => assigned
2021-07-30 14:59 pengbo Note Added: 0003909
2021-07-30 14:59 pengbo Status assigned => feedback
2022-06-28 11:20 pengbo Note Added: 0004072
2022-06-28 11:20 pengbo Status feedback => closed