[root@pg_node1 ~] + Cluster: twpg (6976142033405049133) ---+---------+----+-----------+ | Member | Host | Role | State | TL | Lag in MB | +--------+---------------------+---------+---------+----+-----------+ | pg1 | 192.168.210.15:5432 | Leader | running | 6 | | | pg2 | 192.168.210.81:5432 | Replica | running | 6 | 0.0 | | pg3 | 192.168.210.33:5432 | Replica | running | 6 | 0.0 | +--------+---------------------+---------+---------+----+-----------+ [root@pg_node1 ~] 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever 2: eth0 inet 192.168.210.15/24 brd 192.168.210.255 scope global noprefixroute dynamic eth0\ valid_lft 63429sec preferred_lft 63429sec 2: eth0 inet 192.168.210.66/24 scope global secondary eth0\ valid_lft forever preferred_lft forever [root@pg_node1 ~] [root@pg_node1 ~] 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever 2: eth0 inet 192.168.210.15/24 brd 192.168.210.255 scope global noprefixroute dynamic eth0\ valid_lft 63357sec preferred_lft 63357sec [root@pg_node2 ~] + Cluster: twpg (6976142033405049133) ---+---------+----+-----------+ | Member | Host | Role | State | TL | Lag in MB | +--------+---------------------+---------+---------+----+-----------+ | pg1 | 192.168.210.15:5432 | Leader | running | 6 | | | pg2 | 192.168.210.81:5432 | Replica | running | 6 | 0.0 | | pg3 | 192.168.210.33:5432 | Replica | running | 6 | 0.0 | +--------+---------------------+---------+---------+----+-----------+ [root@pg_node2 ~] + Cluster: twpg (6976142033405049133) ---+---------+----+-----------+ | Member | Host | Role | State | TL | Lag in MB | +--------+---------------------+---------+---------+----+-----------+ | pg1 | 192.168.210.15:5432 | Replica | stopped | | unknown | | pg2 | 192.168.210.81:5432 | Replica | running | 7 | 0.0 | | pg3 | 192.168.210.33:5432 | Leader | running | 7 | | +--------+---------------------+---------+---------+----+-----------+ [root@pg_node2 ~] + Cluster: twpg (6976142033405049133) ---+---------+----+-----------+ | Member | Host | Role | State | TL | Lag in MB | +--------+---------------------+---------+---------+----+-----------+ | pg2 | 192.168.210.81:5432 | Replica | running | 7 | 0.0 | | pg3 | 192.168.210.33:5432 | Leader | running | 7 | | +--------+---------------------+---------+---------+----+-----------+ [root@pg_node3 ~] + Cluster: twpg (6976142033405049133) ---+---------+----+-----------+ | Member | Host | Role | State | TL | Lag in MB | +--------+---------------------+---------+---------+----+-----------+ | pg1 | 192.168.210.15:5432 | Leader | running | 6 | | | pg2 | 192.168.210.81:5432 | Replica | running | 6 | 0.0 | | pg3 | 192.168.210.33:5432 | Replica | running | 6 | 0.0 | +--------+---------------------+---------+---------+----+-----------+ [root@pg_node3 ~] + Cluster: twpg (6976142033405049133) ---+---------+----+-----------+ | Member | Host | Role | State | TL | Lag in MB | +--------+---------------------+---------+---------+----+-----------+ | pg2 | 192.168.210.81:5432 | Replica | running | 7 | 0.0 | | pg3 | 192.168.210.33:5432 | Leader | running | 7 | | +--------+---------------------+---------+---------+----+-----------+ [root@pg_node3 ~] 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever 2: eth0 inet 192.168.210.33/24 brd 192.168.210.255 scope global noprefixroute dynamic eth0\ valid_lft 69980sec preferred_lft 69980sec 2: eth0 inet 192.168.210.66/24 scope global secondary eth0\ valid_lft forever preferred_lft forever
Jun 23 09:59:07 pg_node3 patroni: 2021-06-23 09:59:07,841 INFO: Lock owner: pg1; I am pg3 Jun 23 09:59:07 pg_node3 patroni: 2021-06-23 09:59:07,841 INFO: does not have lock Jun 23 09:59:07 pg_node3 patroni: 2021-06-23 09:59:07,849 INFO: no action. i am a secondary and i am following a leader Jun 23 09:59:12 pg_node3 patroni: 2021-06-23 09:59:12.171 CST [25279] LOG: replication terminated by primary server Jun 23 09:59:12 pg_node3 patroni: 2021-06-23 09:59:12.171 CST [25279] DETAIL: End of WAL reached on timeline 6 at 0/50007C8. Jun 23 09:59:12 pg_node3 patroni: 2021-06-23 09:59:12.171 CST [25279] FATAL: could not send end-of-streaming message to primary: no COPY in progress Jun 23 09:59:12 pg_node3 patroni: 2021-06-23 09:59:12.172 CST [32443] LOG: invalid record length at 0/50007C8: wanted 24, got 0 Jun 23 09:59:12 pg_node3 patroni: 2021-06-23 09:59:12.179 CST [26335] FATAL: could not connect to the primary server: could not connect to server: Connection refused Jun 23 09:59:12 pg_node3 patroni: Is the server running on host "192.168.210.15" and accepting Jun 23 09:59:12 pg_node3 patroni: TCP/IP connections on port 5432? Jun 23 09:59:13 pg_node3 patroni: 2021-06-23 09:59:13,151 WARNING: Request failed to pg1: GET http://192.168.210.15:8008/patroni (HTTPConnectionPool(host=u'192.168.210.15', port=8008): Max retries exceeded with url: /patroni (Caused by ProtocolError('Connection aborted.', error(104, 'Connection reset by peer')))) Jun 23 09:59:13 pg_node3 patroni: 2021-06-23 09:59:13,153 INFO: Got response from pg2 http://192.168.210.81:8008/patroni: {"database_system_identifier": "6976142033405049133", "postmaster_start_time": "2021-06-23 09:51:39.194 CST", "timeline": 6, "cluster_unlocked": false, "patroni": {"scope": "twpg", "version": "2.0.2"}, "state": "running", "role": "replica", "xlog": {"received_location": 83888072, "replayed_timestamp": null, "paused": false, "replayed_location": 83888072}, "server_version": 130003} Jun 23 09:59:13 pg_node3 patroni: 2021-06-23 09:59:13,247 INFO: Software Watchdog activated with 25 second timeout, timing slack 15 seconds Jun 23 09:59:13 pg_node3 patroni: 2021-06-23 09:59:13,264 INFO: promoted self to leader by acquiring session lock Jun 23 09:59:13 pg_node3 patroni: server promoting Jun 23 09:59:13 pg_node3 patroni: 2021-06-23 09:59:13,273 INFO: Lock owner: pg3; I am pg3 Jun 23 09:59:13 pg_node3 patroni: 2021-06-23 09:59:13.276 CST [32443] LOG: received promote request Jun 23 09:59:13 pg_node3 patroni: 2021-06-23 09:59:13.276 CST [32443] LOG: redo done at 0/5000750 Jun 23 09:59:13 pg_node3 patroni: 2021-06-23 09:59:13,277 INFO: cleared rewind state after becoming the leader Jun 23 09:59:13 pg_node3 patroni: 2021-06-23 09:59:13.289 CST [32443] LOG: selected new timeline ID: 7 Jun 23 09:59:13 pg_node3 patroni: this is patroni callback on_role_change master twpg Jun 23 09:59:13 pg_node3 patroni: 2021-06-23 09:59:13,298 INFO: updated leader lock during promote Jun 23 09:59:13 pg_node3 systemd: Started Session c7 of user root. Jun 23 09:59:13 pg_node3 systemd: Started Session c8 of user root. Jun 23 09:59:13 pg_node3 patroni: 2021-06-23 09:59:13.585 CST [32443] LOG: archive recovery complete Jun 23 09:59:13 pg_node3 patroni: 2021-06-23 09:59:13.650 CST [32441] LOG: database system is ready to accept connections Jun 23 09:59:14 pg_node3 patroni: 2021-06-23 09:59:14,318 INFO: Lock owner: pg3; I am pg3 Jun 23 09:59:14 pg_node3 patroni: 2021-06-23 09:59:14,435 INFO: no action. i am the leader with the lock
Jun 23 09:59:10 pg_node2 patroni: 2021-06-23 09:59:10,228 INFO: Lock owner: pg1; I am pg2 Jun 23 09:59:10 pg_node2 patroni: 2021-06-23 09:59:10,228 INFO: does not have lock Jun 23 09:59:10 pg_node2 patroni: 2021-06-23 09:59:10,237 INFO: no action. i am a secondary and i am following a leader Jun 23 09:59:12 pg_node2 patroni: 2021-06-23 09:59:12.170 CST [26290] LOG: replication terminated by primary server Jun 23 09:59:12 pg_node2 patroni: 2021-06-23 09:59:12.170 CST [26290] DETAIL: End of WAL reached on timeline 6 at 0/50007C8. Jun 23 09:59:12 pg_node2 patroni: 2021-06-23 09:59:12.170 CST [26290] FATAL: could not send end-of-streaming message to primary: no COPY in progress Jun 23 09:59:12 pg_node2 patroni: 2021-06-23 09:59:12.170 CST [26286] LOG: invalid record length at 0/50007C8: wanted 24, got 0 Jun 23 09:59:12 pg_node2 patroni: 2021-06-23 09:59:12.177 CST [26671] FATAL: could not connect to the primary server: could not connect to server: Connection refused Jun 23 09:59:12 pg_node2 patroni: Is the server running on host "192.168.210.15" and accepting Jun 23 09:59:12 pg_node2 patroni: TCP/IP connections on port 5432? Jun 23 09:59:13 pg_node2 patroni: 2021-06-23 09:59:13,174 WARNING: Request failed to pg1: GET http://192.168.210.15:8008/patroni (HTTPConnectionPool(host=u'192.168.210.15', port=8008): Max retries exceeded with url: /patroni (Caused by NewConnectionError('<urllib3.connection.HTTPConnection object at 0x7f1b110fb9d0>: Failed to establish a new connection: [Errno 111] Connection refused',))) Jun 23 09:59:13 pg_node2 patroni: 2021-06-23 09:59:13,188 INFO: Got response from pg3 http://192.168.210.33:8008/patroni: {"database_system_identifier": "6976142033405049133", "postmaster_start_time": "2021-06-21 15:43:32.837 CST", "timeline": 6, "cluster_unlocked": true, "patroni": {"scope": "twpg", "version": "2.0.2"}, "state": "running", "role": "replica", "xlog": {"received_location": 83888072, "replayed_timestamp": null, "paused": false, "replayed_location": 83888072}, "server_version": 130003} Jun 23 09:59:13 pg_node2 patroni: 2021-06-23 09:59:13,277 INFO: Could not take out TTL lock Jun 23 09:59:13 pg_node2 patroni: server signaled Jun 23 09:59:13 pg_node2 patroni: 2021-06-23 09:59:13.291 CST [26284] LOG: received SIGHUP, reloading configuration files Jun 23 09:59:13 pg_node2 patroni: 2021-06-23 09:59:13.292 CST [26284] LOG: parameter "primary_conninfo" changed to "user=repuser passfile=/home/postgres/pgpass host=192.168.210.33 port=5432 sslmode=prefer application_name=pg2 gssencmode=prefer channel_binding=prefer" Jun 23 09:59:13 pg_node2 patroni: 2021-06-23 09:59:13.296 CST [26682] FATAL: could not connect to the primary server: could not connect to server: Connection refused Jun 23 09:59:13 pg_node2 patroni: Is the server running on host "192.168.210.15" and accepting Jun 23 09:59:13 pg_node2 patroni: TCP/IP connections on port 5432? Jun 23 09:59:13 pg_node2 patroni: 2021-06-23 09:59:13,330 INFO: following new leader after trying and failing to obtain lock Jun 23 09:59:13 pg_node2 patroni: 2021-06-23 09:59:13,333 INFO: Lock owner: pg3; I am pg2 Jun 23 09:59:13 pg_node2 patroni: 2021-06-23 09:59:13,333 INFO: does not have lock Jun 23 09:59:13 pg_node2 patroni: 2021-06-23 09:59:13,342 INFO: Local timeline=6 lsn=0/50007C8 Jun 23 09:59:13 pg_node2 patroni: 2021-06-23 09:59:13,349 INFO: master_timeline=6 Jun 23 09:59:13 pg_node2 patroni: 2021-06-23 09:59:13,354 INFO: no action. i am a secondary and i am following a leader Jun 23 09:59:14 pg_node2 patroni: 2021-06-23 09:59:14,373 INFO: Lock owner: pg3; I am pg2 Jun 23 09:59:14 pg_node2 patroni: 2021-06-23 09:59:14,373 INFO: does not have lock Jun 23 09:59:14 pg_node2 patroni: 2021-06-23 09:59:14,375 INFO: no action. i am a secondary and i am following a leader Jun 23 09:59:14 pg_node2 patroni: 2021-06-23 09:59:14,491 INFO: Lock owner: pg3; I am pg2 Jun 23 09:59:14 pg_node2 patroni: 2021-06-23 09:59:14,492 INFO: does not have lock Jun 23 09:59:14 pg_node2 patroni: 2021-06-23 09:59:14,500 INFO: no action. i am a secondary and i am following a leader Jun 23 09:59:18 pg_node2 patroni: 2021-06-23 09:59:18.306 CST [26690] LOG: fetching timeline history file for timeline 7 from primary server Jun 23 09:59:18 pg_node2 patroni: 2021-06-23 09:59:18.320 CST [26690] LOG: started streaming WAL from primary at 0/5000000 on timeline 6 Jun 23 09:59:18 pg_node2 patroni: 2021-06-23 09:59:18.320 CST [26690] LOG: replication terminated by primary server Jun 23 09:59:18 pg_node2 patroni: 2021-06-23 09:59:18.320 CST [26690] DETAIL: End of WAL reached on timeline 6 at 0/50007C8. Jun 23 09:59:18 pg_node2 patroni: 2021-06-23 09:59:18.321 CST [26286] LOG: new target timeline is 7 Jun 23 09:59:18 pg_node2 patroni: 2021-06-23 09:59:18.322 CST [26690] LOG: restarted WAL streaming at 0/5000000 on timeline 7 Jun 23 09:59:24 pg_node2 patroni: 2021-06-23 09:59:24,492 INFO: Lock owner: pg3; I am pg2 Jun 23 09:59:24 pg_node2 patroni: 2021-06-23 09:59:24,492 INFO: does not have lock Jun 23 09:59:24 pg_node2 patroni: 2021-06-23 09:59:24,513 INFO: no action. i am a secondary and i am following a leader
|