Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

LOG: could not receive data from client: Connection reset by peer #840

Open
marcofortina opened this issue Jan 19, 2024 · 4 comments
Open

LOG: could not receive data from client: Connection reset by peer #840

marcofortina opened this issue Jan 19, 2024 · 4 comments

Comments

@marcofortina
Copy link

marcofortina commented Jan 19, 2024 •
edited
Loading

Hello,

checking the status of service repmgr I have this error in the /var/lib/pgsql/14/data/log/postgresql-Fri.log file:

2024-01-19 13:36:42.015 CET [3534] LOG: could not receive data from client: Connection reset by peer

[postgres@cs1 data]$ /usr/pgsql-14/bin/repmgr -f /etc/repmgr/14/repmgr.conf service status
 ID | Name            | Role    | Status    | Upstream        | repmgrd | PID  | Paused? | Upstream last seen
----+-----------------+---------+-----------+-----------------+---------+------+---------+--------------------
 1  | cs1.localdomain | primary | * running |                 | running | 3502 | no      | n/a                
 2  | cs2.localdomain | standby |   running | cs1.localdomain | running | 2560 | no      | 1 second(s) ago    

[postgres@cs2 ~]$ /usr/pgsql-14/bin/repmgr -f /etc/repmgr/14/repmgr.conf service status
 ID | Name            | Role    | Status    | Upstream        | repmgrd | PID  | Paused? | Upstream last seen
----+-----------------+---------+-----------+-----------------+---------+------+---------+--------------------
 1  | cs1.localdomain | primary | * running |                 | running | 3502 | no      | n/a                
 2  | cs2.localdomain | standby |   running | cs1.localdomain | running | 2560 | no      | 0 second(s) ago

Here the contents of /etc/repmgr/14/repmgr.conf:

[postgres@cs1 data]$ cat /etc/repmgr/14/repmgr.conf
node_id=1
node_name='cs1.localdomain'

conninfo='host=cs1.localdomain user=repmgr password=**** dbname=repmgr connect_timeout=2 sslmode=verify-ca sslcert=/etc/repmgr/14/server.crt sslkey=/etc/repmgr/14/server.key sslrootcert=/etc/repmgr/14/root.crt'

data_directory='/var/lib/pgsql/14/data/'

replication_user='repmgr'
replication_type='physical'

use_replication_slots=yes

pg_bindir='/usr/pgsql-14/bin/'

repmgr_bindir='/usr/pgsql-14/bin/'

ssh_options='-q -o ConnectTimeout=10'

failover='automatic'

promote_command='/usr/pgsql-14/bin/repmgr standby promote -f /etc/repmgr/14/repmgr.conf --log-to-file'
follow_command='/usr/pgsql-14/bin/repmgr standby follow -f /etc/repmgr/14/repmgr.conf --log-to-file --upstream-node-id=%n'

service_start_command = 'sudo systemctl start postgresql-14'
service_stop_command = 'sudo systemctl stop postgresql-14'
service_restart_command = 'sudo systemctl restart postgresql-14'
service_reload_command = 'sudo systemctl reload postgresql-14'

repmgrd_service_start_command = 'sudo systemctl start repmgr-14'
repmgrd_service_stop_command = 'sudo systemctl stop repmgr-14'

[postgres@cs2 ~]$ cat /etc/repmgr/14/repmgr.conf
node_id=2
node_name='cs2.localdomain'

conninfo='host=cs2.localdomain user=repmgr password=**** dbname=repmgr connect_timeout=2 sslmode=verify-ca sslcert=/etc/repmgr/14/server.crt sslkey=/etc/repmgr/14/server.key sslrootcert=/etc/repmgr/14/root.crt'

data_directory='/var/lib/pgsql/14/data/'

replication_user='repmgr'
replication_type='physical'

use_replication_slots=yes

pg_bindir='/usr/pgsql-14/bin/'

repmgr_bindir='/usr/pgsql-14/bin/'

ssh_options='-q -o ConnectTimeout=10'

failover='automatic'

promote_command='/usr/pgsql-14/bin/repmgr standby promote -f /etc/repmgr/14/repmgr.conf --log-to-file'
follow_command='/usr/pgsql-14/bin/repmgr standby follow -f /etc/repmgr/14/repmgr.conf --log-to-file --upstream-node-id=%n'

service_start_command = 'sudo systemctl start postgresql-14'
service_stop_command = 'sudo systemctl stop postgresql-14'
service_restart_command = 'sudo systemctl restart postgresql-14'
service_reload_command = 'sudo systemctl reload postgresql-14'

repmgrd_service_start_command = 'sudo systemctl start repmgr-14'
repmgrd_service_stop_command = 'sudo systemctl stop repmgr-14'

Here how SSL is configured inside file postgresql.conf on both servers:

[postgres@cs2 data]$ grep ^ssl postgresql.conf
ssl = on
ssl_ca_file = '/etc/repmgr/14/root.crt'
ssl_cert_file = '/etc/repmgr/14/server.crt'
ssl_key_file = '/etc/repmgr/14/server.key'
ssl_ciphers = 'HIGH:MEDIUM:+3DES:!aNULL' # allowed SSL ciphers
ssl_prefer_server_ciphers = on

Here how client connections are configured inside file pg_hba.conf on both servers:

[postgres@cs2 data]$ cat pg_hba.conf
hostssl all             all             127.0.0.1/32            md5 clientcert=verify-ca
hostssl all             all             ::1/128                 md5 clientcert=verify-ca

hostssl replication     all             127.0.0.1/32            md5 clientcert=verify-ca
hostssl replication     all             ::1/128                 md5 clientcert=verify-ca

