[04:46:37.730](0.076s) # setting up data directory
# Checking port 63513
# Found port 63513
Name: primary
Data directory: /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata
Backup directory: /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/backup
Archive directory: /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/archives
Connection string: port=63513 host=/tmp/dRGb9XZK2m
Log file: /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log
[04:46:37.748](0.018s) # initializing database system by copying initdb template
# Running: cp -RPp /tmp/cirrus-ci-build/tmp_install/initdb-template /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata
# Running: /tmp/cirrus-ci-build/src/test/ssl/../../../src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata
### Starting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log -o --cluster-name=primary start
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 32292
[04:46:38.142](0.394s) ok 1 - ssl_library parameter
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 32580
[04:46:40.242](2.100s) # testing password-protected keys
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... stopped waiting
pg_ctl: could not start server
Examine the log output.
# pg_ctl restart failed; logfile:
2024-03-08 04:46:38.017 UTC [32292][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:38.017 UTC [32292][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:38.022 UTC [32301][startup] LOG:  database system was shut down at 2024-03-08 04:44:12 UTC
2024-03-08 04:46:38.030 UTC [32292][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:38.111 UTC [32327][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.112 UTC [32327][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.112 UTC [32327][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.139 UTC [32327][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-08 04:46:38.143 UTC [32327][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.034 user=postgres database=postgres host=[local]
2024-03-08 04:46:38.179 UTC [32344][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.180 UTC [32344][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.180 UTC [32344][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.184 UTC [32344][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-08 04:46:38.189 UTC [32344][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-03-08 04:46:38.232 UTC [32352][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.233 UTC [32352][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.233 UTC [32352][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.240 UTC [32352][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-08 04:46:38.244 UTC [32352][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=postgres database=postgres host=[local]
2024-03-08 04:46:38.274 UTC [32356][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.275 UTC [32356][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.275 UTC [32356][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.280 UTC [32356][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-08 04:46:38.280 UTC [32356][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local]
2024-03-08 04:46:38.313 UTC [32377][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.314 UTC [32377][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.314 UTC [32377][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.320 UTC [32377][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-08 04:46:38.322 UTC [32377][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=postgres database=postgres host=[local]
2024-03-08 04:46:38.391 UTC [32387][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.393 UTC [32387][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.393 UTC [32387][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.405 UTC [32387][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-08 04:46:38.523 UTC [32387][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.133 user=postgres database=postgres host=[local]
2024-03-08 04:46:38.694 UTC [32422][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.695 UTC [32422][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.695 UTC [32422][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.705 UTC [32422][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-08 04:46:38.853 UTC [32422][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.161 user=postgres database=postgres host=[local]
2024-03-08 04:46:38.959 UTC [32461][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.959 UTC [32461][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.959 UTC [32461][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.961 UTC [32461][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-08 04:46:39.007 UTC [32461][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.048 user=postgres database=postgres host=[local]
2024-03-08 04:46:39.095 UTC [32472][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:39.096 UTC [32472][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:39.096 UTC [32472][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:39.098 UTC [32472][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-08 04:46:39.161 UTC [32472][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.066 user=postgres database=postgres host=[local]
2024-03-08 04:46:39.248 UTC [32493][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:39.256 UTC [32493][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:39.256 UTC [32493][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:39.260 UTC [32493][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-08 04:46:39.383 UTC [32493][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.135 user=postgres database=postgres host=[local]
2024-03-08 04:46:39.547 UTC [32518][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:39.548 UTC [32518][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:39.548 UTC [32518][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:39.558 UTC [32518][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-08 04:46:39.728 UTC [32518][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.182 user=postgres database=postgres host=[local]
2024-03-08 04:46:39.812 UTC [32292][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:39.812 UTC [32292][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:39.819 UTC [32292][postmaster] LOG:  background worker "logical replication launcher" (PID 32306) exited with exit code 1
2024-03-08 04:46:39.819 UTC [32299][checkpointer] LOG:  shutting down
2024-03-08 04:46:39.820 UTC [32299][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:39.956 UTC [32299][checkpointer] LOG:  checkpoint complete: wrote 5650 buffers (34.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.133 s, sync=0.004 s, total=0.137 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45479 kB, estimate=45479 kB; lsn=0/4196260, redo lsn=0/4196260
2024-03-08 04:46:39.981 UTC [32292][postmaster] LOG:  database system is shut down
2024-03-08 04:46:40.160 UTC [32580][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:40.161 UTC [32580][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:40.161 UTC [32580][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:40.193 UTC [32599][startup] LOG:  database system was shut down at 2024-03-08 04:46:39 UTC
2024-03-08 04:46:40.201 UTC [32580][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:40.262 UTC [32580][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:40.262 UTC [32580][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:40.266 UTC [32580][postmaster] LOG:  background worker "logical replication launcher" (PID 32602) exited with exit code 1
2024-03-08 04:46:40.268 UTC [32595][checkpointer] LOG:  shutting down
2024-03-08 04:46:40.268 UTC [32595][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:40.268 UTC [32595][checkpointer] LOG:  checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41962D8, redo lsn=0/41962D8
2024-03-08 04:46:40.273 UTC [32580][postmaster] LOG:  database system is shut down
2024-03-08 04:46:40.465 UTC [32615][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-08 04:46:40.465 UTC [32615][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[04:46:40.569](0.327s) ok 2 - restart fails with password-protected key file with wrong password
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
pg_ctl: PID file "/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/postmaster.pid" does not exist
Is server running?
trying to start server anyway
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 32633
[04:46:40.789](0.220s) ok 3 - restart succeeds with password-protected key file
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... stopped waiting
pg_ctl: could not start server
Examine the log output.
# pg_ctl restart failed; logfile:
2024-03-08 04:46:38.017 UTC [32292][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:38.017 UTC [32292][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:38.022 UTC [32301][startup] LOG:  database system was shut down at 2024-03-08 04:44:12 UTC
2024-03-08 04:46:38.030 UTC [32292][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:38.111 UTC [32327][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.112 UTC [32327][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.112 UTC [32327][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.139 UTC [32327][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-08 04:46:38.143 UTC [32327][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.034 user=postgres database=postgres host=[local]
2024-03-08 04:46:38.179 UTC [32344][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.180 UTC [32344][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.180 UTC [32344][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.184 UTC [32344][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-08 04:46:38.189 UTC [32344][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-03-08 04:46:38.232 UTC [32352][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.233 UTC [32352][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.233 UTC [32352][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.240 UTC [32352][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-08 04:46:38.244 UTC [32352][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=postgres database=postgres host=[local]
2024-03-08 04:46:38.274 UTC [32356][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.275 UTC [32356][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.275 UTC [32356][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.280 UTC [32356][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-08 04:46:38.280 UTC [32356][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local]
2024-03-08 04:46:38.313 UTC [32377][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.314 UTC [32377][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.314 UTC [32377][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.320 UTC [32377][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-08 04:46:38.322 UTC [32377][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=postgres database=postgres host=[local]
2024-03-08 04:46:38.391 UTC [32387][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.393 UTC [32387][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.393 UTC [32387][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.405 UTC [32387][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-08 04:46:38.523 UTC [32387][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.133 user=postgres database=postgres host=[local]
2024-03-08 04:46:38.694 UTC [32422][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.695 UTC [32422][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.695 UTC [32422][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.705 UTC [32422][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-08 04:46:38.853 UTC [32422][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.161 user=postgres database=postgres host=[local]
2024-03-08 04:46:38.959 UTC [32461][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.959 UTC [32461][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.959 UTC [32461][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.961 UTC [32461][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-08 04:46:39.007 UTC [32461][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.048 user=postgres database=postgres host=[local]
2024-03-08 04:46:39.095 UTC [32472][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:39.096 UTC [32472][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:39.096 UTC [32472][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:39.098 UTC [32472][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-08 04:46:39.161 UTC [32472][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.066 user=postgres database=postgres host=[local]
2024-03-08 04:46:39.248 UTC [32493][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:39.256 UTC [32493][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:39.256 UTC [32493][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:39.260 UTC [32493][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-08 04:46:39.383 UTC [32493][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.135 user=postgres database=postgres host=[local]
2024-03-08 04:46:39.547 UTC [32518][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:39.548 UTC [32518][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:39.548 UTC [32518][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:39.558 UTC [32518][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-08 04:46:39.728 UTC [32518][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.182 user=postgres database=postgres host=[local]
2024-03-08 04:46:39.812 UTC [32292][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:39.812 UTC [32292][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:39.819 UTC [32292][postmaster] LOG:  background worker "logical replication launcher" (PID 32306) exited with exit code 1
2024-03-08 04:46:39.819 UTC [32299][checkpointer] LOG:  shutting down
2024-03-08 04:46:39.820 UTC [32299][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:39.956 UTC [32299][checkpointer] LOG:  checkpoint complete: wrote 5650 buffers (34.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.133 s, sync=0.004 s, total=0.137 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45479 kB, estimate=45479 kB; lsn=0/4196260, redo lsn=0/4196260
2024-03-08 04:46:39.981 UTC [32292][postmaster] LOG:  database system is shut down
2024-03-08 04:46:40.160 UTC [32580][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:40.161 UTC [32580][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:40.161 UTC [32580][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:40.193 UTC [32599][startup] LOG:  database system was shut down at 2024-03-08 04:46:39 UTC
2024-03-08 04:46:40.201 UTC [32580][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:40.262 UTC [32580][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:40.262 UTC [32580][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:40.266 UTC [32580][postmaster] LOG:  background worker "logical replication launcher" (PID 32602) exited with exit code 1
2024-03-08 04:46:40.268 UTC [32595][checkpointer] LOG:  shutting down
2024-03-08 04:46:40.268 UTC [32595][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:40.268 UTC [32595][checkpointer] LOG:  checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41962D8, redo lsn=0/41962D8
2024-03-08 04:46:40.273 UTC [32580][postmaster] LOG:  database system is shut down
2024-03-08 04:46:40.465 UTC [32615][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-08 04:46:40.465 UTC [32615][postmaster] LOG:  database system is shut down
2024-03-08 04:46:40.652 UTC [32633][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:40.652 UTC [32633][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:40.652 UTC [32633][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:40.697 UTC [32642][startup] LOG:  database system was shut down at 2024-03-08 04:46:40 UTC
2024-03-08 04:46:40.726 UTC [32633][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:40.799 UTC [32633][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:40.799 UTC [32633][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:40.804 UTC [32633][postmaster] LOG:  background worker "logical replication launcher" (PID 32647) exited with exit code 1
2024-03-08 04:46:40.804 UTC [32639][checkpointer] LOG:  shutting down
2024-03-08 04:46:40.804 UTC [32639][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:40.805 UTC [32639][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196350, redo lsn=0/4196350
2024-03-08 04:46:40.811 UTC [32633][postmaster] LOG:  database system is shut down
2024-03-08 04:46:40.982 UTC [32671][postmaster] FATAL:  could not set SSL protocol version range
2024-03-08 04:46:40.982 UTC [32671][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-08 04:46:40.982 UTC [32671][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[04:46:41.001](0.212s) ok 4 - restart fails with incorrect SSL protocol bounds
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
pg_ctl: PID file "/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/postmaster.pid" does not exist
Is server running?
trying to start server anyway
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 32687
[04:46:41.218](0.217s) ok 5 - restart succeeds with correct SSL protocol bounds
[04:46:41.218](0.000s) # running client tests
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 32754
[04:46:41.472](0.253s) ok 6 - server doesn't accept non-SSL connections
[04:46:41.472](0.000s) ok 7 - server doesn't accept non-SSL connections: matches
[04:46:41.527](0.055s) ok 8 - connect without server root cert sslmode=require
[04:46:41.527](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr
[04:46:41.559](0.032s) ok 10 - connect without server root cert sslmode=verify-ca
[04:46:41.559](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches
[04:46:41.578](0.019s) ok 12 - connect without server root cert sslmode=verify-full
[04:46:41.578](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches
[04:46:41.616](0.038s) ok 14 - connect with wrong server root cert sslmode=require
[04:46:41.616](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches
[04:46:41.640](0.024s) ok 16 - connect with wrong server root cert sslmode=verify-ca
[04:46:41.640](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches
[04:46:41.669](0.029s) ok 18 - connect with wrong server root cert sslmode=verify-full
[04:46:41.670](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches
[04:46:41.726](0.056s) ok 20 - connect with server CA cert, without root CA
[04:46:41.726](0.000s) ok 21 - connect with server CA cert, without root CA: matches
[04:46:41.762](0.036s) ok 22 - connect with correct server CA cert file sslmode=require
[04:46:41.762](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr
[04:46:41.788](0.026s) ok 24 - connect with correct server CA cert file sslmode=verify-ca
[04:46:41.788](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr
[04:46:41.832](0.044s) ok 26 - connect with correct server CA cert file sslmode=verify-full
[04:46:41.832](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr
[04:46:41.889](0.056s) ok 28 - cert root file that contains two certificates, order 1
[04:46:41.889](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr
[04:46:41.912](0.023s) ok 30 - cert root file that contains two certificates, order 2
[04:46:41.912](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr
[04:46:42.024](0.112s) ok 32 - connect with sslcertmode=disable
[04:46:42.025](0.000s) ok 33 - connect with sslcertmode=disable: no stderr
[04:46:42.165](0.140s) ok 34 - connect with sslcertmode=allow
[04:46:42.165](0.000s) ok 35 - connect with sslcertmode=allow: no stderr
[04:46:42.309](0.144s) ok 36 - connect with sslcertmode=require fails without a client certificate
[04:46:42.310](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches
[04:46:42.458](0.149s) ok 38 - sslcrl option with invalid file name
[04:46:42.459](0.000s) ok 39 - sslcrl option with invalid file name: no stderr
[04:46:42.544](0.085s) ok 40 - CRL belonging to a different CA
[04:46:42.544](0.000s) ok 41 - CRL belonging to a different CA: matches
[04:46:42.646](0.101s) ok 42 - directory CRL belonging to a different CA
[04:46:42.648](0.002s) ok 43 - directory CRL belonging to a different CA: matches
[04:46:42.740](0.092s) ok 44 - CRL with a non-revoked cert
[04:46:42.740](0.000s) ok 45 - CRL with a non-revoked cert: no stderr
[04:46:42.818](0.078s) ok 46 - directory CRL with a non-revoked cert
[04:46:42.818](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr
[04:46:42.905](0.087s) ok 48 - mismatch between host name and server certificate sslmode=require
[04:46:42.905](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr
[04:46:42.974](0.069s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca
[04:46:42.975](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr
[04:46:43.043](0.069s) ok 52 - mismatch between host name and server certificate sslmode=verify-full
[04:46:43.044](0.000s) ok 53 - mismatch between host name and server certificate sslmode=verify-full: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 33080
[04:46:43.437](0.394s) ok 54 - IP address in the Common Name
[04:46:43.437](0.000s) ok 55 - IP address in the Common Name: no stderr
[04:46:43.483](0.046s) ok 56 - mismatch between host name and server certificate IP address
[04:46:43.484](0.000s) ok 57 - mismatch between host name and server certificate IP address: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 33144
[04:46:43.708](0.224s) ok 58 - IP address in a dNSName
[04:46:43.708](0.000s) ok 59 - IP address in a dNSName: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 33165
[04:46:44.089](0.381s) ok 60 - host name matching with X.509 Subject Alternative Names 1
[04:46:44.090](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr
[04:46:44.233](0.144s) ok 62 - host name matching with X.509 Subject Alternative Names 2
[04:46:44.233](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr
[04:46:44.394](0.160s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard
[04:46:44.394](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr
[04:46:44.488](0.094s) ok 66 - host name not matching with X.509 Subject Alternative Names
[04:46:44.488](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches
[04:46:44.604](0.116s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard
[04:46:44.604](0.000s) ok 69 - host name not matching with X.509 Subject Alternative Names wildcard: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 33257
[04:46:45.006](0.402s) ok 70 - host name matching with a single X.509 Subject Alternative Name
[04:46:45.006](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr
[04:46:45.105](0.099s) ok 72 - host name not matching with a single X.509 Subject Alternative Name
[04:46:45.106](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches
[04:46:45.193](0.087s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard
[04:46:45.194](0.000s) ok 75 - host name not matching with a single X.509 Subject Alternative Name wildcard: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 33352
[04:46:45.617](0.423s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1)
[04:46:45.617](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr
[04:46:45.672](0.056s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1)
[04:46:45.673](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr
[04:46:45.721](0.049s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1)
[04:46:45.722](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches
[04:46:45.780](0.058s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2)
[04:46:45.780](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr
[04:46:45.825](0.045s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2)
[04:46:45.825](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr
[04:46:45.910](0.086s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2)
[04:46:45.911](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr
[04:46:45.955](0.044s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2)
[04:46:45.955](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches
[04:46:45.998](0.043s) ok 90 - IPv6 host with CIDR mask does not match
[04:46:45.999](0.000s) ok 91 - IPv6 host with CIDR mask does not match: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 33426
[04:46:46.243](0.244s) ok 92 - certificate with both a CN and SANs 1
[04:46:46.243](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr
[04:46:46.309](0.065s) ok 94 - certificate with both a CN and SANs 2
[04:46:46.309](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr
[04:46:46.378](0.069s) ok 96 - certificate with both a CN and SANs ignores CN
[04:46:46.378](0.000s) ok 97 - certificate with both a CN and SANs ignores CN: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 33466
[04:46:46.722](0.344s) ok 98 - certificate with both a CN and IP SANs matches CN
[04:46:46.722](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr
[04:46:46.827](0.105s) ok 100 - certificate with both a CN and IP SANs matches SAN 1
[04:46:46.827](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr
[04:46:46.959](0.132s) ok 102 - certificate with both a CN and IP SANs matches SAN 2
[04:46:46.960](0.001s) ok 103 - certificate with both a CN and IP SANs matches SAN 2: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 33501
[04:46:47.233](0.274s) ok 104 - certificate with both an IP CN and IP SANs 1
[04:46:47.233](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr
[04:46:47.295](0.061s) ok 106 - certificate with both an IP CN and IP SANs 2
[04:46:47.295](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr
[04:46:47.365](0.070s) ok 108 - certificate with both an IP CN and IP SANs ignores CN
[04:46:47.365](0.000s) ok 109 - certificate with both an IP CN and IP SANs ignores CN: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 33552
[04:46:47.755](0.391s) ok 110 - certificate with both an IP CN and DNS SANs matches CN
[04:46:47.756](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr
[04:46:47.803](0.047s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1
[04:46:47.803](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr
[04:46:48.173](0.370s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2
[04:46:48.173](0.000s) ok 115 - certificate with both an IP CN and DNS SANs matches SAN 2: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 33668
[04:46:48.397](0.224s) ok 116 - server certificate without CN or SANs sslmode=verify-ca
[04:46:48.397](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr
[04:46:48.445](0.048s) ok 118 - server certificate without CN or SANs sslmode=verify-full
[04:46:48.445](0.000s) ok 119 - server certificate without CN or SANs sslmode=verify-full: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 33712
[04:46:48.728](0.282s) ok 120 - sslrootcert=system does not connect with private CA
[04:46:48.728](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches
[04:46:48.761](0.033s) ok 122 - sslrootcert=system only accepts sslmode=verify-full
[04:46:48.761](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches
[04:46:48.845](0.084s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE
[04:46:48.845](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr
[04:46:48.889](0.043s) ok 126 - sslrootcert=system defaults to sslmode=verify-full
[04:46:48.889](0.000s) ok 127 - sslrootcert=system defaults to sslmode=verify-full: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 33757
[04:46:49.137](0.248s) ok 128 - connects without client-side CRL
[04:46:49.137](0.000s) ok 129 - connects without client-side CRL: no stderr
[04:46:49.175](0.038s) ok 130 - does not connect with client-side CRL file
[04:46:49.175](0.000s) ok 131 - does not connect with client-side CRL file: matches
[04:46:49.221](0.045s) ok 132 - does not connect with client-side CRL directory
[04:46:49.221](0.000s) ok 133 - does not connect with client-side CRL directory: matches
# Running: psql -X -A -F , -P null=_null_ -d sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
[04:46:49.278](0.057s) ok 134 - pg_stat_ssl view without client certificate: exit code 0
[04:46:49.279](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr
[04:46:49.279](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches
[04:46:49.330](0.051s) ok 137 - connection success with correct range of TLS protocol versions
[04:46:49.330](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr
[04:46:49.365](0.035s) ok 139 - connection failure with incorrect range of TLS protocol versions
[04:46:49.365](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches
[04:46:49.390](0.025s) ok 141 - connection failure with an incorrect SSL protocol minimum bound
[04:46:49.391](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches
[04:46:49.424](0.033s) ok 143 - connection failure with an incorrect SSL protocol maximum bound
[04:46:49.424](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches
[04:46:49.425](0.000s) # running server tests
[04:46:49.472](0.048s) ok 145 - certificate authorization fails without client cert
[04:46:49.473](0.000s) ok 146 - certificate authorization fails without client cert: matches
[04:46:49.562](0.089s) ok 147 - certificate authorization succeeds with correct client cert in PEM format
[04:46:49.562](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr
[04:46:49.619](0.057s) ok 149 - certificate authorization succeeds with correct client cert in DER format
[04:46:49.619](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr
[04:46:49.670](0.050s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format
[04:46:49.670](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr
[04:46:49.734](0.064s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format
[04:46:49.734](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr
[04:46:49.792](0.059s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require
[04:46:49.793](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr
[04:46:49.843](0.050s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow
[04:46:49.843](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr
[04:46:49.875](0.032s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable
[04:46:49.875](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches
[04:46:49.915](0.040s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format
[04:46:49.915](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches
[04:46:49.980](0.064s) ok 163 - certificate authorization succeeds with DN mapping
[04:46:49.980](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr
[04:46:49.980](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches
[04:46:50.033](0.053s) ok 166 - certificate authorization succeeds with DN regex mapping
[04:46:50.033](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr
[04:46:50.107](0.074s) ok 168 - certificate authorization succeeds with CN mapping
[04:46:50.107](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr
[04:46:50.108](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches
[04:46:50.108](0.000s) not ok 171 # TODO & SKIP Need Pty support
[04:46:50.108](0.000s) not ok 172 # TODO & SKIP Need Pty support
[04:46:50.108](0.000s) not ok 173 # TODO & SKIP Need Pty support
[04:46:50.108](0.000s) not ok 174 # TODO & SKIP Need Pty support
# Running: psql -X -A -F , -P null=_null_ -d sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_PiY7/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
[04:46:50.176](0.068s) ok 175 - pg_stat_ssl with client certificate: exit code 0
[04:46:50.176](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr
[04:46:50.177](0.000s) ok 177 - pg_stat_ssl with client certificate: matches
[04:46:50.213](0.036s) ok 178 - certificate authorization fails because of file permissions
[04:46:50.213](0.000s) ok 179 - certificate authorization fails because of file permissions: matches
[04:46:50.257](0.044s) ok 180 - certificate authorization fails with client cert belonging to another user
[04:46:50.257](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches
[04:46:50.308](0.051s) ok 182 - certificate authorization fails with revoked client cert
[04:46:50.308](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches
[04:46:50.308](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match
[04:46:50.383](0.075s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name
[04:46:50.383](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr
[04:46:50.383](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches
[04:46:50.440](0.057s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name
[04:46:50.440](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches
[04:46:50.441](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match
[04:46:50.484](0.043s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name
[04:46:50.484](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr
[04:46:50.484](0.000s) ok 193 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: log matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 34031
[04:46:50.748](0.264s) ok 194 - intermediate client certificate is provided by client
[04:46:50.748](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr
[04:46:51.301](0.553s) ok 196 - intermediate client certificate is missing
[04:46:51.301](0.000s) ok 197 - intermediate client certificate is missing: matches
[04:46:51.349](0.048s) ok 198 - logged client certificate Subjects are truncated if they're too long
[04:46:51.349](0.000s) ok 199 - logged client certificate Subjects are truncated if they're too long: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 34158
[04:46:51.639](0.290s) ok 200 - intermediate client certificate is untrusted
[04:46:51.640](0.000s) ok 201 - intermediate client certificate is untrusted: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 34211
[04:46:52.003](0.363s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory
[04:46:52.003](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches
[04:46:52.068](0.065s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory
[04:46:52.069](0.000s) ok 205 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 34262
[04:46:52.334](0.265s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1
[04:46:52.334](0.000s) 
[04:46:52.334](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1'
#   at t/001_ssltests.pl line 923.
[04:46:52.335](0.000s) #          got: '2'
#     expected: '0'
[04:46:52.335](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[04:46:52.335](0.000s) 
[04:46:52.335](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr'
#   at t/001_ssltests.pl line 923.
[04:46:52.335](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 63513 failed: server closed the connection unexpectedly
# 	This probably means the server terminated abnormally
# 	before or while processing the request.
# SSL SYSCALL error: Connection reset by peer'
#     expected: ''
[04:46:52.400](0.065s) ok 208 - connect without requesting ocsp response when sslocspstapling=0
[04:46:52.400](0.000s) ok 209 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 34324
[04:46:52.664](0.264s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1
[04:46:52.722](0.058s) ok 211 - connect without requesting ocsp response when sslocspstapling=0
[04:46:52.722](0.000s) ok 212 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 34410
[04:46:52.970](0.248s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1
[04:46:53.020](0.050s) ok 214 - connect without requesting ocsp response when sslocspstapling=0
[04:46:53.020](0.000s) ok 215 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 34480
[04:46:53.289](0.269s) ok 216 - failed with an expired ocsp response when sslocspstapling=1
[04:46:53.357](0.068s) ok 217 - connect without requesting ocsp response when sslocspstapling=0
[04:46:53.357](0.000s) ok 218 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 34541
[04:46:53.756](0.399s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1
[04:46:53.756](0.001s) 
[04:46:53.756](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1'
#   at t/001_ssltests.pl line 1001.
[04:46:53.757](0.000s) #          got: '2'
#     expected: '0'
[04:46:53.757](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[04:46:53.757](0.000s) 
[04:46:53.757](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr'
#   at t/001_ssltests.pl line 1001.
[04:46:53.758](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 63513 failed: server closed the connection unexpectedly
# 	This probably means the server terminated abnormally
# 	before or while processing the request.
# SSL SYSCALL error: Connection reset by peer'
#     expected: ''
[04:46:53.948](0.190s) ok 221 - connect without requesting ocsp response when sslocspstapling=0
[04:46:53.948](0.000s) ok 222 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 34631
[04:46:54.347](0.399s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1
[04:46:54.408](0.061s) ok 224 - connect without requesting ocsp response when sslocspstapling=0
[04:46:54.409](0.000s) ok 225 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 34705
[04:46:54.656](0.248s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1
[04:46:54.753](0.096s) ok 227 - connect without requesting ocsp response when sslocspstapling=0
[04:46:54.753](0.000s) ok 228 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 34798
[04:46:55.005](0.252s) ok 229 - failed with an expired ocsp response when sslocspstapling=1
[04:46:55.093](0.088s) ok 230 - connect without requesting ocsp response when sslocspstapling=0
[04:46:55.093](0.000s) ok 231 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 34867
[04:46:55.334](0.241s) ok 232 - failed with an expired ocsp response when sslocspstapling=1
[04:46:55.382](0.047s) ok 233 - connect without requesting ocsp response when sslocspstapling=0
[04:46:55.382](0.000s) ok 234 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... stopped waiting
pg_ctl: could not start server
Examine the log output.
# pg_ctl restart failed; logfile:
2024-03-08 04:46:38.017 UTC [32292][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:38.017 UTC [32292][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:38.022 UTC [32301][startup] LOG:  database system was shut down at 2024-03-08 04:44:12 UTC
2024-03-08 04:46:38.030 UTC [32292][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:38.111 UTC [32327][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.112 UTC [32327][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.112 UTC [32327][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.139 UTC [32327][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-08 04:46:38.143 UTC [32327][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.034 user=postgres database=postgres host=[local]
2024-03-08 04:46:38.179 UTC [32344][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.180 UTC [32344][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.180 UTC [32344][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.184 UTC [32344][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-08 04:46:38.189 UTC [32344][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-03-08 04:46:38.232 UTC [32352][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.233 UTC [32352][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.233 UTC [32352][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.240 UTC [32352][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-08 04:46:38.244 UTC [32352][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=postgres database=postgres host=[local]
2024-03-08 04:46:38.274 UTC [32356][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.275 UTC [32356][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.275 UTC [32356][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.280 UTC [32356][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-08 04:46:38.280 UTC [32356][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local]
2024-03-08 04:46:38.313 UTC [32377][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.314 UTC [32377][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.314 UTC [32377][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.320 UTC [32377][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-08 04:46:38.322 UTC [32377][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=postgres database=postgres host=[local]
2024-03-08 04:46:38.391 UTC [32387][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.393 UTC [32387][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.393 UTC [32387][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.405 UTC [32387][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-08 04:46:38.523 UTC [32387][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.133 user=postgres database=postgres host=[local]
2024-03-08 04:46:38.694 UTC [32422][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.695 UTC [32422][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.695 UTC [32422][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.705 UTC [32422][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-08 04:46:38.853 UTC [32422][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.161 user=postgres database=postgres host=[local]
2024-03-08 04:46:38.959 UTC [32461][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:38.959 UTC [32461][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:38.959 UTC [32461][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:38.961 UTC [32461][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-08 04:46:39.007 UTC [32461][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.048 user=postgres database=postgres host=[local]
2024-03-08 04:46:39.095 UTC [32472][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:39.096 UTC [32472][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:39.096 UTC [32472][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:39.098 UTC [32472][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-08 04:46:39.161 UTC [32472][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.066 user=postgres database=postgres host=[local]
2024-03-08 04:46:39.248 UTC [32493][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:39.256 UTC [32493][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:39.256 UTC [32493][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:39.260 UTC [32493][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-08 04:46:39.383 UTC [32493][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.135 user=postgres database=postgres host=[local]
2024-03-08 04:46:39.547 UTC [32518][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-08 04:46:39.548 UTC [32518][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-08 04:46:39.548 UTC [32518][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-08 04:46:39.558 UTC [32518][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-08 04:46:39.728 UTC [32518][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.182 user=postgres database=postgres host=[local]
2024-03-08 04:46:39.812 UTC [32292][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:39.812 UTC [32292][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:39.819 UTC [32292][postmaster] LOG:  background worker "logical replication launcher" (PID 32306) exited with exit code 1
2024-03-08 04:46:39.819 UTC [32299][checkpointer] LOG:  shutting down
2024-03-08 04:46:39.820 UTC [32299][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:39.956 UTC [32299][checkpointer] LOG:  checkpoint complete: wrote 5650 buffers (34.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.133 s, sync=0.004 s, total=0.137 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45479 kB, estimate=45479 kB; lsn=0/4196260, redo lsn=0/4196260
2024-03-08 04:46:39.981 UTC [32292][postmaster] LOG:  database system is shut down
2024-03-08 04:46:40.160 UTC [32580][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:40.161 UTC [32580][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:40.161 UTC [32580][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:40.193 UTC [32599][startup] LOG:  database system was shut down at 2024-03-08 04:46:39 UTC
2024-03-08 04:46:40.201 UTC [32580][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:40.262 UTC [32580][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:40.262 UTC [32580][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:40.266 UTC [32580][postmaster] LOG:  background worker "logical replication launcher" (PID 32602) exited with exit code 1
2024-03-08 04:46:40.268 UTC [32595][checkpointer] LOG:  shutting down
2024-03-08 04:46:40.268 UTC [32595][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:40.268 UTC [32595][checkpointer] LOG:  checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41962D8, redo lsn=0/41962D8
2024-03-08 04:46:40.273 UTC [32580][postmaster] LOG:  database system is shut down
2024-03-08 04:46:40.465 UTC [32615][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-08 04:46:40.465 UTC [32615][postmaster] LOG:  database system is shut down
2024-03-08 04:46:40.652 UTC [32633][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:40.652 UTC [32633][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:40.652 UTC [32633][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:40.697 UTC [32642][startup] LOG:  database system was shut down at 2024-03-08 04:46:40 UTC
2024-03-08 04:46:40.726 UTC [32633][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:40.799 UTC [32633][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:40.799 UTC [32633][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:40.804 UTC [32633][postmaster] LOG:  background worker "logical replication launcher" (PID 32647) exited with exit code 1
2024-03-08 04:46:40.804 UTC [32639][checkpointer] LOG:  shutting down
2024-03-08 04:46:40.804 UTC [32639][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:40.805 UTC [32639][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196350, redo lsn=0/4196350
2024-03-08 04:46:40.811 UTC [32633][postmaster] LOG:  database system is shut down
2024-03-08 04:46:40.982 UTC [32671][postmaster] FATAL:  could not set SSL protocol version range
2024-03-08 04:46:40.982 UTC [32671][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-08 04:46:40.982 UTC [32671][postmaster] LOG:  database system is shut down
2024-03-08 04:46:41.089 UTC [32687][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:41.089 UTC [32687][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:41.089 UTC [32687][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:41.108 UTC [32712][startup] LOG:  database system was shut down at 2024-03-08 04:46:40 UTC
2024-03-08 04:46:41.121 UTC [32687][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:41.238 UTC [32687][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:41.238 UTC [32687][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:41.248 UTC [32687][postmaster] LOG:  background worker "logical replication launcher" (PID 32720) exited with exit code 1
2024-03-08 04:46:41.250 UTC [32710][checkpointer] LOG:  shutting down
2024-03-08 04:46:41.251 UTC [32710][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:41.251 UTC [32710][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41963C8, redo lsn=0/41963C8
2024-03-08 04:46:41.257 UTC [32687][postmaster] LOG:  database system is shut down
2024-03-08 04:46:41.377 UTC [32754][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:41.377 UTC [32754][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:41.377 UTC [32754][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:41.395 UTC [32762][startup] LOG:  database system was shut down at 2024-03-08 04:46:41 UTC
2024-03-08 04:46:41.404 UTC [32754][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:41.468 UTC [32773][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33920
2024-03-08 04:46:41.469 UTC [32773][client backend] [[unknown]][0/1:0] FATAL:  no pg_hba.conf entry for host "127.0.0.1", user "ssltestuser", database "trustdb", no encryption
2024-03-08 04:46:41.469 UTC [32773][client backend] [[unknown]][0/1:0] DETAIL:  Client IP address resolved to "localhost", forward lookup not checked.
2024-03-08 04:46:41.500 UTC [32776][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33924
2024-03-08 04:46:41.509 UTC [32776][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:41.509 UTC [32776][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:41.523 UTC [32776][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid sslmode=require$$
2024-03-08 04:46:41.526 UTC [32776][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=ssltestuser database=trustdb host=localhost port=33924
2024-03-08 04:46:41.554 UTC [32789][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33936
2024-03-08 04:46:41.558 UTC [32789][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-08 04:46:41.573 UTC [32793][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33948
2024-03-08 04:46:41.577 UTC [32793][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-08 04:46:41.602 UTC [32801][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33952
2024-03-08 04:46:41.613 UTC [32801][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-08 04:46:41.631 UTC [32806][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33964
2024-03-08 04:46:41.642 UTC [32806][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-08 04:46:41.660 UTC [32816][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33980
2024-03-08 04:46:41.671 UTC [32816][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-08 04:46:41.713 UTC [32837][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33990
2024-03-08 04:46:41.720 UTC [32837][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-08 04:46:41.739 UTC [32844][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34000
2024-03-08 04:46:41.752 UTC [32844][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:41.752 UTC [32844][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:41.758 UTC [32844][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require$$
2024-03-08 04:46:41.758 UTC [32844][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.019 user=ssltestuser database=trustdb host=localhost port=34000
2024-03-08 04:46:41.777 UTC [32852][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34006
2024-03-08 04:46:41.783 UTC [32852][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:41.783 UTC [32852][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:41.784 UTC [32852][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$
2024-03-08 04:46:41.790 UTC [32852][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=34006
2024-03-08 04:46:41.809 UTC [32857][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34022
2024-03-08 04:46:41.823 UTC [32857][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:41.823 UTC [32857][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:41.828 UTC [32857][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-full$$
2024-03-08 04:46:41.829 UTC [32857][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=ssltestuser database=trustdb host=localhost port=34022
2024-03-08 04:46:41.876 UTC [32873][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34034
2024-03-08 04:46:41.883 UTC [32873][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:41.883 UTC [32873][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:41.885 UTC [32873][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-1.crt sslmode=verify-ca$$
2024-03-08 04:46:41.891 UTC [32873][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.022 user=ssltestuser database=trustdb host=localhost port=34034
2024-03-08 04:46:41.900 UTC [32899][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34042
2024-03-08 04:46:41.907 UTC [32899][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:41.907 UTC [32899][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:41.908 UTC [32899][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-2.crt sslmode=verify-ca$$
2024-03-08 04:46:41.911 UTC [32899][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=34042
2024-03-08 04:46:41.974 UTC [32958][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34044
2024-03-08 04:46:41.996 UTC [32958][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:41.996 UTC [32958][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:42.005 UTC [32958][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=disable$$
2024-03-08 04:46:42.009 UTC [32958][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.040 user=ssltestuser database=trustdb host=localhost port=34044
2024-03-08 04:46:42.119 UTC [32982][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34056
2024-03-08 04:46:42.137 UTC [32982][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:42.137 UTC [32982][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:42.159 UTC [32982][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=allow$$
2024-03-08 04:46:42.167 UTC [32982][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.050 user=ssltestuser database=trustdb host=localhost port=34056
2024-03-08 04:46:42.253 UTC [32990][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34066
2024-03-08 04:46:42.279 UTC [32990][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:42.279 UTC [32990][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:42.292 UTC [32990][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.047 user=ssltestuser database=trustdb host=localhost port=34066
2024-03-08 04:46:42.406 UTC [33003][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34070
2024-03-08 04:46:42.436 UTC [33003][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:42.436 UTC [33003][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:42.452 UTC [33003][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=invalid$$
2024-03-08 04:46:42.468 UTC [33003][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.063 user=ssltestuser database=trustdb host=localhost port=34070
2024-03-08 04:46:42.535 UTC [33011][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34086
2024-03-08 04:46:42.548 UTC [33011][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-08 04:46:42.581 UTC [33019][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34096
2024-03-08 04:46:42.623 UTC [33019][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-08 04:46:42.721 UTC [33030][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34102
2024-03-08 04:46:42.732 UTC [33030][client backend] [[unknown]][11/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:42.732 UTC [33030][client backend] [[unknown]][11/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:42.736 UTC [33030][client backend] [001_ssltests.pl][11/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=ssl/root+server.crl$$
2024-03-08 04:46:42.739 UTC [33030][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=34102
2024-03-08 04:46:42.785 UTC [33033][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34110
2024-03-08 04:46:42.797 UTC [33033][client backend] [[unknown]][12/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:42.797 UTC [33033][client backend] [[unknown]][12/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:42.807 UTC [33033][client backend] [001_ssltests.pl][12/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrldir=ssl/root+server-crldir$$
2024-03-08 04:46:42.811 UTC [33033][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=ssltestuser database=trustdb host=localhost port=34110
2024-03-08 04:46:42.859 UTC [33038][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34116
2024-03-08 04:46:42.884 UTC [33038][client backend] [[unknown]][13/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:42.884 UTC [33038][client backend] [[unknown]][13/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:42.886 UTC [33038][client backend] [001_ssltests.pl][13/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=require host=wronghost.test$$
2024-03-08 04:46:42.895 UTC [33038][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.040 user=ssltestuser database=trustdb host=localhost port=34116
2024-03-08 04:46:42.925 UTC [33049][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34130
2024-03-08 04:46:42.968 UTC [33049][client backend] [[unknown]][14/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:42.968 UTC [33049][client backend] [[unknown]][14/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:42.970 UTC [33049][client backend] [001_ssltests.pl][14/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=wronghost.test$$
2024-03-08 04:46:42.974 UTC [33049][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.049 user=ssltestuser database=trustdb host=localhost port=34130
2024-03-08 04:46:43.031 UTC [33054][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34146
2024-03-08 04:46:43.048 UTC [32754][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:43.048 UTC [32754][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:43.061 UTC [32754][postmaster] LOG:  background worker "logical replication launcher" (PID 32768) exited with exit code 1
2024-03-08 04:46:43.061 UTC [32759][checkpointer] LOG:  shutting down
2024-03-08 04:46:43.061 UTC [32759][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:43.062 UTC [32759][checkpointer] LOG:  checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196440, redo lsn=0/4196440
2024-03-08 04:46:43.068 UTC [32754][postmaster] LOG:  database system is shut down
2024-03-08 04:46:43.228 UTC [33080][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:43.228 UTC [33080][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:43.228 UTC [33080][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:43.254 UTC [33092][startup] LOG:  database system was shut down at 2024-03-08 04:46:43 UTC
2024-03-08 04:46:43.275 UTC [33080][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:43.394 UTC [33127][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34156
2024-03-08 04:46:43.407 UTC [33127][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:43.407 UTC [33127][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:43.433 UTC [33127][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-08 04:46:43.439 UTC [33127][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.046 user=ssltestuser database=trustdb host=localhost port=34156
2024-03-08 04:46:43.469 UTC [33139][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34166
2024-03-08 04:46:43.493 UTC [33080][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:43.493 UTC [33080][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:43.510 UTC [33080][postmaster] LOG:  background worker "logical replication launcher" (PID 33100) exited with exit code 1
2024-03-08 04:46:43.510 UTC [33089][checkpointer] LOG:  shutting down
2024-03-08 04:46:43.510 UTC [33089][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:43.511 UTC [33089][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41964B8, redo lsn=0/41964B8
2024-03-08 04:46:43.517 UTC [33080][postmaster] LOG:  database system is shut down
2024-03-08 04:46:43.588 UTC [33144][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:43.588 UTC [33144][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:43.588 UTC [33144][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:43.612 UTC [33152][startup] LOG:  database system was shut down at 2024-03-08 04:46:43 UTC
2024-03-08 04:46:43.628 UTC [33144][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:43.672 UTC [33159][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34168
2024-03-08 04:46:43.684 UTC [33159][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:43.684 UTC [33159][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:43.704 UTC [33159][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-08 04:46:43.711 UTC [33159][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.039 user=ssltestuser database=trustdb host=localhost port=34168
2024-03-08 04:46:43.725 UTC [33144][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:43.725 UTC [33144][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:43.733 UTC [33144][postmaster] LOG:  background worker "logical replication launcher" (PID 33157) exited with exit code 1
2024-03-08 04:46:43.733 UTC [33149][checkpointer] LOG:  shutting down
2024-03-08 04:46:43.733 UTC [33149][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:43.733 UTC [33149][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196530, redo lsn=0/4196530
2024-03-08 04:46:43.740 UTC [33144][postmaster] LOG:  database system is shut down
2024-03-08 04:46:43.862 UTC [33165][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:43.863 UTC [33165][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:43.863 UTC [33165][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:43.896 UTC [33179][startup] LOG:  database system was shut down at 2024-03-08 04:46:43 UTC
2024-03-08 04:46:43.918 UTC [33165][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:44.000 UTC [33194][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34180
2024-03-08 04:46:44.011 UTC [33194][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:44.011 UTC [33194][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:44.084 UTC [33194][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$
2024-03-08 04:46:44.091 UTC [33194][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.096 user=ssltestuser database=trustdb host=localhost port=34180
2024-03-08 04:46:44.177 UTC [33214][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34192
2024-03-08 04:46:44.203 UTC [33214][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:44.203 UTC [33214][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:44.212 UTC [33214][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$
2024-03-08 04:46:44.220 UTC [33214][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.043 user=ssltestuser database=trustdb host=localhost port=34192
2024-03-08 04:46:44.332 UTC [33226][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34204
2024-03-08 04:46:44.361 UTC [33226][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:44.361 UTC [33226][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:44.388 UTC [33226][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=foo.wildcard.pg-ssltest.test$$
2024-03-08 04:46:44.392 UTC [33226][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.063 user=ssltestuser database=trustdb host=localhost port=34204
2024-03-08 04:46:44.458 UTC [33235][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34206
2024-03-08 04:46:44.585 UTC [33246][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34210
2024-03-08 04:46:44.631 UTC [33165][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:44.631 UTC [33165][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:44.638 UTC [33165][postmaster] LOG:  background worker "logical replication launcher" (PID 33186) exited with exit code 1
2024-03-08 04:46:44.638 UTC [33177][checkpointer] LOG:  shutting down
2024-03-08 04:46:44.638 UTC [33177][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:44.639 UTC [33177][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41965A8, redo lsn=0/41965A8
2024-03-08 04:46:44.645 UTC [33165][postmaster] LOG:  database system is shut down
2024-03-08 04:46:44.827 UTC [33257][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:44.828 UTC [33257][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:44.828 UTC [33257][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:44.864 UTC [33277][startup] LOG:  database system was shut down at 2024-03-08 04:46:44 UTC
2024-03-08 04:46:44.879 UTC [33257][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:44.974 UTC [33304][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34214
2024-03-08 04:46:44.982 UTC [33304][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:44.982 UTC [33304][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:45.000 UTC [33304][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=single.alt-name.pg-ssltest.test$$
2024-03-08 04:46:45.012 UTC [33304][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.048 user=ssltestuser database=trustdb host=localhost port=34214
2024-03-08 04:46:45.065 UTC [33313][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34218
2024-03-08 04:46:45.161 UTC [33320][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34232
2024-03-08 04:46:45.227 UTC [33257][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:45.227 UTC [33257][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:45.238 UTC [33257][postmaster] LOG:  background worker "logical replication launcher" (PID 33286) exited with exit code 1
2024-03-08 04:46:45.238 UTC [33272][checkpointer] LOG:  shutting down
2024-03-08 04:46:45.238 UTC [33272][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:45.239 UTC [33272][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196620, redo lsn=0/4196620
2024-03-08 04:46:45.246 UTC [33257][postmaster] LOG:  database system is shut down
2024-03-08 04:46:45.436 UTC [33352][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:45.437 UTC [33352][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:45.437 UTC [33352][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:45.476 UTC [33370][startup] LOG:  database system was shut down at 2024-03-08 04:46:45 UTC
2024-03-08 04:46:45.488 UTC [33352][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:45.574 UTC [33383][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34236
2024-03-08 04:46:45.588 UTC [33383][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:45.588 UTC [33383][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:45.613 UTC [33383][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-08 04:46:45.618 UTC [33383][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.045 user=ssltestuser database=trustdb host=localhost port=34236
2024-03-08 04:46:45.660 UTC [33391][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34238
2024-03-08 04:46:45.667 UTC [33391][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:45.667 UTC [33391][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:45.668 UTC [33391][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.000.002.001$$
2024-03-08 04:46:45.674 UTC [33391][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=trustdb host=localhost port=34238
2024-03-08 04:46:45.700 UTC [33399][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34252
2024-03-08 04:46:45.768 UTC [33405][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34264
2024-03-08 04:46:45.775 UTC [33405][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:45.775 UTC [33405][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:45.776 UTC [33405][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:DB8::1$$
2024-03-08 04:46:45.781 UTC [33405][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=localhost port=34264
2024-03-08 04:46:45.812 UTC [33409][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34274
2024-03-08 04:46:45.819 UTC [33409][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:45.819 UTC [33409][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:45.821 UTC [33409][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8:0:0:0:0:0:1$$
2024-03-08 04:46:45.824 UTC [33409][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.022 user=ssltestuser database=trustdb host=localhost port=34274
2024-03-08 04:46:45.887 UTC [33417][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34284
2024-03-08 04:46:45.897 UTC [33417][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:45.897 UTC [33417][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:45.906 UTC [33417][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::0.0.0.1$$
2024-03-08 04:46:45.908 UTC [33417][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=ssltestuser database=trustdb host=localhost port=34284
2024-03-08 04:46:45.946 UTC [33419][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34286
2024-03-08 04:46:45.990 UTC [33421][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34300
2024-03-08 04:46:46.016 UTC [33352][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:46.016 UTC [33352][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:46.020 UTC [33352][postmaster] LOG:  background worker "logical replication launcher" (PID 33374) exited with exit code 1
2024-03-08 04:46:46.027 UTC [33367][checkpointer] LOG:  shutting down
2024-03-08 04:46:46.027 UTC [33367][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:46.028 UTC [33367][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196698, redo lsn=0/4196698
2024-03-08 04:46:46.032 UTC [33352][postmaster] LOG:  database system is shut down
2024-03-08 04:46:46.094 UTC [33426][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:46.094 UTC [33426][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:46.094 UTC [33426][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:46.104 UTC [33435][startup] LOG:  database system was shut down at 2024-03-08 04:46:46 UTC
2024-03-08 04:46:46.118 UTC [33426][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:46.187 UTC [33445][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34302
2024-03-08 04:46:46.194 UTC [33445][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:46.194 UTC [33445][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:46.238 UTC [33445][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$
2024-03-08 04:46:46.239 UTC [33445][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.053 user=ssltestuser database=trustdb host=localhost port=34302
2024-03-08 04:46:46.278 UTC [33451][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34312
2024-03-08 04:46:46.299 UTC [33451][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:46.299 UTC [33451][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:46.305 UTC [33451][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$
2024-03-08 04:46:46.305 UTC [33451][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.028 user=ssltestuser database=trustdb host=localhost port=34312
2024-03-08 04:46:46.367 UTC [33454][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34320
2024-03-08 04:46:46.416 UTC [33426][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:46.416 UTC [33426][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:46.426 UTC [33426][postmaster] LOG:  background worker "logical replication launcher" (PID 33440) exited with exit code 1
2024-03-08 04:46:46.428 UTC [33433][checkpointer] LOG:  shutting down
2024-03-08 04:46:46.428 UTC [33433][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:46.429 UTC [33433][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196710, redo lsn=0/4196710
2024-03-08 04:46:46.436 UTC [33426][postmaster] LOG:  database system is shut down
2024-03-08 04:46:46.588 UTC [33466][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:46.588 UTC [33466][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:46.588 UTC [33466][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:46.599 UTC [33472][startup] LOG:  database system was shut down at 2024-03-08 04:46:46 UTC
2024-03-08 04:46:46.609 UTC [33466][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:46.677 UTC [33478][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34330
2024-03-08 04:46:46.685 UTC [33478][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:46.685 UTC [33478][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:46.715 UTC [33478][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$
2024-03-08 04:46:46.724 UTC [33478][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.059 user=ssltestuser database=trustdb host=localhost port=34330
2024-03-08 04:46:46.769 UTC [33484][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46726
2024-03-08 04:46:46.805 UTC [33484][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:46.805 UTC [33484][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:46.809 UTC [33484][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-08 04:46:46.814 UTC [33484][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.047 user=ssltestuser database=trustdb host=localhost port=46726
2024-03-08 04:46:46.917 UTC [33494][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46728
2024-03-08 04:46:46.940 UTC [33494][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:46.940 UTC [33494][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:46.944 UTC [33494][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$
2024-03-08 04:46:46.948 UTC [33494][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.033 user=ssltestuser database=trustdb host=localhost port=46728
2024-03-08 04:46:46.978 UTC [33466][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:46.978 UTC [33466][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:46.993 UTC [33466][postmaster] LOG:  background worker "logical replication launcher" (PID 33476) exited with exit code 1
2024-03-08 04:46:46.993 UTC [33470][checkpointer] LOG:  shutting down
2024-03-08 04:46:46.993 UTC [33470][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:46.994 UTC [33470][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196788, redo lsn=0/4196788
2024-03-08 04:46:47.001 UTC [33466][postmaster] LOG:  database system is shut down
2024-03-08 04:46:47.145 UTC [33501][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:47.145 UTC [33501][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:47.145 UTC [33501][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:47.164 UTC [33504][startup] LOG:  database system was shut down at 2024-03-08 04:46:46 UTC
2024-03-08 04:46:47.174 UTC [33501][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:47.200 UTC [33509][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46736
2024-03-08 04:46:47.207 UTC [33509][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:47.207 UTC [33509][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:47.228 UTC [33509][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.2$$
2024-03-08 04:46:47.235 UTC [33509][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.035 user=ssltestuser database=trustdb host=localhost port=46736
2024-03-08 04:46:47.271 UTC [33513][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46746
2024-03-08 04:46:47.279 UTC [33513][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:47.279 UTC [33513][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:47.289 UTC [33513][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$
2024-03-08 04:46:47.297 UTC [33513][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=ssltestuser database=trustdb host=localhost port=46746
2024-03-08 04:46:47.355 UTC [33525][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46748
2024-03-08 04:46:47.379 UTC [33501][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:47.379 UTC [33501][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:47.388 UTC [33501][postmaster] LOG:  background worker "logical replication launcher" (PID 33507) exited with exit code 1
2024-03-08 04:46:47.388 UTC [33502][checkpointer] LOG:  shutting down
2024-03-08 04:46:47.388 UTC [33502][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:47.389 UTC [33502][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196800, redo lsn=0/4196800
2024-03-08 04:46:47.395 UTC [33501][postmaster] LOG:  database system is shut down
2024-03-08 04:46:47.562 UTC [33552][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:47.562 UTC [33552][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:47.562 UTC [33552][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:47.578 UTC [33562][startup] LOG:  database system was shut down at 2024-03-08 04:46:47 UTC
2024-03-08 04:46:47.590 UTC [33552][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:47.710 UTC [33590][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46764
2024-03-08 04:46:47.726 UTC [33590][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:47.726 UTC [33590][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:47.751 UTC [33590][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-08 04:46:47.757 UTC [33590][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.049 user=ssltestuser database=trustdb host=localhost port=46764
2024-03-08 04:46:47.786 UTC [33607][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46778
2024-03-08 04:46:47.795 UTC [33607][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:47.795 UTC [33607][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:47.799 UTC [33607][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$
2024-03-08 04:46:47.810 UTC [33607][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=localhost port=46778
2024-03-08 04:46:47.831 UTC [33618][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46794
2024-03-08 04:46:47.844 UTC [33618][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:47.844 UTC [33618][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:47.848 UTC [33618][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$
2024-03-08 04:46:47.852 UTC [33618][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=ssltestuser database=trustdb host=localhost port=46794
2024-03-08 04:46:48.185 UTC [33552][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:48.185 UTC [33552][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:48.197 UTC [33552][postmaster] LOG:  background worker "logical replication launcher" (PID 33567) exited with exit code 1
2024-03-08 04:46:48.197 UTC [33560][checkpointer] LOG:  shutting down
2024-03-08 04:46:48.197 UTC [33560][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:48.198 UTC [33560][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196878, redo lsn=0/4196878
2024-03-08 04:46:48.203 UTC [33552][postmaster] LOG:  database system is shut down
2024-03-08 04:46:48.252 UTC [33668][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:48.252 UTC [33668][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:48.252 UTC [33668][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:48.287 UTC [33675][startup] LOG:  database system was shut down at 2024-03-08 04:46:48 UTC
2024-03-08 04:46:48.304 UTC [33668][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:48.347 UTC [33684][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46798
2024-03-08 04:46:48.357 UTC [33684][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:48.357 UTC [33684][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:48.393 UTC [33684][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=common-name.pg-ssltest.test$$
2024-03-08 04:46:48.396 UTC [33684][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.049 user=ssltestuser database=trustdb host=localhost port=46798
2024-03-08 04:46:48.428 UTC [33690][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46802
2024-03-08 04:46:48.462 UTC [33668][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:48.462 UTC [33668][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:48.466 UTC [33668][postmaster] LOG:  background worker "logical replication launcher" (PID 33681) exited with exit code 1
2024-03-08 04:46:48.469 UTC [33672][checkpointer] LOG:  shutting down
2024-03-08 04:46:48.469 UTC [33672][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:48.470 UTC [33672][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41968F0, redo lsn=0/41968F0
2024-03-08 04:46:48.475 UTC [33668][postmaster] LOG:  database system is shut down
2024-03-08 04:46:48.623 UTC [33712][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:48.623 UTC [33712][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:48.623 UTC [33712][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:48.644 UTC [33719][startup] LOG:  database system was shut down at 2024-03-08 04:46:48 UTC
2024-03-08 04:46:48.654 UTC [33712][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:48.711 UTC [33728][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46814
2024-03-08 04:46:48.730 UTC [33728][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-08 04:46:48.804 UTC [33746][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46830
2024-03-08 04:46:48.812 UTC [33746][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:48.812 UTC [33746][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:48.840 UTC [33746][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=system hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$
2024-03-08 04:46:48.847 UTC [33746][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.051 user=ssltestuser database=trustdb host=localhost port=46830
2024-03-08 04:46:48.879 UTC [33752][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46838
2024-03-08 04:46:48.902 UTC [33712][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:48.903 UTC [33712][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:48.912 UTC [33712][postmaster] LOG:  background worker "logical replication launcher" (PID 33723) exited with exit code 1
2024-03-08 04:46:48.912 UTC [33717][checkpointer] LOG:  shutting down
2024-03-08 04:46:48.912 UTC [33717][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:48.913 UTC [33717][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196968, redo lsn=0/4196968
2024-03-08 04:46:48.920 UTC [33712][postmaster] LOG:  database system is shut down
2024-03-08 04:46:48.994 UTC [33757][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:48.994 UTC [33757][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:48.994 UTC [33757][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:49.006 UTC [33764][startup] LOG:  database system was shut down at 2024-03-08 04:46:48 UTC
2024-03-08 04:46:49.022 UTC [33757][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:49.068 UTC [33776][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46854
2024-03-08 04:46:49.087 UTC [33776][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:49.087 UTC [33776][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:49.132 UTC [33776][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$
2024-03-08 04:46:49.139 UTC [33776][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.074 user=ssltestuser database=trustdb host=localhost port=46854
2024-03-08 04:46:49.163 UTC [33802][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46864
2024-03-08 04:46:49.169 UTC [33802][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-08 04:46:49.204 UTC [33810][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46874
2024-03-08 04:46:49.213 UTC [33810][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-08 04:46:49.252 UTC [33819][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46890
2024-03-08 04:46:49.269 UTC [33819][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:49.269 UTC [33819][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:49.271 UTC [33819][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-08 04:46:49.281 UTC [33819][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=ssltestuser database=trustdb host=localhost port=46890
2024-03-08 04:46:49.314 UTC [33829][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46902
2024-03-08 04:46:49.322 UTC [33829][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:49.322 UTC [33829][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.2, cipher=ECDHE-RSA-AES256-GCM-SHA384, bits=256)
2024-03-08 04:46:49.324 UTC [33829][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require ssl_min_protocol_version=TLSv1.2 ssl_max_protocol_version=TLSv1.2$$
2024-03-08 04:46:49.328 UTC [33829][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=46902
2024-03-08 04:46:49.461 UTC [33840][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46910
2024-03-08 04:46:49.468 UTC [33840][client backend] [[unknown]][3/1:0] FATAL:  connection requires a valid client certificate
2024-03-08 04:46:49.519 UTC [33846][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46924
2024-03-08 04:46:49.539 UTC [33846][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-08 04:46:49.539 UTC [33846][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:49.556 UTC [33846][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_PiY7/client.key$$
2024-03-08 04:46:49.563 UTC [33846][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.049 user=ssltestuser database=certdb host=localhost port=46924
2024-03-08 04:46:49.583 UTC [33857][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46930
2024-03-08 04:46:49.601 UTC [33857][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-08 04:46:49.601 UTC [33857][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:49.604 UTC [33857][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_PiY7/client-der.key$$
2024-03-08 04:46:49.607 UTC [33857][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=certdb host=localhost port=46930
2024-03-08 04:46:49.649 UTC [33868][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46946
2024-03-08 04:46:49.660 UTC [33868][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-08 04:46:49.660 UTC [33868][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:49.665 UTC [33868][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_PiY7/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$
2024-03-08 04:46:49.665 UTC [33868][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.017 user=ssltestuser database=certdb host=localhost port=46946
2024-03-08 04:46:49.717 UTC [33874][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46960
2024-03-08 04:46:49.726 UTC [33874][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-08 04:46:49.726 UTC [33874][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:49.728 UTC [33874][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_PiY7/client-encrypted-der.key sslpassword='dUmmyP^#+'$$
2024-03-08 04:46:49.733 UTC [33874][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=ssltestuser database=certdb host=localhost port=46960
2024-03-08 04:46:49.763 UTC [33878][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46964
2024-03-08 04:46:49.782 UTC [33878][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-08 04:46:49.782 UTC [33878][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:49.784 UTC [33878][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcertmode=require sslcert=ssl/client.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_PiY7/client.key$$
2024-03-08 04:46:49.792 UTC [33878][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=ssltestuser database=certdb host=localhost port=46964
2024-03-08 04:46:49.816 UTC [33882][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46972
2024-03-08 04:46:49.833 UTC [33882][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-08 04:46:49.833 UTC [33882][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:49.835 UTC [33882][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcertmode=allow sslcert=ssl/client.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_PiY7/client.key$$
2024-03-08 04:46:49.835 UTC [33882][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=ssltestuser database=certdb host=localhost port=46972
2024-03-08 04:46:49.864 UTC [33892][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46982
2024-03-08 04:46:49.871 UTC [33892][client backend] [[unknown]][10/1:0] FATAL:  connection requires a valid client certificate
2024-03-08 04:46:49.908 UTC [33902][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=46996
2024-03-08 04:46:49.917 UTC [33902][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-08 04:46:49.949 UTC [33914][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47008
2024-03-08 04:46:49.957 UTC [33914][client backend] [[unknown]][11/1:0] LOG:  connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:8)
2024-03-08 04:46:49.957 UTC [33914][client backend] [[unknown]][11/1:0] LOG:  connection authorized: user=ssltestuser database=certdb_dn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:49.975 UTC [33914][client backend] [001_ssltests.pl][11/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost dbname=certdb_dn user=ssltestuser sslcert=ssl/client-dn.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_PiY7/client-dn.key$$
2024-03-08 04:46:49.981 UTC [33914][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.041 user=ssltestuser database=certdb_dn host=localhost port=47008
2024-03-08 04:46:50.007 UTC [33926][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47018
2024-03-08 04:46:50.016 UTC [33926][client backend] [[unknown]][12/1:0] LOG:  connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:9)
2024-03-08 04:46:50.016 UTC [33926][client backend] [[unknown]][12/1:0] LOG:  connection authorized: user=ssltestuser database=certdb_dn_re application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:50.029 UTC [33926][client backend] [001_ssltests.pl][12/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost dbname=certdb_dn_re user=ssltestuser sslcert=ssl/client-dn.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_PiY7/client-dn.key$$
2024-03-08 04:46:50.034 UTC [33926][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=ssltestuser database=certdb_dn_re host=localhost port=47018
2024-03-08 04:46:50.060 UTC [33932][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47024
2024-03-08 04:46:50.078 UTC [33932][client backend] [[unknown]][13/1:0] LOG:  connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:10)
2024-03-08 04:46:50.078 UTC [33932][client backend] [[unknown]][13/1:0] LOG:  connection authorized: user=ssltestuser database=certdb_cn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:50.103 UTC [33932][client backend] [001_ssltests.pl][13/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost dbname=certdb_cn user=ssltestuser sslcert=ssl/client-dn.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_PiY7/client-dn.key$$
2024-03-08 04:46:50.109 UTC [33932][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.052 user=ssltestuser database=certdb_cn host=localhost port=47024
2024-03-08 04:46:50.148 UTC [33944][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47028
2024-03-08 04:46:50.169 UTC [33944][client backend] [[unknown]][14/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-08 04:46:50.169 UTC [33944][client backend] [[unknown]][14/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:50.171 UTC [33944][client backend] [001_ssltests.pl][14/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-08 04:46:50.178 UTC [33944][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.033 user=ssltestuser database=certdb host=localhost port=47028
2024-03-08 04:46:50.208 UTC [33955][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47044
2024-03-08 04:46:50.210 UTC [33955][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-08 04:46:50.244 UTC [33962][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47060
2024-03-08 04:46:50.253 UTC [33962][client backend] [[unknown]][15/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-08 04:46:50.253 UTC [33962][client backend] [[unknown]][15/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-08 04:46:50.253 UTC [33962][client backend] [[unknown]][15/1:0] FATAL:  certificate authentication failed for user "anotheruser"
2024-03-08 04:46:50.253 UTC [33962][client backend] [[unknown]][15/1:0] DETAIL:  Connection matched file "/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf" line 7: "hostssl certdb          all             127.0.0.1/32            cert"
2024-03-08 04:46:50.297 UTC [33972][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47072
2024-03-08 04:46:50.310 UTC [33972][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-08 04:46:50.310 UTC [33972][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: certificate revoked.
	Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-08 04:46:50.341 UTC [33983][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47084
2024-03-08 04:46:50.356 UTC [33983][client backend] [[unknown]][16/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:4)
2024-03-08 04:46:50.356 UTC [33983][client backend] [[unknown]][16/1:0] LOG:  connection authorized: user=ssltestuser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:50.379 UTC [33983][client backend] [001_ssltests.pl][16/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=verifydb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_PiY7/client.key$$
2024-03-08 04:46:50.386 UTC [33983][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.046 user=ssltestuser database=verifydb host=localhost port=47084
2024-03-08 04:46:50.412 UTC [33995][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47086
2024-03-08 04:46:50.428 UTC [33995][client backend] [[unknown]][17/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-08 04:46:50.428 UTC [33995][client backend] [[unknown]][17/1:0] LOG:  certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch
2024-03-08 04:46:50.428 UTC [33995][client backend] [[unknown]][17/1:0] FATAL:  "trust" authentication failed for user "anotheruser"
2024-03-08 04:46:50.428 UTC [33995][client backend] [[unknown]][17/1:0] DETAIL:  Connection matched file "/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf" line 5: "hostssl verifydb        anotheruser     127.0.0.1/32            trust        clientcert=verify-full"
2024-03-08 04:46:50.461 UTC [34002][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47100
2024-03-08 04:46:50.469 UTC [34002][client backend] [[unknown]][18/1:0] LOG:  connection authenticated: user="yetanotheruser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:6)
2024-03-08 04:46:50.469 UTC [34002][client backend] [[unknown]][18/1:0] LOG:  connection authorized: user=yetanotheruser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:50.480 UTC [34002][client backend] [001_ssltests.pl][18/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=verifydb hostaddr=127.0.0.1 host=localhost user=yetanotheruser sslcert=ssl/client.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_PiY7/client.key$$
2024-03-08 04:46:50.483 UTC [34002][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.022 user=yetanotheruser database=verifydb host=localhost port=47100
2024-03-08 04:46:50.490 UTC [33757][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:50.490 UTC [33757][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:50.501 UTC [33757][postmaster] LOG:  background worker "logical replication launcher" (PID 33771) exited with exit code 1
2024-03-08 04:46:50.501 UTC [33762][checkpointer] LOG:  shutting down
2024-03-08 04:46:50.501 UTC [33762][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:50.502 UTC [33762][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41969E0, redo lsn=0/41969E0
2024-03-08 04:46:50.508 UTC [33757][postmaster] LOG:  database system is shut down
2024-03-08 04:46:50.627 UTC [34031][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:50.627 UTC [34031][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:50.627 UTC [34031][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:50.634 UTC [34039][startup] LOG:  database system was shut down at 2024-03-08 04:46:50 UTC
2024-03-08 04:46:50.648 UTC [34031][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:50.708 UTC [34058][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47116
2024-03-08 04:46:50.718 UTC [34058][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-08 04:46:50.718 UTC [34058][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:50.744 UTC [34058][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=certdb  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_PiY7/client.key sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 host=localhost sslmode=require sslcert=ssl/client+client_ca.crt$$
2024-03-08 04:46:50.747 UTC [34058][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.039 user=ssltestuser database=certdb host=localhost port=47116
2024-03-08 04:46:50.780 UTC [34069][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47120
2024-03-08 04:46:50.793 UTC [34069][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-08 04:46:50.793 UTC [34069][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: unable to get local issuer certificate.
	Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921344, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-08 04:46:51.327 UTC [34150][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47134
2024-03-08 04:46:51.337 UTC [34150][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-08 04:46:51.337 UTC [34150][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: unable to get local issuer certificate.
	Failed certificate data (unverified): subject ".../CN=ssl-123456789012345678901234567890123456789012345678901234567890", serial number 2315702411956921348, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-08 04:46:51.358 UTC [34031][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:51.358 UTC [34031][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:51.365 UTC [34031][postmaster] LOG:  background worker "logical replication launcher" (PID 34045) exited with exit code 1
2024-03-08 04:46:51.370 UTC [34037][checkpointer] LOG:  shutting down
2024-03-08 04:46:51.370 UTC [34037][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:51.371 UTC [34037][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196A58, redo lsn=0/4196A58
2024-03-08 04:46:51.379 UTC [34031][postmaster] LOG:  database system is shut down
2024-03-08 04:46:51.474 UTC [34158][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:51.474 UTC [34158][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:51.474 UTC [34158][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:51.486 UTC [34168][startup] LOG:  database system was shut down at 2024-03-08 04:46:51 UTC
2024-03-08 04:46:51.503 UTC [34158][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:51.619 UTC [34197][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47148
2024-03-08 04:46:51.631 UTC [34197][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-08 04:46:51.631 UTC [34197][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 1: unable to get local issuer certificate.
	Failed certificate data (unverified): subject "/CN=Test CA for PostgreSQL SSL regression test client certs", serial number 2315702411956921089, issuer "/CN=Test root CA for PostgreSQL SSL regression test suite".
2024-03-08 04:46:51.648 UTC [34158][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:51.648 UTC [34158][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:51.653 UTC [34158][postmaster] LOG:  background worker "logical replication launcher" (PID 34176) exited with exit code 1
2024-03-08 04:46:51.663 UTC [34165][checkpointer] LOG:  shutting down
2024-03-08 04:46:51.663 UTC [34165][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:51.663 UTC [34165][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196AD0, redo lsn=0/4196AD0
2024-03-08 04:46:51.670 UTC [34158][postmaster] LOG:  database system is shut down
2024-03-08 04:46:51.822 UTC [34211][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:51.822 UTC [34211][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:51.822 UTC [34211][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:51.842 UTC [34215][startup] LOG:  database system was shut down at 2024-03-08 04:46:51 UTC
2024-03-08 04:46:51.867 UTC [34211][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:51.984 UTC [34230][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47160
2024-03-08 04:46:51.993 UTC [34230][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-08 04:46:51.993 UTC [34230][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: certificate revoked.
	Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-08 04:46:52.041 UTC [34245][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47162
2024-03-08 04:46:52.050 UTC [34245][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-08 04:46:52.050 UTC [34245][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: certificate revoked.
	Failed certificate data (unverified): subject "/CN=\xce\x9f\xce\xb4\xcf\x85\xcf\x83\xcf\x83\xce\xad\xce\xb1\xcf\x82", serial number 2315702411956921349, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-08 04:46:52.086 UTC [34211][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:52.086 UTC [34211][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:52.100 UTC [34211][postmaster] LOG:  background worker "logical replication launcher" (PID 34222) exited with exit code 1
2024-03-08 04:46:52.100 UTC [34213][checkpointer] LOG:  shutting down
2024-03-08 04:46:52.100 UTC [34213][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:52.101 UTC [34213][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196B48, redo lsn=0/4196B48
2024-03-08 04:46:52.109 UTC [34211][postmaster] LOG:  database system is shut down
2024-03-08 04:46:52.255 UTC [34262][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:52.255 UTC [34262][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:52.255 UTC [34262][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:52.262 UTC [34271][startup] LOG:  database system was shut down at 2024-03-08 04:46:52 UTC
2024-03-08 04:46:52.270 UTC [34262][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:52.322 UTC [34282][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47168
2024-03-08 04:46:52.325 UTC [34282][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-08 04:46:52.325 UTC [34282][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-08 04:46:52.362 UTC [34293][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47180
2024-03-08 04:46:52.370 UTC [34293][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:52.370 UTC [34293][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:52.396 UTC [34293][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-08 04:46:52.401 UTC [34293][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.043 user=ssltestuser database=trustdb host=localhost port=47180
2024-03-08 04:46:52.410 UTC [34262][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:52.410 UTC [34262][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:52.420 UTC [34262][postmaster] LOG:  background worker "logical replication launcher" (PID 34276) exited with exit code 1
2024-03-08 04:46:52.420 UTC [34269][checkpointer] LOG:  shutting down
2024-03-08 04:46:52.420 UTC [34269][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:52.420 UTC [34269][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196BC0, redo lsn=0/4196BC0
2024-03-08 04:46:52.427 UTC [34262][postmaster] LOG:  database system is shut down
2024-03-08 04:46:52.551 UTC [34324][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:52.551 UTC [34324][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:52.551 UTC [34324][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:52.568 UTC [34333][startup] LOG:  database system was shut down at 2024-03-08 04:46:52 UTC
2024-03-08 04:46:52.575 UTC [34324][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:52.651 UTC [34349][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47196
2024-03-08 04:46:52.657 UTC [34349][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-08 04:46:52.657 UTC [34349][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-08 04:46:52.671 UTC [34349][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert internal error
2024-03-08 04:46:52.688 UTC [34363][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47210
2024-03-08 04:46:52.699 UTC [34363][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:52.699 UTC [34363][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:52.717 UTC [34363][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-08 04:46:52.720 UTC [34363][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.034 user=ssltestuser database=trustdb host=localhost port=47210
2024-03-08 04:46:52.730 UTC [34324][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:52.730 UTC [34324][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:52.746 UTC [34324][postmaster] LOG:  background worker "logical replication launcher" (PID 34337) exited with exit code 1
2024-03-08 04:46:52.746 UTC [34329][checkpointer] LOG:  shutting down
2024-03-08 04:46:52.746 UTC [34329][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:52.747 UTC [34329][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196C38, redo lsn=0/4196C38
2024-03-08 04:46:52.753 UTC [34324][postmaster] LOG:  database system is shut down
2024-03-08 04:46:52.883 UTC [34410][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:52.883 UTC [34410][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:52.883 UTC [34410][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:52.898 UTC [34425][startup] LOG:  database system was shut down at 2024-03-08 04:46:52 UTC
2024-03-08 04:46:52.905 UTC [34410][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:52.960 UTC [34444][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47218
2024-03-08 04:46:52.963 UTC [34444][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-08 04:46:52.963 UTC [34444][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-08 04:46:52.994 UTC [34449][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47220
2024-03-08 04:46:53.001 UTC [34449][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:53.001 UTC [34449][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:53.014 UTC [34449][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-08 04:46:53.021 UTC [34449][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=ssltestuser database=trustdb host=localhost port=47220
2024-03-08 04:46:53.032 UTC [34410][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:53.032 UTC [34410][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:53.040 UTC [34410][postmaster] LOG:  background worker "logical replication launcher" (PID 34434) exited with exit code 1
2024-03-08 04:46:53.040 UTC [34422][checkpointer] LOG:  shutting down
2024-03-08 04:46:53.040 UTC [34422][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:53.040 UTC [34422][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196CB0, redo lsn=0/4196CB0
2024-03-08 04:46:53.046 UTC [34410][postmaster] LOG:  database system is shut down
2024-03-08 04:46:53.165 UTC [34480][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:53.165 UTC [34480][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:53.165 UTC [34480][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:53.187 UTC [34488][startup] LOG:  database system was shut down at 2024-03-08 04:46:53 UTC
2024-03-08 04:46:53.195 UTC [34480][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:53.279 UTC [34502][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47226
2024-03-08 04:46:53.283 UTC [34502][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-08 04:46:53.283 UTC [34502][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-08 04:46:53.288 UTC [34502][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert internal error
2024-03-08 04:46:53.315 UTC [34513][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47230
2024-03-08 04:46:53.334 UTC [34513][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:53.334 UTC [34513][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:53.350 UTC [34513][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-08 04:46:53.353 UTC [34513][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.039 user=ssltestuser database=trustdb host=localhost port=47230
2024-03-08 04:46:53.383 UTC [34480][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:53.383 UTC [34480][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:53.391 UTC [34480][postmaster] LOG:  background worker "logical replication launcher" (PID 34492) exited with exit code 1
2024-03-08 04:46:53.391 UTC [34485][checkpointer] LOG:  shutting down
2024-03-08 04:46:53.391 UTC [34485][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:53.392 UTC [34485][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196D28, redo lsn=0/4196D28
2024-03-08 04:46:53.398 UTC [34480][postmaster] LOG:  database system is shut down
2024-03-08 04:46:53.565 UTC [34541][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:53.565 UTC [34541][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:53.565 UTC [34541][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:53.589 UTC [34566][startup] LOG:  database system was shut down at 2024-03-08 04:46:53 UTC
2024-03-08 04:46:53.603 UTC [34541][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:53.739 UTC [34593][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47232
2024-03-08 04:46:53.744 UTC [34593][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-08 04:46:53.744 UTC [34593][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-08 04:46:53.881 UTC [34604][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47238
2024-03-08 04:46:53.898 UTC [34604][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:53.898 UTC [34604][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:53.942 UTC [34604][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-08 04:46:53.951 UTC [34604][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.076 user=ssltestuser database=trustdb host=localhost port=47238
2024-03-08 04:46:53.967 UTC [34541][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:53.967 UTC [34541][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:53.978 UTC [34541][postmaster] LOG:  background worker "logical replication launcher" (PID 34573) exited with exit code 1
2024-03-08 04:46:53.978 UTC [34563][checkpointer] LOG:  shutting down
2024-03-08 04:46:53.978 UTC [34563][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:53.979 UTC [34563][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196DA0, redo lsn=0/4196DA0
2024-03-08 04:46:53.986 UTC [34541][postmaster] LOG:  database system is shut down
2024-03-08 04:46:54.154 UTC [34631][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:54.155 UTC [34631][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:54.155 UTC [34631][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:54.181 UTC [34649][startup] LOG:  database system was shut down at 2024-03-08 04:46:53 UTC
2024-03-08 04:46:54.191 UTC [34631][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:54.326 UTC [34667][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47252
2024-03-08 04:46:54.335 UTC [34667][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-08 04:46:54.335 UTC [34667][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-08 04:46:54.371 UTC [34678][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47256
2024-03-08 04:46:54.378 UTC [34678][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:54.378 UTC [34678][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:54.403 UTC [34678][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-08 04:46:54.410 UTC [34678][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.040 user=ssltestuser database=trustdb host=localhost port=47256
2024-03-08 04:46:54.432 UTC [34631][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:54.432 UTC [34631][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:54.445 UTC [34631][postmaster] LOG:  background worker "logical replication launcher" (PID 34654) exited with exit code 1
2024-03-08 04:46:54.445 UTC [34645][checkpointer] LOG:  shutting down
2024-03-08 04:46:54.445 UTC [34645][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:54.446 UTC [34645][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196E18, redo lsn=0/4196E18
2024-03-08 04:46:54.452 UTC [34631][postmaster] LOG:  database system is shut down
2024-03-08 04:46:54.567 UTC [34705][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:54.567 UTC [34705][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:54.567 UTC [34705][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:54.587 UTC [34717][startup] LOG:  database system was shut down at 2024-03-08 04:46:54 UTC
2024-03-08 04:46:54.596 UTC [34705][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:54.645 UTC [34742][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47268
2024-03-08 04:46:54.647 UTC [34742][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-08 04:46:54.647 UTC [34742][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-08 04:46:54.692 UTC [34754][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47280
2024-03-08 04:46:54.704 UTC [34754][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:54.704 UTC [34754][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:54.748 UTC [34754][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-08 04:46:54.755 UTC [34754][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.064 user=ssltestuser database=trustdb host=localhost port=47280
2024-03-08 04:46:54.763 UTC [34705][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:54.763 UTC [34705][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:54.767 UTC [34705][postmaster] LOG:  background worker "logical replication launcher" (PID 34725) exited with exit code 1
2024-03-08 04:46:54.776 UTC [34715][checkpointer] LOG:  shutting down
2024-03-08 04:46:54.776 UTC [34715][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:54.777 UTC [34715][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196E90, redo lsn=0/4196E90
2024-03-08 04:46:54.783 UTC [34705][postmaster] LOG:  database system is shut down
2024-03-08 04:46:54.934 UTC [34798][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:54.934 UTC [34798][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:54.934 UTC [34798][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:54.952 UTC [34811][startup] LOG:  database system was shut down at 2024-03-08 04:46:54 UTC
2024-03-08 04:46:54.966 UTC [34798][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:54.993 UTC [34825][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47286
2024-03-08 04:46:54.995 UTC [34825][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-08 04:46:54.995 UTC [34825][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-08 04:46:55.054 UTC [34833][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47298
2024-03-08 04:46:55.061 UTC [34833][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:55.061 UTC [34833][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:55.089 UTC [34833][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-08 04:46:55.094 UTC [34833][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.043 user=ssltestuser database=trustdb host=localhost port=47298
2024-03-08 04:46:55.100 UTC [34798][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:55.100 UTC [34798][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:55.106 UTC [34798][postmaster] LOG:  background worker "logical replication launcher" (PID 34820) exited with exit code 1
2024-03-08 04:46:55.107 UTC [34809][checkpointer] LOG:  shutting down
2024-03-08 04:46:55.107 UTC [34809][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:55.107 UTC [34809][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196F08, redo lsn=0/4196F08
2024-03-08 04:46:55.113 UTC [34798][postmaster] LOG:  database system is shut down
2024-03-08 04:46:55.236 UTC [34867][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-08 04:46:55.236 UTC [34867][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63513
2024-03-08 04:46:55.236 UTC [34867][postmaster] LOG:  listening on Unix socket "/tmp/dRGb9XZK2m/.s.PGSQL.63513"
2024-03-08 04:46:55.262 UTC [34872][startup] LOG:  database system was shut down at 2024-03-08 04:46:55 UTC
2024-03-08 04:46:55.270 UTC [34867][postmaster] LOG:  database system is ready to accept connections
2024-03-08 04:46:55.326 UTC [34894][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47306
2024-03-08 04:46:55.329 UTC [34894][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-08 04:46:55.329 UTC [34894][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-08 04:46:55.336 UTC [34894][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert internal error
2024-03-08 04:46:55.359 UTC [34904][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=47318
2024-03-08 04:46:55.365 UTC [34904][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-08 04:46:55.365 UTC [34904][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-08 04:46:55.378 UTC [34904][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-08 04:46:55.383 UTC [34904][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=trustdb host=localhost port=47318
2024-03-08 04:46:55.394 UTC [34867][postmaster] LOG:  received fast shutdown request
2024-03-08 04:46:55.394 UTC [34867][postmaster] LOG:  aborting any active transactions
2024-03-08 04:46:55.399 UTC [34867][postmaster] LOG:  background worker "logical replication launcher" (PID 34875) exited with exit code 1
2024-03-08 04:46:55.404 UTC [34870][checkpointer] LOG:  shutting down
2024-03-08 04:46:55.404 UTC [34870][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-08 04:46:55.404 UTC [34870][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4196F80, redo lsn=0/4196F80
2024-03-08 04:46:55.410 UTC [34867][postmaster] LOG:  database system is shut down
2024-03-08 04:46:55.540 UTC [34937][postmaster] FATAL:  could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory
2024-03-08 04:46:55.540 UTC [34937][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[04:46:55.596](0.215s) Bail out!  pg_ctl restart failed
# No postmaster PID for node "primary"