2025-09-22 11:11:37.538: watchdog pid 902555: DEBUG: sending watchdog packet to socket:7, type:[M], command ID:24557, data Length:124
2025-09-22 11:11:37.539: watchdog pid 902555: DEBUG: received watchdog packet type:I
2025-09-22 11:11:37.539: watchdog pid 902555: DEBUG: reading packet type I of length 369
2025-09-22 11:11:37.539: watchdog pid 902555: DEBUG: STATE MACHINE INVOKED WITH EVENT = PACKET RECEIVED Current State = LEADER
2025-09-22 11:11:37.539: watchdog pid 902555: DEBUG: received packet, watchdog node:[192.168.1.72:9999 Linux localhost.localdomain] command id:[24557] type:[NODE INFO] state:[LEADER]
2025-09-22 11:11:37.539: watchdog pid 902555: DEBUG: packet I with command ID 24557 is reply to the command M
2025-09-22 11:11:37.539: watchdog pid 902555: DEBUG: Watchdog node "192.168.1.72:9999 Linux localhost.localdomain" has replied for command id 24557
2025-09-22 11:11:37.539: watchdog pid 902555: DEBUG: command I with command id 24557 is finished with COMMAND_FINISHED_ALL_REPLIED
2025-09-22 11:11:37.539: watchdog pid 902555: DEBUG: STATE MACHINE INVOKED WITH EVENT = COMMAND FINISHED Current State = LEADER
2025-09-22 11:11:37.539: watchdog pid 902555: DEBUG: I am the cluster leader node command finished with status:[ALL NODES REPLIED]
2025-09-22 11:11:37.539: watchdog pid 902555: DETAIL: The command was sent to 1 nodes and 1 nodes replied to it
2025-09-22 11:11:37.548: watchdog pid 902555: DEBUG: received watchdog packet type:d
2025-09-22 11:11:37.549: watchdog pid 902555: DEBUG: reading packet type d of length 62
2025-09-22 11:11:37.549: watchdog pid 902555: DEBUG: STATE MACHINE INVOKED WITH EVENT = PACKET RECEIVED Current State = LEADER
2025-09-22 11:11:37.549: watchdog pid 902555: DEBUG: received packet, watchdog node:[192.168.1.72:9999 Linux localhost.localdomain] command id:[24954] type:[DATA REQUEST FOR LEADER] state:[LEADER]
2025-09-22 11:11:37.549: watchdog pid 902555: DEBUG: sending packet, watchdog node:[192.168.1.72:9999 Linux localhost.localdomain] command id:[24954] type:[DATA] state:[LEADER]
2025-09-22 11:11:37.549: watchdog pid 902555: DEBUG: sending watchdog packet to socket:7, type:[D], command ID:24954, data Length:110
2025-09-22 11:11:37.924: health_check1 pid 902636: DEBUG: health check: clearing alarm
2025-09-22 11:11:37.924: health_check1 pid 902636: DEBUG: SSL is requested but SSL support is not available
2025-09-22 11:11:37.926: health_check1 pid 902636: DEBUG: authenticate kind = 10
2025-09-22 11:11:37.935: health_check1 pid 902636: DEBUG: SCRAM authentication successful for user:pgpool
2025-09-22 11:11:37.935: health_check1 pid 902636: DEBUG: authenticate backend: key data received
2025-09-22 11:11:37.935: health_check1 pid 902636: DEBUG: authenticate backend: transaction state: I
2025-09-22 11:11:37.935: health_check1 pid 902636: DEBUG: health check: clearing alarm
2025-09-22 11:11:37.935: health_check1 pid 902636: DEBUG: health check: clearing alarm
2025-09-22 11:11:38.969: heart_beat_sender pid 902601: DEBUG: watchdog heartbeat: send heartbeat signal to 192.168.1.72:9694
2025-09-22 11:11:40.969: heart_beat_sender pid 902601: DEBUG: watchdog heartbeat: send heartbeat signal to 192.168.1.72:9694
2025-09-22 11:11:42.544: watchdog pid 902555: DEBUG: new IPC connection received
2025-09-22 11:11:42.544: watchdog pid 902555: DEBUG: sending watchdog packet to socket:9, type:[7], command ID:0, data Length:34
2025-09-22 11:11:42.544: sr_check_worker pid 903049: DEBUG: watchdog status: 4
2025-09-22 11:11:42.544: watchdog pid 902555: DEBUG: new IPC connection received
2025-09-22 11:11:42.544: watchdog pid 902555: DEBUG: received the get data request from local pgpool-II on IPC interface
2025-09-22 11:11:42.544: watchdog pid 902555: DEBUG: sending watchdog packet to socket:9, type:[7], command ID:0, data Length:0
2025-09-22 11:11:42.544: sr_check_worker pid 903049: DEBUG: 1:0: Unexpected
2025-09-22 11:11:42.545: watchdog pid 902555: DEBUG: new IPC connection received
2025-09-22 11:11:42.545: watchdog pid 902555: DEBUG: sending watchdog packet to socket:9, type:[7], command ID:0, data Length:34
2025-09-22 11:11:42.545: sr_check_worker pid 903049: DEBUG: quorum: 1 node_count: -1
2025-09-22 11:11:42.545: sr_check_worker pid 903049: DEBUG: pool_acquire_follow_primary_lock: lock was not held by anyone
2025-09-22 11:11:42.545: sr_check_worker pid 903049: DEBUG: pool_acquire_follow_primary_lock: succeeded in acquiring lock
2025-09-22 11:11:42.545: sr_check_worker pid 903049: DEBUG: SSL is requested but SSL support is not available
2025-09-22 11:11:42.547: sr_check_worker pid 903049: DEBUG: authenticate kind = 10
2025-09-22 11:11:42.555: sr_check_worker pid 903049: DEBUG: SCRAM authentication successful for user:rep
2025-09-22 11:11:42.555: sr_check_worker pid 903049: DEBUG: authenticate backend: key data received
2025-09-22 11:11:42.555: sr_check_worker pid 903049: DEBUG: authenticate backend: transaction state: I
2025-09-22 11:11:42.555: sr_check_worker pid 903049: DEBUG: do_query: extended:0 query:"SELECT pg_catalog.pg_is_in_recovery()"
2025-09-22 11:11:42.556: sr_check_worker pid 903049: DEBUG: verify_backend_node_status: there's no primary node
2025-09-22 11:11:42.556: sr_check_worker pid 903049: DEBUG: node status[0]: 0
2025-09-22 11:11:42.556: sr_check_worker pid 903049: DEBUG: node status[1]: 2
2025-09-22 11:11:42.556: sr_check_worker pid 903049: DEBUG: pool_release_follow_primary_lock called
2025-09-22 11:11:42.935: health_check1 pid 902636: DEBUG: health check: clearing alarm
2025-09-22 11:11:42.936: health_check1 pid 902636: DEBUG: SSL is requested but SSL support is not available
2025-09-22 11:11:42.938: health_check1 pid 902636: DEBUG: authenticate kind = 10
2025-09-22 11:11:42.946: health_check1 pid 902636: DEBUG: SCRAM authentication successful for user:pgpool
2025-09-22 11:11:42.947: health_check1 pid 902636: DEBUG: authenticate backend: key data received
2025-09-22 11:11:42.947: health_check1 pid 902636: DEBUG: authenticate backend: transaction state: I
2025-09-22 11:11:42.947: health_check1 pid 902636: DEBUG: health check: clearing alarm
2025-09-22 11:11:42.947: health_check1 pid 902636: DEBUG: health check: clearing alarm
2025-09-22 11:11:42.969: heart_beat_sender pid 902601: DEBUG: watchdog heartbeat: send heartbeat signal to 192.168.1.72:9694
2025-09-22 11:11:44.969: heart_beat_sender pid 902601: DEBUG: watchdog heartbeat: send heartbeat signal to 192.168.1.72:9694
2025-09-22 11:11:46.970: heart_beat_sender pid 902601: DEBUG: watchdog heartbeat: send heartbeat signal to 192.168.1.72:9694
2025-09-22 11:11:47.550: watchdog pid 902555: DEBUG: STATE MACHINE INVOKED WITH EVENT = TIMEOUT Current State = LEADER
2025-09-22 11:11:47.550: watchdog pid 902555: DEBUG: sending packet, watchdog node:[192.168.1.72:9999 Linux localhost.localdomain] command id:[24558] type:[IAM COORDINATOR] state:[LEADER]
2025-09-22 11:11:47.550: watchdog pid 902555: DEBUG: sending watchdog packet to socket:7, type:[M], command ID:24558, data Length:124
2025-09-22 11:11:47.551: watchdog pid 902555: DEBUG: received watchdog packet type:I
2025-09-22 11:11:47.551: watchdog pid 902555: DEBUG: reading packet type I of length 369
2025-09-22 11:11:47.551: watchdog pid 902555: DEBUG: STATE MACHINE INVOKED WITH EVENT = PACKET RECEIVED Current State = LEADER
2025-09-22 11:11:47.551: watchdog pid 902555: DEBUG: received packet, watchdog node:[192.168.1.72:9999 Linux localhost.localdomain] command id:[24558] type:[NODE INFO] state:[LEADER]
2025-09-22 11:11:47.551: watchdog pid 902555: DEBUG: packet I with command ID 24558 is reply to the command M
2025-09-22 11:11:47.551: watchdog pid 902555: DEBUG: Watchdog node "192.168.1.72:9999 Linux localhost.localdomain" has replied for command id 24558
2025-09-22 11:11:47.551: watchdog pid 902555: DEBUG: command I with command id 24558 is finished with COMMAND_FINISHED_ALL_REPLIED
2025-09-22 11:11:47.551: watchdog pid 902555: DEBUG: STATE MACHINE INVOKED WITH EVENT = COMMAND FINISHED Current State = LEADER
2025-09-22 11:11:47.551: watchdog pid 902555: DEBUG: I am the cluster leader node command finished with status:[ALL NODES REPLIED]
2025-09-22 11:11:47.551: watchdog pid 902555: DETAIL: The command was sent to 1 nodes and 1 nodes replied to it
2025-09-22 11:11:47.560: watchdog pid 902555: DEBUG: received watchdog packet type:d
2025-09-22 11:11:47.560: watchdog pid 902555: DEBUG: reading packet type d of length 62
2025-09-22 11:11:47.560: watchdog pid 902555: DEBUG: STATE MACHINE INVOKED WITH EVENT = PACKET RECEIVED Current State = LEADER
2025-09-22 11:11:47.560: watchdog pid 902555: DEBUG: received packet, watchdog node:[192.168.1.72:9999 Linux localhost.localdomain] command id:[24955] type:[DATA REQUEST FOR LEADER] state:[LEADER]
2025-09-22 11:11:47.560: watchdog pid 902555: DEBUG: sending packet, watchdog node:[192.168.1.72:9999 Linux localhost.localdomain] command id:[24955] type:[DATA] state:[LEADER]
2025-09-22 11:11:47.560: watchdog pid 902555: DEBUG: sending watchdog packet to socket:7, type:[D], command ID:24955, data Length:110
2025-09-22 11:11:47.947: health_check1 pid 902636: DEBUG: health check: clearing alarm
2025-09-22 11:11:47.948: health_check1 pid 902636: DEBUG: SSL is requested but SSL support is not available
2025-09-22 11:11:47.949: health_check1 pid 902636: DEBUG: authenticate kind = 10
2025-09-22 11:11:47.958: health_check1 pid 902636: DEBUG: SCRAM authentication successful for user:pgpool
2025-09-22 11:11:47.958: health_check1 pid 902636: DEBUG: authenticate backend: key data received
2025-09-22 11:11:47.958: health_check1 pid 902636: DEBUG: authenticate backend: transaction state: I
2025-09-22 11:11:47.958: health_check1 pid 902636: DEBUG: health check: clearing alarm
2025-09-22 11:11:47.958: health_check1 pid 902636: DEBUG: health check: clearing alarm
2025-09-22 11:11:48.970: heart_beat_sender pid 902601: DEBUG: watchdog heartbeat: send heartbeat signal to 192.168.1.72:9694
2025-09-22 11:11:50.971: heart_beat_sender pid 902601: DEBUG: watchdog heartbeat: send heartbeat signal to 192.168.1.72:9694
2025-09-22 11:11:52.557: watchdog pid 902555: DEBUG: new IPC connection received
2025-09-22 11:11:52.557: watchdog pid 902555: DEBUG: sending watchdog packet to socket:9, type:[7], command ID:0, data Length:34
2025-09-22 11:11:52.557: sr_check_worker pid 903049: DEBUG: watchdog status: 4
2025-09-22 11:11:52.557: watchdog pid 902555: DEBUG: new IPC connection received
2025-09-22 11:11:52.557: watchdog pid 902555: DEBUG: received the get data request from local pgpool-II on IPC interface
2025-09-22 11:11:52.557: watchdog pid 902555: DEBUG: sending watchdog packet to socket:9, type:[7], command ID:0, data Length:0
2025-09-22 11:11:52.557: sr_check_worker pid 903049: DEBUG: 1:0: Unexpected
2025-09-22 11:11:52.557: watchdog pid 902555: DEBUG: new IPC connection received
2025-09-22 11:11:52.557: watchdog pid 902555: DEBUG: sending watchdog packet to socket:9, type:[7], command ID:0, data Length:34
2025-09-22 11:11:52.557: sr_check_worker pid 903049: DEBUG: quorum: 1 node_count: -1
2025-09-22 11:11:52.558: sr_check_worker pid 903049: DEBUG: pool_acquire_follow_primary_lock: lock was not held by anyone
2025-09-22 11:11:52.558: sr_check_worker pid 903049: DEBUG: pool_acquire_follow_primary_lock: succeeded in acquiring lock
2025-09-22 11:11:52.558: sr_check_worker pid 903049: DEBUG: SSL is requested but SSL support is not available
2025-09-22 11:11:52.560: sr_check_worker pid 903049: DEBUG: authenticate kind = 10
2025-09-22 11:11:52.569: sr_check_worker pid 903049: DEBUG: SCRAM authentication successful for user:rep
2025-09-22 11:11:52.569: sr_check_worker pid 903049: DEBUG: authenticate backend: key data received
2025-09-22 11:11:52.569: sr_check_worker pid 903049: DEBUG: authenticate backend: transaction state: I
2025-09-22 11:11:52.569: sr_check_worker pid 903049: DEBUG: do_query: extended:0 query:"SELECT pg_catalog.pg_is_in_recovery()"
2025-09-22 11:11:52.570: sr_check_worker pid 903049: DEBUG: verify_backend_node_status: there's no primary node
2025-09-22 11:11:52.570: sr_check_worker pid 903049: DEBUG: node status[0]: 0
2025-09-22 11:11:52.570: sr_check_worker pid 903049: DEBUG: node status[1]: 2
2025-09-22 11:11:52.570: sr_check_worker pid 903049: DEBUG: pool_release_follow_primary_lock called
2025-09-22 11:11:52.959: health_check1 pid 902636: DEBUG: health check: clearing alarm
2025-09-22 11:11:52.960: health_check1 pid 902636: DEBUG: SSL is requested but SSL support is not available
2025-09-22 11:11:52.961: health_check1 pid 902636: DEBUG: authenticate kind = 10
2025-09-22 11:11:52.970: health_check1 pid 902636: DEBUG: SCRAM authentication successful for user:pgpool
2025-09-22 11:11:52.970: health_check1 pid 902636: DEBUG: authenticate backend: key data received
2025-09-22 11:11:52.970: health_check1 pid 902636: DEBUG: authenticate backend: transaction state: I
2025-09-22 11:11:52.970: health_check1 pid 902636: DEBUG: health check: clearing alarm
2025-09-22 11:11:52.970: health_check1 pid 902636: DEBUG: health check: clearing alarm
2025-09-22 11:11:52.971: heart_beat_sender pid 902601: DEBUG: watchdog heartbeat: send heartbeat signal to 192.168.1.72:9694
2025-09-22 11:11:54.971: heart_beat_sender pid 902601: DEBUG: watchdog heartbeat: send heartbeat signal to 192.168.1.72:9694
2025-09-22 11:11:56.972: heart_beat_sender pid 902601: DEBUG: watchdog heartbeat: send heartbeat signal to 192.168.1.72:9694
2025-09-22 11:11:57.563: watchdog pid 902555: DEBUG: STATE MACHINE INVOKED WITH EVENT = TIMEOUT Current State = LEADER
2025-09-22 11:11:57.563: watchdog pid 902555: DEBUG: sending packet, watchdog node:[192.168.1.72:9999 Linux localhost.localdomain] command id:[24559] type:[IAM COORDINATOR] state:[LEADER]
2025-09-22 11:11:57.563: watchdog pid 902555: DEBUG: sending watchdog packet to socket:7, type:[M], command ID:24559, data Length:124
2025-09-22 11:11:57.564: watchdog pid 902555: DEBUG: received watchdog packet type:I
2025-09-22 11:11:57.564: watchdog pid 902555: DEBUG: reading packet type I of length 369
2025-09-22 11:11:57.564: watchdog pid 902555: DEBUG: STATE MACHINE INVOKED WITH EVENT = PACKET RECEIVED Current State = LEADER
2025-09-22 11:11:57.564: watchdog pid 902555: DEBUG: received packet, watchdog node:[192.168.1.72:9999 Linux localhost.localdomain] command id:[24559] type:[NODE INFO] state:[LEADER]
2025-09-22 11:11:57.564: watchdog pid 902555: DEBUG: packet I with command ID 24559 is reply to the command M
2025-09-22 11:11:57.564: watchdog pid 902555: DEBUG: Watchdog node "192.168.1.72:9999 Linux localhost.localdomain" has replied for command id 24559
2025-09-22 11:11:57.564: watchdog pid 902555: DEBUG: command I with command id 24559 is finished with COMMAND_FINISHED_ALL_REPLIED
2025-09-22 11:11:57.564: watchdog pid 902555: DEBUG: STATE MACHINE INVOKED WITH EVENT = COMMAND FINISHED Current State = LEADER
2025-09-22 11:11:57.564: watchdog pid 902555: DEBUG: I am the cluster leader node command finished with status:[ALL NODES REPLIED]
2025-09-22 11:11:57.564: watchdog pid 902555: DETAIL: The command was sent to 1 nodes and 1 nodes replied to it
2025-09-22 11:11:57.573: watchdog pid 902555: DEBUG: received watchdog packet type:d
2025-09-22 11:11:57.573: watchdog pid 902555: DEBUG: reading packet type d of length 62
2025-09-22 11:11:57.573: watchdog pid 902555: DEBUG: STATE MACHINE INVOKED WITH EVENT = PACKET RECEIVED Current State = LEADER
2025-09-22 11:11:57.573: watchdog pid 902555: DEBUG: received packet, watchdog node:[192.168.1.72:9999 Linux localhost.localdomain] command id:[24956] type:[DATA REQUEST FOR LEADER] state:[LEADER]
2025-09-22 11:11:57.573: watchdog pid 902555: DEBUG: sending packet, watchdog node:[192.168.1.72:9999 Linux localhost.localdomain] command id:[24956] type:[DATA] state:[LEADER]
2025-09-22 11:11:57.573: watchdog pid 902555: DEBUG: sending watchdog packet to socket:7, type:[D], command ID:24956, data Length:110
2025-09-22 11:11:57.971: health_check1 pid 902636: DEBUG: health check: clearing alarm
2025-09-22 11:11:57.971: health_check1 pid 902636: DEBUG: SSL is requested but SSL support is not available
2025-09-22 11:11:57.973: health_check1 pid 902636: DEBUG: authenticate kind = 10
2025-09-22 11:11:57.981: health_check1 pid 902636: DEBUG: SCRAM authentication successful for user:pgpool
2025-09-22 11:11:57.981: health_check1 pid 902636: DEBUG: authenticate backend: key data received
2025-09-22 11:11:57.981: health_check1 pid 902636: DEBUG: authenticate backend: transaction state: I
2025-09-22 11:11:57.981: health_check1 pid 902636: DEBUG: health check: clearing alarm
2025-09-22 11:11:57.981: health_check1 pid 902636: DEBUG: health check: clearing alarm
2025-09-22 11:11:58.972: heart_beat_sender pid 902601: DEBUG: watchdog heartbeat: send heartbeat signal to 192.168.1.72:9694
2025-09-22 11:12:00.972: heart_beat_sender pid 902601: DEBUG: watchdog heartbeat: send heartbeat signal to 192.168.1.72:9694
2025-09-22 11:12:02.570: watchdog pid 902555: DEBUG: new IPC connection received
2025-09-22 11:12:02.570: watchdog pid 902555: DEBUG: sending watchdog packet to socket:9, type:[7], command ID:0, data Length:34
2025-09-22 11:12:02.570: sr_check_worker pid 903049: DEBUG: watchdog status: 4
2025-09-22 11:12:02.570: watchdog pid 902555: DEBUG: new IPC connection received
2025-09-22 11:12:02.571: watchdog pid 902555: DEBUG: received the get data request from local pgpool-II on IPC interface
2025-09-22 11:12:02.571: watchdog pid 902555: DEBUG: sending watchdog packet to socket:9, type:[7], command ID:0, data Length:0
2025-09-22 11:12:02.571: sr_check_worker pid 903049: DEBUG: 1:0: Unexpected
2025-09-22 11:12:02.571: watchdog pid 902555: DEBUG: new IPC connection received
2025-09-22 11:12:02.571: watchdog pid 902555: DEBUG: sending watchdog packet to socket:9, type:[7], command ID:0, data Length:34
2025-09-22 11:12:02.571: sr_check_worker pid 903049: DEBUG: quorum: 1 node_count: -1
2025-09-22 11:12:02.571: sr_check_worker pid 903049: DEBUG: pool_acquire_follow_primary_lock: lock was not held by anyone
2025-09-22 11:12:02.571: sr_check_worker pid 903049: DEBUG: pool_acquire_follow_primary_lock: succeeded in acquiring lock
2025-09-22 11:12:02.571: sr_check_worker pid 903049: DEBUG: SSL is requested but SSL support is not available
2025-09-22 11:12:02.572: sr_check_worker pid 903049: DEBUG: authenticate kind = 10
2025-09-22 11:12:02.581: sr_check_worker pid 903049: DEBUG: SCRAM authentication successful for user:rep
2025-09-22 11:12:02.581: sr_check_worker pid 903049: DEBUG: authenticate backend: key data received
2025-09-22 11:12:02.581: sr_check_worker pid 903049: DEBUG: authenticate backend: transaction state: I
2025-09-22 11:12:02.581: sr_check_worker pid 903049: DEBUG: do_query: extended:0 query:"SELECT pg_catalog.pg_is_in_recovery()"
2025-09-22 11:12:02.581: sr_check_worker pid 903049: DEBUG: verify_backend_node_status: there's no primary node
2025-09-22 11:12:02.581: sr_check_worker pid 903049: DEBUG: node status[0]: 0
2025-09-22 11:12:02.581: sr_check_worker pid 903049: DEBUG: node status[1]: 2
2025-09-22 11:12:02.581: sr_check_worker pid 903049: DEBUG: pool_release_follow_primary_lock called
2025-09-22 11:12:02.972: heart_beat_sender pid 902601: DEBUG: watchdog heartbeat: send heartbeat signal to 192.168.1.72:9694
2025-09-22 11:12:02.981: health_check1 pid 902636: DEBUG: health check: clearing alarm
2025-09-22 11:12:02.982: health_check1 pid 902636: DEBUG: SSL is requested but SSL support is not available
2025-09-22 11:12:02.983: health_check1 pid 902636: DEBUG: authenticate kind = 10
2025-09-22 11:12:02.992: health_check1 pid 902636: DEBUG: SCRAM authentication successful for user:pgpool
2025-09-22 11:12:02.992: health_check1 pid 902636: DEBUG: authenticate backend: key data received
2025-09-22 11:12:02.992: health_check1 pid 902636: DEBUG: authenticate backend: transaction state: I
2025-09-22 11:12:02.992: health_check1 pid 902636: DEBUG: health check: clearing alarm
2025-09-22 11:12:02.992: health_check1 pid 902636: DEBUG: health check: clearing alarm
2025-09-22 11:12:04.973: heart_beat_sender pid 902601: DEBUG: watchdog heartbeat: send heartbeat signal to 192.168.1.72:9694
2025-09-22 11:12:06.973: heart_beat_sender pid 902601: DEBUG: watchdog heartbeat: send heartbeat signal to 192.168.1.72:9694根据pgpool日志,排除错误,写出详细故障处理步骤