hostssl replication     repmgr          127.0.0.1/32            md5 clientcert=verify-ca
hostssl replication     repmgr          192.168.1.53/32         md5 clientcert=verify-ca
hostssl replication     repmgr          192.168.1.182/32        md5 clientcert=verify-ca
hostssl repmgr          repmgr          127.0.0.1/32            md5 clientcert=verify-ca
hostssl repmgr          repmgr          192.168.1.53/32         md5 clientcert=verify-ca
hostssl repmgr          repmgr          192.168.1.182/32        md5 clientcert=verify-ca

hostssl all             postgres        192.168.1.53/32         md5 clientcert=verify-ca
hostssl all             postgres        192.168.1.182/32        md5 clientcert=verify-ca
hostssl all             postgres        0.0.0.0/0               reject
hostssl all             all             0.0.0.0/0               md5 clientcert=verify-ca

Everything seems to work except the 'Connection reset by peer' error.

Could you please check why i have this error logged?

Best regards,
Marco
@marcofortina
Copy link
Author

Here client messages with debug5 on:

[postgres@cs1 data]$ /usr/pgsql-14/bin/repmgr -f /etc/repmgr/14/repmgr.conf service status
DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  statement: SET synchronous_commit TO 'local'
DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  duration: 0.323 ms
DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  statement:     SELECT n.node_id, n.type, n.upstream_node_id, n.node_name, n.conninfo, n.repluser, n.slot_name, n.location, n.priority, n.active, n.config_file, un.node_name AS upstream_node_name, NULL AS attached       FROM repmgr.nodes n  LEFT JOIN repmgr.nodes un         ON un.node_id = n.upstream_node_id  ORDER BY n.node_id 
DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  duration: 2.592 ms
DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  statement: SET synchronous_commit TO 'local'
DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  duration: 0.323 ms
DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  statement: SELECT pg_catalog.pg_is_in_recovery()
DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  duration: 0.625 ms
DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  statement: SELECT repmgr.get_repmgrd_pid()
DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  duration: 0.447 ms
DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  statement: SELECT repmgr.repmgrd_is_running()
DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  duration: 0.338 ms
DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  statement: SELECT repmgr.repmgrd_is_paused()
DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  duration: 0.460 ms
DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  statement: SELECT pg_catalog.pg_is_in_recovery()
DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  duration: 0.311 ms
DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  statement: SELECT CASE WHEN pg_catalog.pg_is_in_recovery() IS FALSE    THEN -1    ELSE repmgr.get_upstream_last_seen()  END AS upstream_last_seen 
DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  duration: 0.452 ms
DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  statement: SELECT pg_catalog.pg_is_in_recovery()
DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  duration: 0.323 ms
DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  statement:     SELECT n.node_id, n.type, n.upstream_node_id, n.node_name, n.conninfo, n.repluser, n.slot_name, n.location, n.priority, n.active, n.config_file, un.node_name AS upstream_node_name, NULL AS attached       FROM repmgr.nodes n  LEFT JOIN repmgr.nodes un         ON un.node_id = n.upstream_node_id WHERE n.node_id = 1
DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  duration: 2.251 ms
DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  statement: SET synchronous_commit TO 'local'
DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  duration: 0.405 ms
DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  statement:  SELECT pid, state    FROM pg_catalog.pg_stat_replication   WHERE application_name = 'cs2.localdomain'
DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  duration: 4.728 ms
DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  statement:   SELECT CASE            WHEN pg_catalog.pg_has_role('pg_monitor','MEMBER')              THEN TRUE            WHEN pg_catalog.pg_has_role('pg_read_all_stats','MEMBER')              THEN TRUE            ELSE FALSE          END AS has_pg_monitor
DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
LOG:  duration: 0.893 ms
 ID | Name            | Role    | Status    | Upstream        | repmgrd     | PID | Paused? | Upstream last seen
----+-----------------+---------+-----------+-----------------+-------------+-----+---------+--------------------
 1  | cs1.localdomain | primary | * running |                 | not running | n/a | n/a     | n/a                
 2  | cs2.localdomain | standby |   running | cs1.localdomain | not running | n/a | n/a     | n/a

@marcofortina
Copy link
Author

And here server log with debug5 on:

2024-01-19 13:25:39 CET [3263]: [19-1] user=,db= DEBUG:  forked new backend, pid=3450 socket=10
2024-01-19 13:25:39 CET [3450]: [1-1] user=[unknown],db=[unknown] LOG:  connection received: host=192.168.1.53 port=49252
2024-01-19 13:25:39 CET [3450]: [2-1] user=[unknown],db=[unknown] DEBUG:  SSL: handshake start: "before SSL initialization"
2024-01-19 13:25:39 CET [3450]: [3-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "before SSL initialization"
2024-01-19 13:25:39 CET [3450]: [4-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept exit (-1): "before SSL initialization"
2024-01-19 13:25:39 CET [3450]: [5-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "before SSL initialization"
2024-01-19 13:25:39 CET [3450]: [6-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS read client hello"
2024-01-19 13:25:39 CET [3450]: [7-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write server hello"
2024-01-19 13:25:39 CET [3450]: [8-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write change cipher spec"
2024-01-19 13:25:39 CET [3450]: [9-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "TLSv1.3 early data"
2024-01-19 13:25:39 CET [3450]: [10-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept exit (-1): "TLSv1.3 early data"
2024-01-19 13:25:39 CET [3450]: [11-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "TLSv1.3 early data"
2024-01-19 13:25:39 CET [3450]: [12-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS read client hello"
2024-01-19 13:25:39 CET [3450]: [13-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write server hello"
2024-01-19 13:25:39 CET [3450]: [14-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "TLSv1.3 write encrypted extensions"
2024-01-19 13:25:39 CET [3450]: [15-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write certificate request"
2024-01-19 13:25:39 CET [3450]: [16-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write certificate"
2024-01-19 13:25:39 CET [3450]: [17-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "TLSv1.3 write server certificate verify"
2024-01-19 13:25:39 CET [3450]: [18-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write finished"
2024-01-19 13:25:39 CET [3450]: [19-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "TLSv1.3 early data"
2024-01-19 13:25:39 CET [3450]: [20-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept exit (-1): "TLSv1.3 early data"
2024-01-19 13:25:39 CET [3450]: [21-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "TLSv1.3 early data"
2024-01-19 13:25:39 CET [3450]: [22-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS read client certificate"
2024-01-19 13:25:39 CET [3450]: [23-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS read certificate verify"
2024-01-19 13:25:39 CET [3450]: [24-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS read finished"
2024-01-19 13:25:39 CET [3450]: [25-1] user=[unknown],db=[unknown] DEBUG:  SSL: handshake done: "SSLv3/TLS write session ticket"
2024-01-19 13:25:39 CET [3450]: [26-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write session ticket"
2024-01-19 13:25:39 CET [3450]: [27-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write session ticket"
2024-01-19 13:25:39 CET [3450]: [28-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept exit (1): "SSL negotiation finished successfully"
2024-01-19 13:25:39 CET [3450]: [29-1] user=[unknown],db=[unknown] DEBUG:  SSL connection from DN:"CN=cs.localdomain,OU=Security,O=Atlantica Digital S.p.A.,L=Rome,ST=Rome,C=IT" CN:"cs.localdomain"
2024-01-19 13:25:39 CET [3450]: [30-1] user=repmgr,db=repmgr DEBUG:  InitPostgres
2024-01-19 13:25:39 CET [3450]: [31-1] user=repmgr,db=repmgr DEBUG:  my backend ID is 4
2024-01-19 13:25:39 CET [3450]: [32-1] user=repmgr,db=repmgr DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3450]: [33-1] user=repmgr,db=repmgr DEBUG:  processing received SASL response of length 76
2024-01-19 13:25:39 CET [3450]: [34-1] user=repmgr,db=repmgr DEBUG:  sending SASL challenge of length 84
2024-01-19 13:25:39 CET [3450]: [35-1] user=repmgr,db=repmgr DEBUG:  processing received SASL response of length 176
2024-01-19 13:25:39 CET [3450]: [36-1] user=repmgr,db=repmgr DEBUG:  sending SASL challenge of length 46
2024-01-19 13:25:39 CET [3450]: [37-1] user=repmgr,db=repmgr LOG:  connection authenticated: identity="repmgr" method=md5 (/var/lib/pgsql/14/data/pg_hba.conf:99)
2024-01-19 13:25:39 CET [3450]: [38-1] user=repmgr,db=repmgr LOG:  connection authorized: user=repmgr database=repmgr application_name=repmgr SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-01-19 13:25:39 CET [3450]: [39-1] user=repmgr,db=repmgr DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3450]: [40-1] user=repmgr,db=repmgr DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3450]: [41-1] user=repmgr,db=repmgr STATEMENT:  SET synchronous_commit TO 'local'
2024-01-19 13:25:39 CET [3450]: [42-1] user=repmgr,db=repmgr LOG:  statement: SET synchronous_commit TO 'local'
2024-01-19 13:25:39 CET [3450]: [43-1] user=repmgr,db=repmgr DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3450]: [44-1] user=repmgr,db=repmgr STATEMENT:  SET synchronous_commit TO 'local'
2024-01-19 13:25:39 CET [3450]: [45-1] user=repmgr,db=repmgr LOG:  duration: 0.323 ms
2024-01-19 13:25:39 CET [3450]: [46-1] user=repmgr,db=repmgr DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3450]: [47-1] user=repmgr,db=repmgr STATEMENT:      SELECT n.node_id, n.type, n.upstream_node_id, n.node_name, n.conninfo, n.repluser, n.slot_name, n.location, n.priority, n.active, n.config_file, un.node_name AS upstream_node_name, NULL AS attached       FROM repmgr.nodes n  LEFT JOIN repmgr.nodes un         ON un.node_id = n.upstream_node_id  ORDER BY n.node_id 
2024-01-19 13:25:39 CET [3450]: [48-1] user=repmgr,db=repmgr LOG:  statement:     SELECT n.node_id, n.type, n.upstream_node_id, n.node_name, n.conninfo, n.repluser, n.slot_name, n.location, n.priority, n.active, n.config_file, un.node_name AS upstream_node_name, NULL AS attached       FROM repmgr.nodes n  LEFT JOIN repmgr.nodes un         ON un.node_id = n.upstream_node_id  ORDER BY n.node_id 
2024-01-19 13:25:39 CET [3450]: [49-1] user=repmgr,db=repmgr DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3450]: [50-1] user=repmgr,db=repmgr STATEMENT:      SELECT n.node_id, n.type, n.upstream_node_id, n.node_name, n.conninfo, n.repluser, n.slot_name, n.location, n.priority, n.active, n.config_file, un.node_name AS upstream_node_name, NULL AS attached       FROM repmgr.nodes n  LEFT JOIN repmgr.nodes un         ON un.node_id = n.upstream_node_id  ORDER BY n.node_id 
2024-01-19 13:25:39 CET [3450]: [51-1] user=repmgr,db=repmgr LOG:  duration: 2.592 ms
2024-01-19 13:25:39 CET [3263]: [20-1] user=,db= DEBUG:  forked new backend, pid=3454 socket=10
2024-01-19 13:25:39 CET [3454]: [1-1] user=[unknown],db=[unknown] LOG:  connection received: host=192.168.1.53 port=49256
2024-01-19 13:25:39 CET [3454]: [2-1] user=[unknown],db=[unknown] DEBUG:  SSL: handshake start: "before SSL initialization"
2024-01-19 13:25:39 CET [3454]: [3-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "before SSL initialization"
2024-01-19 13:25:39 CET [3454]: [4-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept exit (-1): "before SSL initialization"
2024-01-19 13:25:39 CET [3454]: [5-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "before SSL initialization"
2024-01-19 13:25:39 CET [3454]: [6-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS read client hello"
2024-01-19 13:25:39 CET [3454]: [7-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write server hello"
2024-01-19 13:25:39 CET [3454]: [8-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write change cipher spec"
2024-01-19 13:25:39 CET [3454]: [9-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "TLSv1.3 early data"
2024-01-19 13:25:39 CET [3454]: [10-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept exit (-1): "TLSv1.3 early data"
2024-01-19 13:25:39 CET [3454]: [11-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "TLSv1.3 early data"
2024-01-19 13:25:39 CET [3454]: [12-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS read client hello"
2024-01-19 13:25:39 CET [3454]: [13-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write server hello"
2024-01-19 13:25:39 CET [3454]: [14-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "TLSv1.3 write encrypted extensions"
2024-01-19 13:25:39 CET [3454]: [15-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write certificate request"
2024-01-19 13:25:39 CET [3454]: [16-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write certificate"
2024-01-19 13:25:39 CET [3454]: [17-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "TLSv1.3 write server certificate verify"
2024-01-19 13:25:39 CET [3454]: [18-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write finished"
2024-01-19 13:25:39 CET [3454]: [19-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "TLSv1.3 early data"
2024-01-19 13:25:39 CET [3454]: [20-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept exit (-1): "TLSv1.3 early data"
2024-01-19 13:25:39 CET [3454]: [21-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "TLSv1.3 early data"
2024-01-19 13:25:39 CET [3454]: [22-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS read client certificate"
2024-01-19 13:25:39 CET [3454]: [23-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS read certificate verify"
2024-01-19 13:25:39 CET [3454]: [24-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS read finished"
2024-01-19 13:25:39 CET [3454]: [25-1] user=[unknown],db=[unknown] DEBUG:  SSL: handshake done: "SSLv3/TLS write session ticket"
2024-01-19 13:25:39 CET [3454]: [26-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write session ticket"
2024-01-19 13:25:39 CET [3454]: [27-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write session ticket"
2024-01-19 13:25:39 CET [3454]: [28-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept exit (1): "SSL negotiation finished successfully"
2024-01-19 13:25:39 CET [3454]: [29-1] user=[unknown],db=[unknown] DEBUG:  SSL connection from DN:"CN=cs.localdomain,OU=Security,O=Atlantica Digital S.p.A.,L=Rome,ST=Rome,C=IT" CN:"cs.localdomain"
2024-01-19 13:25:39 CET [3454]: [30-1] user=repmgr,db=repmgr DEBUG:  InitPostgres
2024-01-19 13:25:39 CET [3454]: [31-1] user=repmgr,db=repmgr DEBUG:  my backend ID is 5
2024-01-19 13:25:39 CET [3454]: [32-1] user=repmgr,db=repmgr DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3454]: [33-1] user=repmgr,db=repmgr DEBUG:  processing received SASL response of length 76
2024-01-19 13:25:39 CET [3454]: [34-1] user=repmgr,db=repmgr DEBUG:  sending SASL challenge of length 84
2024-01-19 13:25:39 CET [3454]: [35-1] user=repmgr,db=repmgr DEBUG:  processing received SASL response of length 176
2024-01-19 13:25:39 CET [3454]: [36-1] user=repmgr,db=repmgr DEBUG:  sending SASL challenge of length 46
2024-01-19 13:25:39 CET [3454]: [37-1] user=repmgr,db=repmgr LOG:  connection authenticated: identity="repmgr" method=md5 (/var/lib/pgsql/14/data/pg_hba.conf:99)
2024-01-19 13:25:39 CET [3454]: [38-1] user=repmgr,db=repmgr LOG:  connection authorized: user=repmgr database=repmgr application_name=repmgr SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-01-19 13:25:39 CET [3454]: [39-1] user=repmgr,db=repmgr DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3454]: [40-1] user=repmgr,db=repmgr DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3454]: [41-1] user=repmgr,db=repmgr STATEMENT:  SET synchronous_commit TO 'local'
2024-01-19 13:25:39 CET [3454]: [42-1] user=repmgr,db=repmgr LOG:  statement: SET synchronous_commit TO 'local'
2024-01-19 13:25:39 CET [3454]: [43-1] user=repmgr,db=repmgr DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3454]: [44-1] user=repmgr,db=repmgr STATEMENT:  SET synchronous_commit TO 'local'
2024-01-19 13:25:39 CET [3454]: [45-1] user=repmgr,db=repmgr LOG:  duration: 0.323 ms
2024-01-19 13:25:39 CET [3454]: [46-1] user=repmgr,db=repmgr DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3454]: [47-1] user=repmgr,db=repmgr STATEMENT:  SELECT pg_catalog.pg_is_in_recovery()
2024-01-19 13:25:39 CET [3454]: [48-1] user=repmgr,db=repmgr LOG:  statement: SELECT pg_catalog.pg_is_in_recovery()
2024-01-19 13:25:39 CET [3454]: [49-1] user=repmgr,db=repmgr DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3454]: [50-1] user=repmgr,db=repmgr STATEMENT:  SELECT pg_catalog.pg_is_in_recovery()
2024-01-19 13:25:39 CET [3454]: [51-1] user=repmgr,db=repmgr LOG:  duration: 0.625 ms
2024-01-19 13:25:39 CET [3454]: [52-1] user=repmgr,db=repmgr DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3454]: [53-1] user=repmgr,db=repmgr STATEMENT:  SELECT repmgr.get_repmgrd_pid()
2024-01-19 13:25:39 CET [3454]: [54-1] user=repmgr,db=repmgr LOG:  statement: SELECT repmgr.get_repmgrd_pid()
2024-01-19 13:25:39 CET [3454]: [55-1] user=repmgr,db=repmgr DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3454]: [56-1] user=repmgr,db=repmgr STATEMENT:  SELECT repmgr.get_repmgrd_pid()
2024-01-19 13:25:39 CET [3454]: [57-1] user=repmgr,db=repmgr LOG:  duration: 0.447 ms
2024-01-19 13:25:39 CET [3454]: [58-1] user=repmgr,db=repmgr DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3454]: [59-1] user=repmgr,db=repmgr STATEMENT:  SELECT repmgr.repmgrd_is_running()
2024-01-19 13:25:39 CET [3454]: [60-1] user=repmgr,db=repmgr LOG:  statement: SELECT repmgr.repmgrd_is_running()
2024-01-19 13:25:39 CET [3454]: [61-1] user=repmgr,db=repmgr DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3454]: [62-1] user=repmgr,db=repmgr STATEMENT:  SELECT repmgr.repmgrd_is_running()
2024-01-19 13:25:39 CET [3454]: [63-1] user=repmgr,db=repmgr LOG:  duration: 0.338 ms
2024-01-19 13:25:39 CET [3454]: [64-1] user=repmgr,db=repmgr DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3454]: [65-1] user=repmgr,db=repmgr STATEMENT:  SELECT repmgr.repmgrd_is_paused()
2024-01-19 13:25:39 CET [3454]: [66-1] user=repmgr,db=repmgr LOG:  statement: SELECT repmgr.repmgrd_is_paused()
2024-01-19 13:25:39 CET [3454]: [67-1] user=repmgr,db=repmgr DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3454]: [68-1] user=repmgr,db=repmgr STATEMENT:  SELECT repmgr.repmgrd_is_paused()
2024-01-19 13:25:39 CET [3454]: [69-1] user=repmgr,db=repmgr LOG:  duration: 0.460 ms
2024-01-19 13:25:39 CET [3454]: [70-1] user=repmgr,db=repmgr DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3454]: [71-1] user=repmgr,db=repmgr STATEMENT:  SELECT pg_catalog.pg_is_in_recovery()
2024-01-19 13:25:39 CET [3454]: [72-1] user=repmgr,db=repmgr LOG:  statement: SELECT pg_catalog.pg_is_in_recovery()
2024-01-19 13:25:39 CET [3454]: [73-1] user=repmgr,db=repmgr DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3454]: [74-1] user=repmgr,db=repmgr STATEMENT:  SELECT pg_catalog.pg_is_in_recovery()
2024-01-19 13:25:39 CET [3454]: [75-1] user=repmgr,db=repmgr LOG:  duration: 0.311 ms
2024-01-19 13:25:39 CET [3454]: [76-1] user=repmgr,db=repmgr DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3454]: [77-1] user=repmgr,db=repmgr STATEMENT:  SELECT CASE WHEN pg_catalog.pg_is_in_recovery() IS FALSE    THEN -1    ELSE repmgr.get_upstream_last_seen()  END AS upstream_last_seen 
2024-01-19 13:25:39 CET [3454]: [78-1] user=repmgr,db=repmgr LOG:  statement: SELECT CASE WHEN pg_catalog.pg_is_in_recovery() IS FALSE    THEN -1    ELSE repmgr.get_upstream_last_seen()  END AS upstream_last_seen 
2024-01-19 13:25:39 CET [3454]: [79-1] user=repmgr,db=repmgr DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3454]: [80-1] user=repmgr,db=repmgr STATEMENT:  SELECT CASE WHEN pg_catalog.pg_is_in_recovery() IS FALSE    THEN -1    ELSE repmgr.get_upstream_last_seen()  END AS upstream_last_seen 
2024-01-19 13:25:39 CET [3454]: [81-1] user=repmgr,db=repmgr LOG:  duration: 0.452 ms
2024-01-19 13:25:39 CET [3454]: [82-1] user=repmgr,db=repmgr DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3454]: [83-1] user=repmgr,db=repmgr STATEMENT:  SELECT pg_catalog.pg_is_in_recovery()
2024-01-19 13:25:39 CET [3454]: [84-1] user=repmgr,db=repmgr LOG:  statement: SELECT pg_catalog.pg_is_in_recovery()
2024-01-19 13:25:39 CET [3454]: [85-1] user=repmgr,db=repmgr DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3454]: [86-1] user=repmgr,db=repmgr STATEMENT:  SELECT pg_catalog.pg_is_in_recovery()
2024-01-19 13:25:39 CET [3454]: [87-1] user=repmgr,db=repmgr LOG:  duration: 0.323 ms
2024-01-19 13:25:39 CET [3454]: [88-1] user=repmgr,db=repmgr DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3454]: [89-1] user=repmgr,db=repmgr STATEMENT:      SELECT n.node_id, n.type, n.upstream_node_id, n.node_name, n.conninfo, n.repluser, n.slot_name, n.location, n.priority, n.active, n.config_file, un.node_name AS upstream_node_name, NULL AS attached       FROM repmgr.nodes n  LEFT JOIN repmgr.nodes un         ON un.node_id = n.upstream_node_id WHERE n.node_id = 1
2024-01-19 13:25:39 CET [3454]: [90-1] user=repmgr,db=repmgr LOG:  statement:     SELECT n.node_id, n.type, n.upstream_node_id, n.node_name, n.conninfo, n.repluser, n.slot_name, n.location, n.priority, n.active, n.config_file, un.node_name AS upstream_node_name, NULL AS attached       FROM repmgr.nodes n  LEFT JOIN repmgr.nodes un         ON un.node_id = n.upstream_node_id WHERE n.node_id = 1
2024-01-19 13:25:39 CET [3454]: [91-1] user=repmgr,db=repmgr DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:39 CET [3454]: [92-1] user=repmgr,db=repmgr STATEMENT:      SELECT n.node_id, n.type, n.upstream_node_id, n.node_name, n.conninfo, n.repluser, n.slot_name, n.location, n.priority, n.active, n.config_file, un.node_name AS upstream_node_name, NULL AS attached       FROM repmgr.nodes n  LEFT JOIN repmgr.nodes un         ON un.node_id = n.upstream_node_id WHERE n.node_id = 1
2024-01-19 13:25:39 CET [3454]: [93-1] user=repmgr,db=repmgr LOG:  duration: 2.251 ms
2024-01-19 13:25:39 CET [3454]: [94-1] user=repmgr,db=repmgr DEBUG:  shmem_exit(0): 1 before_shmem_exit callbacks to make
2024-01-19 13:25:39 CET [3454]: [95-1] user=repmgr,db=repmgr DEBUG:  shmem_exit(0): 7 on_shmem_exit callbacks to make
2024-01-19 13:25:39 CET [3454]: [96-1] user=repmgr,db=repmgr DEBUG:  proc_exit(0): 4 callbacks to make
2024-01-19 13:25:39 CET [3454]: [97-1] user=repmgr,db=repmgr LOG:  disconnection: session time: 0:00:00.054 user=repmgr database=repmgr host=192.168.1.53 port=49256
2024-01-19 13:25:39 CET [3454]: [98-1] user=repmgr,db=repmgr DEBUG:  SSL: write alert (0x0100): "SSL negotiation finished successfully"
2024-01-19 13:25:39 CET [3454]: [99-1] user=repmgr,db=repmgr DEBUG:  exit(0)
2024-01-19 13:25:39 CET [3454]: [100-1] user=repmgr,db=repmgr DEBUG:  shmem_exit(-1): 0 before_shmem_exit callbacks to make
2024-01-19 13:25:39 CET [3454]: [101-1] user=repmgr,db=repmgr DEBUG:  shmem_exit(-1): 0 on_shmem_exit callbacks to make
2024-01-19 13:25:39 CET [3454]: [102-1] user=repmgr,db=repmgr DEBUG:  proc_exit(-1): 0 callbacks to make
2024-01-19 13:25:39 CET [3263]: [21-1] user=,db= DEBUG:  reaping dead processes
2024-01-19 13:25:39 CET [3263]: [22-1] user=,db= DEBUG:  server process (PID 3454) exited with exit code 0
2024-01-19 13:25:40 CET [3263]: [23-1] user=,db= DEBUG:  forked new backend, pid=3456 socket=10
2024-01-19 13:25:40 CET [3456]: [1-1] user=[unknown],db=[unknown] LOG:  connection received: host=192.168.1.53 port=49260
2024-01-19 13:25:40 CET [3456]: [2-1] user=[unknown],db=[unknown] DEBUG:  SSL: handshake start: "before SSL initialization"
2024-01-19 13:25:40 CET [3456]: [3-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "before SSL initialization"
2024-01-19 13:25:40 CET [3456]: [4-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept exit (-1): "before SSL initialization"
2024-01-19 13:25:40 CET [3456]: [5-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "before SSL initialization"
2024-01-19 13:25:40 CET [3456]: [6-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS read client hello"
2024-01-19 13:25:40 CET [3456]: [7-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write server hello"
2024-01-19 13:25:40 CET [3456]: [8-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write change cipher spec"
2024-01-19 13:25:40 CET [3456]: [9-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "TLSv1.3 early data"
2024-01-19 13:25:40 CET [3456]: [10-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept exit (-1): "TLSv1.3 early data"
2024-01-19 13:25:40 CET [3456]: [11-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "TLSv1.3 early data"
2024-01-19 13:25:40 CET [3456]: [12-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS read client hello"
2024-01-19 13:25:40 CET [3456]: [13-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write server hello"
2024-01-19 13:25:40 CET [3456]: [14-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "TLSv1.3 write encrypted extensions"
2024-01-19 13:25:40 CET [3456]: [15-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write certificate request"
2024-01-19 13:25:40 CET [3456]: [16-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write certificate"
2024-01-19 13:25:40 CET [3456]: [17-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "TLSv1.3 write server certificate verify"
2024-01-19 13:25:40 CET [3456]: [18-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write finished"
2024-01-19 13:25:40 CET [3456]: [19-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "TLSv1.3 early data"
2024-01-19 13:25:40 CET [3456]: [20-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept exit (-1): "TLSv1.3 early data"
2024-01-19 13:25:40 CET [3456]: [21-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "TLSv1.3 early data"
2024-01-19 13:25:40 CET [3456]: [22-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS read client certificate"
2024-01-19 13:25:40 CET [3456]: [23-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS read certificate verify"
2024-01-19 13:25:40 CET [3456]: [24-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS read finished"
2024-01-19 13:25:40 CET [3456]: [25-1] user=[unknown],db=[unknown] DEBUG:  SSL: handshake done: "SSLv3/TLS write session ticket"
2024-01-19 13:25:40 CET [3456]: [26-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write session ticket"
2024-01-19 13:25:40 CET [3456]: [27-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept loop: "SSLv3/TLS write session ticket"
2024-01-19 13:25:40 CET [3456]: [28-1] user=[unknown],db=[unknown] DEBUG:  SSL: accept exit (1): "SSL negotiation finished successfully"
2024-01-19 13:25:40 CET [3456]: [29-1] user=[unknown],db=[unknown] DEBUG:  SSL connection from DN:"CN=cs.localdomain,OU=Security,O=Atlantica Digital S.p.A.,L=Rome,ST=Rome,C=IT" CN:"cs.localdomain"
2024-01-19 13:25:40 CET [3456]: [30-1] user=repmgr,db=repmgr DEBUG:  InitPostgres
2024-01-19 13:25:40 CET [3456]: [31-1] user=repmgr,db=repmgr DEBUG:  my backend ID is 5
2024-01-19 13:25:40 CET [3456]: [32-1] user=repmgr,db=repmgr DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:40 CET [3456]: [33-1] user=repmgr,db=repmgr DEBUG:  processing received SASL response of length 76
2024-01-19 13:25:40 CET [3456]: [34-1] user=repmgr,db=repmgr DEBUG:  sending SASL challenge of length 84
2024-01-19 13:25:40 CET [3456]: [35-1] user=repmgr,db=repmgr DEBUG:  processing received SASL response of length 176
2024-01-19 13:25:40 CET [3456]: [36-1] user=repmgr,db=repmgr DEBUG:  sending SASL challenge of length 46
2024-01-19 13:25:40 CET [3456]: [37-1] user=repmgr,db=repmgr LOG:  connection authenticated: identity="repmgr" method=md5 (/var/lib/pgsql/14/data/pg_hba.conf:99)
2024-01-19 13:25:40 CET [3456]: [38-1] user=repmgr,db=repmgr LOG:  connection authorized: user=repmgr database=repmgr application_name=repmgr SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-01-19 13:25:40 CET [3456]: [39-1] user=repmgr,db=repmgr DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:40 CET [3456]: [40-1] user=repmgr,db=repmgr DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:40 CET [3456]: [41-1] user=repmgr,db=repmgr STATEMENT:  SET synchronous_commit TO 'local'
2024-01-19 13:25:40 CET [3456]: [42-1] user=repmgr,db=repmgr LOG:  statement: SET synchronous_commit TO 'local'
2024-01-19 13:25:40 CET [3456]: [43-1] user=repmgr,db=repmgr DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:40 CET [3456]: [44-1] user=repmgr,db=repmgr STATEMENT:  SET synchronous_commit TO 'local'
2024-01-19 13:25:40 CET [3456]: [45-1] user=repmgr,db=repmgr LOG:  duration: 0.405 ms
2024-01-19 13:25:40 CET [3456]: [46-1] user=repmgr,db=repmgr DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:40 CET [3456]: [47-1] user=repmgr,db=repmgr STATEMENT:   SELECT pid, state    FROM pg_catalog.pg_stat_replication   WHERE application_name = 'cs2.localdomain'
2024-01-19 13:25:40 CET [3456]: [48-1] user=repmgr,db=repmgr LOG:  statement:  SELECT pid, state    FROM pg_catalog.pg_stat_replication   WHERE application_name = 'cs2.localdomain'
2024-01-19 13:25:40 CET [3456]: [49-1] user=repmgr,db=repmgr DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:40 CET [3456]: [50-1] user=repmgr,db=repmgr STATEMENT:   SELECT pid, state    FROM pg_catalog.pg_stat_replication   WHERE application_name = 'cs2.localdomain'
2024-01-19 13:25:40 CET [3456]: [51-1] user=repmgr,db=repmgr LOG:  duration: 4.728 ms
2024-01-19 13:25:40 CET [3456]: [52-1] user=repmgr,db=repmgr DEBUG:  StartTransaction(1) name: unnamed; blockState: DEFAULT; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:40 CET [3456]: [53-1] user=repmgr,db=repmgr STATEMENT:    SELECT CASE            WHEN pg_catalog.pg_has_role('pg_monitor','MEMBER')              THEN TRUE            WHEN pg_catalog.pg_has_role('pg_read_all_stats','MEMBER')              THEN TRUE            ELSE FALSE          END AS has_pg_monitor
2024-01-19 13:25:40 CET [3456]: [54-1] user=repmgr,db=repmgr LOG:  statement:   SELECT CASE            WHEN pg_catalog.pg_has_role('pg_monitor','MEMBER')              THEN TRUE            WHEN pg_catalog.pg_has_role('pg_read_all_stats','MEMBER')              THEN TRUE            ELSE FALSE          END AS has_pg_monitor
2024-01-19 13:25:40 CET [3456]: [55-1] user=repmgr,db=repmgr DEBUG:  CommitTransaction(1) name: unnamed; blockState: STARTED; state: INPROGRESS, xid/subid/cid: 0/1/0
2024-01-19 13:25:40 CET [3456]: [56-1] user=repmgr,db=repmgr STATEMENT:    SELECT CASE            WHEN pg_catalog.pg_has_role('pg_monitor','MEMBER')              THEN TRUE            WHEN pg_catalog.pg_has_role('pg_read_all_stats','MEMBER')              THEN TRUE            ELSE FALSE          END AS has_pg_monitor
2024-01-19 13:25:40 CET [3456]: [57-1] user=repmgr,db=repmgr LOG:  duration: 0.893 ms
2024-01-19 13:25:40 CET [3456]: [58-1] user=repmgr,db=repmgr DEBUG:  shmem_exit(0): 1 before_shmem_exit callbacks to make
2024-01-19 13:25:40 CET [3456]: [59-1] user=repmgr,db=repmgr DEBUG:  shmem_exit(0): 7 on_shmem_exit callbacks to make
2024-01-19 13:25:40 CET [3456]: [60-1] user=repmgr,db=repmgr DEBUG:  proc_exit(0): 4 callbacks to make
2024-01-19 13:25:40 CET [3456]: [61-1] user=repmgr,db=repmgr LOG:  disconnection: session time: 0:00:00.037 user=repmgr database=repmgr host=192.168.1.53 port=49260
2024-01-19 13:25:40 CET [3456]: [62-1] user=repmgr,db=repmgr DEBUG:  SSL: write alert (0x0100): "SSL negotiation finished successfully"
2024-01-19 13:25:40 CET [3456]: [63-1] user=repmgr,db=repmgr DEBUG:  exit(0)
2024-01-19 13:25:40 CET [3456]: [64-1] user=repmgr,db=repmgr DEBUG:  shmem_exit(-1): 0 before_shmem_exit callbacks to make
2024-01-19 13:25:40 CET [3456]: [65-1] user=repmgr,db=repmgr DEBUG:  shmem_exit(-1): 0 on_shmem_exit callbacks to make
2024-01-19 13:25:40 CET [3456]: [66-1] user=repmgr,db=repmgr DEBUG:  proc_exit(-1): 0 callbacks to make
2024-01-19 13:25:40 CET [3450]: [52-1] user=repmgr,db=repmgr LOG:  could not receive data from client: Connection reset by peer
2024-01-19 13:25:40 CET [3450]: [53-1] user=repmgr,db=repmgr DEBUG:  unexpected EOF on client connection
2024-01-19 13:25:40 CET [3450]: [54-1] user=repmgr,db=repmgr DEBUG:  shmem_exit(0): 1 before_shmem_exit callbacks to make
2024-01-19 13:25:40 CET [3450]: [55-1] user=repmgr,db=repmgr DEBUG:  shmem_exit(0): 7 on_shmem_exit callbacks to make
2024-01-19 13:25:40 CET [3450]: [56-1] user=repmgr,db=repmgr DEBUG:  proc_exit(0): 4 callbacks to make
2024-01-19 13:25:40 CET [3450]: [57-1] user=repmgr,db=repmgr LOG:  disconnection: session time: 0:00:00.347 user=repmgr database=repmgr host=192.168.1.53 port=49252
2024-01-19 13:25:40 CET [3450]: [58-1] user=repmgr,db=repmgr DEBUG:  SSL: write alert (0x0100): "SSL negotiation finished successfully"
2024-01-19 13:25:40 CET [3450]: [59-1] user=repmgr,db=repmgr DEBUG:  exit(0)
2024-01-19 13:25:40 CET [3450]: [60-1] user=repmgr,db=repmgr DEBUG:  shmem_exit(-1): 0 before_shmem_exit callbacks to make
2024-01-19 13:25:40 CET [3450]: [61-1] user=repmgr,db=repmgr DEBUG:  shmem_exit(-1): 0 on_shmem_exit callbacks to make
2024-01-19 13:25:40 CET [3450]: [62-1] user=repmgr,db=repmgr DEBUG:  proc_exit(-1): 0 callbacks to make
2024-01-19 13:25:40 CET [3263]: [24-1] user=,db= DEBUG:  reaping dead processes
2024-01-19 13:25:40 CET [3263]: [25-1] user=,db= DEBUG:  server process (PID 3456) exited with exit code 0
2024-01-19 13:25:40 CET [3263]: [26-1] user=,db= DEBUG:  reaping dead processes
2024-01-19 13:25:40 CET [3263]: [27-1] user=,db= DEBUG:  server process (PID 3450) exited with exit code 0
2024-01-19 13:25:41 CET [3420]: [3-1] user=repmgr,db=[unknown] DEBUG:  write 0/A000AD0 flush 0/A000AD0 apply 0/A000AD0 (reply requested) reply_time 2024-01-19 13:25:41.870076+01
2024-01-19 13:25:41 CET [3420]: [4-1] user=repmgr,db=[unknown] STATEMENT:  START_REPLICATION SLOT "repmgr_slot_2" 0/A000000 TIMELINE 1
2024-01-19 13:25:41 CET [3420]: [5-1] user=repmgr,db=[unknown] DEBUG:  sending replication keepalive
2024-01-19 13:25:41 CET [3420]: [6-1] user=repmgr,db=[unknown] STATEMENT:  START_REPLICATION SLOT "repmgr_slot_2" 0/A000000 TIMELINE 1

@marcofortina
Copy link
Author

OS info:

[root@cs1 ~]# cat /etc/redhat-release 
CentOS Stream release 8

[root@cs1 ~]# cat /etc/os-release 
NAME="CentOS Stream"
VERSION="8"
ID="centos"
ID_LIKE="rhel fedora"
VERSION_ID="8"
PLATFORM_ID="platform:el8"
PRETTY_NAME="CentOS Stream 8"
ANSI_COLOR="0;31"
CPE_NAME="cpe:/o:centos:centos:8"
HOME_URL="https://centos.org/"
BUG_REPORT_URL="https://bugzilla.redhat.com/"
REDHAT_SUPPORT_PRODUCT="Red Hat Enterprise Linux 8"
REDHAT_SUPPORT_PRODUCT_VERSION="CentOS Stream"

[root@cs1 ~]# dnf list installed postgres*
Installed Packages
postgresql14.x86_64                                                14.10-2PGDG.rhel8                                         @pgdg14
postgresql14-libs.x86_64                                           14.10-2PGDG.rhel8                                         @pgdg14
postgresql14-server.x86_64                                         14.10-2PGDG.rhel8                                         @pgdg14

@gonzalemario
Copy link
Contributor

Were you able to fix the issue? The "reset by peer" seems like a local network problem, in rare cases a duplicated IP can cause something like this

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@gonzalemario @marcofortina