[07:38:02.706](0.037s) # setting up data directory
# Checking port 56269
# Found port 56269
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=56269 host=/tmp/7aj4gFw1rW
Log file: /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log
[07:38:02.711](0.005s) # 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 30165
[07:38:03.035](0.324s) 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 30498
[07:38:05.157](2.122s) # 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-12 07:38:02.949 UTC [30165][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-12 07:38:02.949 UTC [30165][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:02.958 UTC [30183][startup] LOG:  database system was shut down at 2024-03-12 07:36:03 UTC
2024-03-12 07:38:02.966 UTC [30165][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:03.005 UTC [30196][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.006 UTC [30196][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-12 07:38:03.006 UTC [30196][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.031 UTC [30196][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-12 07:38:03.036 UTC [30196][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.064 UTC [30210][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.065 UTC [30210][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-12 07:38:03.065 UTC [30210][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.067 UTC [30210][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-12 07:38:03.069 UTC [30210][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.117 UTC [30223][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.118 UTC [30223][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-12 07:38:03.118 UTC [30223][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.120 UTC [30223][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-12 07:38:03.122 UTC [30223][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.169 UTC [30236][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.170 UTC [30236][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-12 07:38:03.170 UTC [30236][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.174 UTC [30236][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-12 07:38:03.177 UTC [30236][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.219 UTC [30248][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.219 UTC [30248][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-12 07:38:03.219 UTC [30248][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.223 UTC [30248][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-12 07:38:03.226 UTC [30248][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.254 UTC [30252][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.255 UTC [30252][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-12 07:38:03.255 UTC [30252][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.257 UTC [30252][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-12 07:38:03.343 UTC [30252][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.091 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.400 UTC [30270][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.401 UTC [30270][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-12 07:38:03.401 UTC [30270][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.402 UTC [30270][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-12 07:38:03.521 UTC [30270][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.128 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.712 UTC [30313][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.713 UTC [30313][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-12 07:38:03.713 UTC [30313][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.715 UTC [30313][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-12 07:38:03.798 UTC [30313][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.093 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.813 UTC [30325][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.814 UTC [30325][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-12 07:38:03.814 UTC [30325][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.815 UTC [30325][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-12 07:38:04.089 UTC [30325][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.276 user=postgres database=postgres host=[local]
2024-03-12 07:38:04.443 UTC [30413][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:04.449 UTC [30413][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-12 07:38:04.449 UTC [30413][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:04.457 UTC [30413][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-12 07:38:04.506 UTC [30413][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.064 user=postgres database=postgres host=[local]
2024-03-12 07:38:04.588 UTC [30430][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:04.589 UTC [30430][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-12 07:38:04.589 UTC [30430][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:04.592 UTC [30430][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-12 07:38:04.711 UTC [30430][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.129 user=postgres database=postgres host=[local]
2024-03-12 07:38:04.851 UTC [30165][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:04.851 UTC [30165][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:04.855 UTC [30165][postmaster] LOG:  background worker "logical replication launcher" (PID 30190) exited with exit code 1
2024-03-12 07:38:04.858 UTC [30181][checkpointer] LOG:  shutting down
2024-03-12 07:38:04.858 UTC [30181][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:04.947 UTC [30181][checkpointer] LOG:  checkpoint complete: wrote 5650 buffers (34.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.088 s, sync=0.001 s, total=0.089 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45479 kB, estimate=45479 kB; lsn=0/41961F0, redo lsn=0/41961F0
2024-03-12 07:38:04.979 UTC [30165][postmaster] LOG:  database system is shut down
2024-03-12 07:38:05.103 UTC [30498][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-12 07:38:05.103 UTC [30498][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:05.103 UTC [30498][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:05.132 UTC [30511][startup] LOG:  database system was shut down at 2024-03-12 07:38:04 UTC
2024-03-12 07:38:05.144 UTC [30498][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:05.190 UTC [30498][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:05.190 UTC [30498][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:05.195 UTC [30498][postmaster] LOG:  background worker "logical replication launcher" (PID 30516) exited with exit code 1
2024-03-12 07:38:05.198 UTC [30509][checkpointer] LOG:  shutting down
2024-03-12 07:38:05.198 UTC [30509][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:05.199 UTC [30509][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/4196268, redo lsn=0/4196268
2024-03-12 07:38:05.205 UTC [30498][postmaster] LOG:  database system is shut down
2024-03-12 07:38:05.351 UTC [30529][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-12 07:38:05.352 UTC [30529][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[07:38:05.393](0.236s) 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 30549
[07:38:05.506](0.113s) 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-12 07:38:02.949 UTC [30165][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-12 07:38:02.949 UTC [30165][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:02.958 UTC [30183][startup] LOG:  database system was shut down at 2024-03-12 07:36:03 UTC
2024-03-12 07:38:02.966 UTC [30165][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:03.005 UTC [30196][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.006 UTC [30196][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-12 07:38:03.006 UTC [30196][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.031 UTC [30196][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-12 07:38:03.036 UTC [30196][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.064 UTC [30210][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.065 UTC [30210][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-12 07:38:03.065 UTC [30210][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.067 UTC [30210][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-12 07:38:03.069 UTC [30210][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.117 UTC [30223][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.118 UTC [30223][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-12 07:38:03.118 UTC [30223][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.120 UTC [30223][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-12 07:38:03.122 UTC [30223][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.169 UTC [30236][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.170 UTC [30236][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-12 07:38:03.170 UTC [30236][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.174 UTC [30236][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-12 07:38:03.177 UTC [30236][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.219 UTC [30248][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.219 UTC [30248][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-12 07:38:03.219 UTC [30248][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.223 UTC [30248][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-12 07:38:03.226 UTC [30248][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.254 UTC [30252][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.255 UTC [30252][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-12 07:38:03.255 UTC [30252][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.257 UTC [30252][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-12 07:38:03.343 UTC [30252][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.091 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.400 UTC [30270][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.401 UTC [30270][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-12 07:38:03.401 UTC [30270][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.402 UTC [30270][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-12 07:38:03.521 UTC [30270][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.128 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.712 UTC [30313][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.713 UTC [30313][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-12 07:38:03.713 UTC [30313][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.715 UTC [30313][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-12 07:38:03.798 UTC [30313][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.093 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.813 UTC [30325][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.814 UTC [30325][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-12 07:38:03.814 UTC [30325][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.815 UTC [30325][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-12 07:38:04.089 UTC [30325][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.276 user=postgres database=postgres host=[local]
2024-03-12 07:38:04.443 UTC [30413][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:04.449 UTC [30413][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-12 07:38:04.449 UTC [30413][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:04.457 UTC [30413][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-12 07:38:04.506 UTC [30413][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.064 user=postgres database=postgres host=[local]
2024-03-12 07:38:04.588 UTC [30430][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:04.589 UTC [30430][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-12 07:38:04.589 UTC [30430][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:04.592 UTC [30430][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-12 07:38:04.711 UTC [30430][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.129 user=postgres database=postgres host=[local]
2024-03-12 07:38:04.851 UTC [30165][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:04.851 UTC [30165][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:04.855 UTC [30165][postmaster] LOG:  background worker "logical replication launcher" (PID 30190) exited with exit code 1
2024-03-12 07:38:04.858 UTC [30181][checkpointer] LOG:  shutting down
2024-03-12 07:38:04.858 UTC [30181][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:04.947 UTC [30181][checkpointer] LOG:  checkpoint complete: wrote 5650 buffers (34.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.088 s, sync=0.001 s, total=0.089 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45479 kB, estimate=45479 kB; lsn=0/41961F0, redo lsn=0/41961F0
2024-03-12 07:38:04.979 UTC [30165][postmaster] LOG:  database system is shut down
2024-03-12 07:38:05.103 UTC [30498][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-12 07:38:05.103 UTC [30498][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:05.103 UTC [30498][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:05.132 UTC [30511][startup] LOG:  database system was shut down at 2024-03-12 07:38:04 UTC
2024-03-12 07:38:05.144 UTC [30498][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:05.190 UTC [30498][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:05.190 UTC [30498][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:05.195 UTC [30498][postmaster] LOG:  background worker "logical replication launcher" (PID 30516) exited with exit code 1
2024-03-12 07:38:05.198 UTC [30509][checkpointer] LOG:  shutting down
2024-03-12 07:38:05.198 UTC [30509][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:05.199 UTC [30509][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/4196268, redo lsn=0/4196268
2024-03-12 07:38:05.205 UTC [30498][postmaster] LOG:  database system is shut down
2024-03-12 07:38:05.351 UTC [30529][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-12 07:38:05.352 UTC [30529][postmaster] LOG:  database system is shut down
2024-03-12 07:38:05.464 UTC [30549][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-12 07:38:05.464 UTC [30549][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:05.464 UTC [30549][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:05.482 UTC [30557][startup] LOG:  database system was shut down at 2024-03-12 07:38:05 UTC
2024-03-12 07:38:05.494 UTC [30549][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:05.515 UTC [30549][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:05.515 UTC [30549][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:05.520 UTC [30549][postmaster] LOG:  background worker "logical replication launcher" (PID 30562) exited with exit code 1
2024-03-12 07:38:05.526 UTC [30553][checkpointer] LOG:  shutting down
2024-03-12 07:38:05.526 UTC [30553][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:05.526 UTC [30553][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/41962E0, redo lsn=0/41962E0
2024-03-12 07:38:05.533 UTC [30549][postmaster] LOG:  database system is shut down
2024-03-12 07:38:05.666 UTC [30577][postmaster] FATAL:  could not set SSL protocol version range
2024-03-12 07:38:05.666 UTC [30577][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-12 07:38:05.666 UTC [30577][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[07:38:05.718](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 30593
[07:38:05.833](0.115s) ok 5 - restart succeeds with correct SSL protocol bounds
[07:38:05.833](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 30635
[07:38:06.204](0.371s) ok 6 - server doesn't accept non-SSL connections
[07:38:06.205](0.000s) ok 7 - server doesn't accept non-SSL connections: matches
[07:38:06.297](0.092s) ok 8 - connect without server root cert sslmode=require
[07:38:06.297](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr
[07:38:06.351](0.054s) ok 10 - connect without server root cert sslmode=verify-ca
[07:38:06.351](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches
[07:38:06.384](0.033s) ok 12 - connect without server root cert sslmode=verify-full
[07:38:06.385](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches
[07:38:06.461](0.077s) ok 14 - connect with wrong server root cert sslmode=require
[07:38:06.461](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches
[07:38:06.509](0.047s) ok 16 - connect with wrong server root cert sslmode=verify-ca
[07:38:06.509](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches
[07:38:06.533](0.024s) ok 18 - connect with wrong server root cert sslmode=verify-full
[07:38:06.533](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches
[07:38:06.566](0.033s) ok 20 - connect with server CA cert, without root CA
[07:38:06.567](0.000s) ok 21 - connect with server CA cert, without root CA: matches
[07:38:06.655](0.088s) ok 22 - connect with correct server CA cert file sslmode=require
[07:38:06.655](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr
[07:38:06.716](0.061s) ok 24 - connect with correct server CA cert file sslmode=verify-ca
[07:38:06.717](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr
[07:38:06.769](0.052s) ok 26 - connect with correct server CA cert file sslmode=verify-full
[07:38:06.769](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr
[07:38:06.821](0.052s) ok 28 - cert root file that contains two certificates, order 1
[07:38:06.821](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr
[07:38:06.863](0.041s) ok 30 - cert root file that contains two certificates, order 2
[07:38:06.863](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr
[07:38:06.909](0.046s) ok 32 - connect with sslcertmode=disable
[07:38:06.910](0.000s) ok 33 - connect with sslcertmode=disable: no stderr
[07:38:06.970](0.061s) ok 34 - connect with sslcertmode=allow
[07:38:06.974](0.003s) ok 35 - connect with sslcertmode=allow: no stderr
[07:38:07.054](0.081s) ok 36 - connect with sslcertmode=require fails without a client certificate
[07:38:07.055](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches
[07:38:07.102](0.047s) ok 38 - sslcrl option with invalid file name
[07:38:07.102](0.000s) ok 39 - sslcrl option with invalid file name: no stderr
[07:38:07.167](0.065s) ok 40 - CRL belonging to a different CA
[07:38:07.168](0.000s) ok 41 - CRL belonging to a different CA: matches
[07:38:07.211](0.043s) ok 42 - directory CRL belonging to a different CA
[07:38:07.211](0.000s) ok 43 - directory CRL belonging to a different CA: matches
[07:38:07.253](0.042s) ok 44 - CRL with a non-revoked cert
[07:38:07.253](0.000s) ok 45 - CRL with a non-revoked cert: no stderr
[07:38:07.317](0.064s) ok 46 - directory CRL with a non-revoked cert
[07:38:07.317](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr
[07:38:07.414](0.097s) ok 48 - mismatch between host name and server certificate sslmode=require
[07:38:07.414](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr
[07:38:07.473](0.059s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca
[07:38:07.473](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr
[07:38:07.527](0.054s) ok 52 - mismatch between host name and server certificate sslmode=verify-full
[07:38:07.527](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 30917
[07:38:07.901](0.373s) ok 54 - IP address in the Common Name
[07:38:07.901](0.000s) ok 55 - IP address in the Common Name: no stderr
[07:38:07.929](0.028s) ok 56 - mismatch between host name and server certificate IP address
[07:38:07.929](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 30989
[07:38:08.338](0.409s) ok 58 - IP address in a dNSName
[07:38:08.339](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 31071
[07:38:08.628](0.290s) ok 60 - host name matching with X.509 Subject Alternative Names 1
[07:38:08.629](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr
[07:38:08.711](0.083s) ok 62 - host name matching with X.509 Subject Alternative Names 2
[07:38:08.711](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr
[07:38:08.755](0.044s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard
[07:38:08.756](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr
[07:38:08.805](0.050s) ok 66 - host name not matching with X.509 Subject Alternative Names
[07:38:08.806](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches
[07:38:08.885](0.080s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard
[07:38:08.886](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 31164
[07:38:09.083](0.197s) ok 70 - host name matching with a single X.509 Subject Alternative Name
[07:38:09.083](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr
[07:38:09.136](0.053s) ok 72 - host name not matching with a single X.509 Subject Alternative Name
[07:38:09.136](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches
[07:38:09.183](0.047s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard
[07:38:09.184](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 31279
[07:38:09.478](0.294s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1)
[07:38:09.478](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr
[07:38:09.548](0.070s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1)
[07:38:09.548](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr
[07:38:09.628](0.080s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1)
[07:38:09.629](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches
[07:38:09.691](0.063s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2)
[07:38:09.692](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr
[07:38:09.755](0.063s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2)
[07:38:09.755](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr
[07:38:09.835](0.080s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2)
[07:38:09.835](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr
[07:38:09.874](0.039s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2)
[07:38:09.874](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches
[07:38:09.928](0.053s) ok 90 - IPv6 host with CIDR mask does not match
[07:38:09.928](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 31424
[07:38:10.223](0.295s) ok 92 - certificate with both a CN and SANs 1
[07:38:10.224](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr
[07:38:10.259](0.035s) ok 94 - certificate with both a CN and SANs 2
[07:38:10.259](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr
[07:38:10.310](0.052s) ok 96 - certificate with both a CN and SANs ignores CN
[07:38:10.311](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 31495
[07:38:10.752](0.441s) ok 98 - certificate with both a CN and IP SANs matches CN
[07:38:10.752](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr
[07:38:10.828](0.076s) ok 100 - certificate with both a CN and IP SANs matches SAN 1
[07:38:10.828](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr
[07:38:10.874](0.046s) ok 102 - certificate with both a CN and IP SANs matches SAN 2
[07:38:10.874](0.000s) 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 31591
[07:38:11.251](0.377s) ok 104 - certificate with both an IP CN and IP SANs 1
[07:38:11.251](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr
[07:38:11.317](0.066s) ok 106 - certificate with both an IP CN and IP SANs 2
[07:38:11.317](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr
[07:38:11.384](0.066s) ok 108 - certificate with both an IP CN and IP SANs ignores CN
[07:38:11.384](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 31713
[07:38:11.793](0.409s) ok 110 - certificate with both an IP CN and DNS SANs matches CN
[07:38:11.793](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr
[07:38:11.852](0.059s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1
[07:38:11.853](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr
[07:38:11.923](0.070s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2
[07:38:11.923](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 31831
[07:38:12.171](0.248s) ok 116 - server certificate without CN or SANs sslmode=verify-ca
[07:38:12.171](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr
[07:38:12.207](0.036s) ok 118 - server certificate without CN or SANs sslmode=verify-full
[07:38:12.207](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 31894
[07:38:12.443](0.236s) ok 120 - sslrootcert=system does not connect with private CA
[07:38:12.443](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches
[07:38:12.456](0.013s) ok 122 - sslrootcert=system only accepts sslmode=verify-full
[07:38:12.457](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches
[07:38:12.498](0.041s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE
[07:38:12.498](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr
[07:38:12.535](0.037s) ok 126 - sslrootcert=system defaults to sslmode=verify-full
[07:38:12.535](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 31939
[07:38:12.785](0.250s) ok 128 - connects without client-side CRL
[07:38:12.785](0.000s) ok 129 - connects without client-side CRL: no stderr
[07:38:12.831](0.046s) ok 130 - does not connect with client-side CRL file
[07:38:12.831](0.000s) ok 131 - does not connect with client-side CRL file: matches
[07:38:12.878](0.047s) ok 132 - does not connect with client-side CRL directory
[07:38:12.879](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()
[07:38:12.929](0.050s) ok 134 - pg_stat_ssl view without client certificate: exit code 0
[07:38:12.929](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr
[07:38:12.929](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches
[07:38:12.970](0.041s) ok 137 - connection success with correct range of TLS protocol versions
[07:38:12.970](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr
[07:38:13.003](0.032s) ok 139 - connection failure with incorrect range of TLS protocol versions
[07:38:13.003](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches
[07:38:13.034](0.031s) ok 141 - connection failure with an incorrect SSL protocol minimum bound
[07:38:13.034](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches
[07:38:13.054](0.020s) ok 143 - connection failure with an incorrect SSL protocol maximum bound
[07:38:13.054](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches
[07:38:13.054](0.000s) # running server tests
[07:38:13.122](0.067s) ok 145 - certificate authorization fails without client cert
[07:38:13.122](0.000s) ok 146 - certificate authorization fails without client cert: matches
[07:38:13.170](0.048s) ok 147 - certificate authorization succeeds with correct client cert in PEM format
[07:38:13.170](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr
[07:38:13.220](0.050s) ok 149 - certificate authorization succeeds with correct client cert in DER format
[07:38:13.221](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr
[07:38:13.280](0.059s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format
[07:38:13.280](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr
[07:38:13.321](0.041s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format
[07:38:13.321](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr
[07:38:13.372](0.051s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require
[07:38:13.373](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr
[07:38:13.441](0.069s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow
[07:38:13.442](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr
[07:38:13.513](0.071s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable
[07:38:13.513](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches
[07:38:13.553](0.040s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format
[07:38:13.554](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches
[07:38:13.644](0.090s) ok 163 - certificate authorization succeeds with DN mapping
[07:38:13.644](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr
[07:38:13.644](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches
[07:38:13.734](0.089s) ok 166 - certificate authorization succeeds with DN regex mapping
[07:38:13.734](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr
[07:38:13.790](0.056s) ok 168 - certificate authorization succeeds with CN mapping
[07:38:13.790](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr
[07:38:13.790](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches
[07:38:13.790](0.000s) not ok 171 # TODO & SKIP Need Pty support
[07:38:13.791](0.000s) not ok 172 # TODO & SKIP Need Pty support
[07:38:13.791](0.000s) not ok 173 # TODO & SKIP Need Pty support
[07:38:13.791](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_f2wT/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
[07:38:13.856](0.065s) ok 175 - pg_stat_ssl with client certificate: exit code 0
[07:38:13.856](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr
[07:38:13.856](0.000s) ok 177 - pg_stat_ssl with client certificate: matches
[07:38:13.892](0.036s) ok 178 - certificate authorization fails because of file permissions
[07:38:13.892](0.000s) ok 179 - certificate authorization fails because of file permissions: matches
[07:38:13.952](0.060s) ok 180 - certificate authorization fails with client cert belonging to another user
[07:38:13.953](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches
[07:38:13.984](0.032s) ok 182 - certificate authorization fails with revoked client cert
[07:38:13.985](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches
[07:38:13.985](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match
[07:38:14.022](0.037s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name
[07:38:14.023](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr
[07:38:14.023](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches
[07:38:14.069](0.046s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name
[07:38:14.069](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches
[07:38:14.069](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match
[07:38:14.121](0.052s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name
[07:38:14.121](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr
[07:38:14.122](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 32165
[07:38:14.491](0.369s) ok 194 - intermediate client certificate is provided by client
[07:38:14.491](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr
[07:38:14.531](0.040s) ok 196 - intermediate client certificate is missing
[07:38:14.532](0.000s) ok 197 - intermediate client certificate is missing: matches
[07:38:14.559](0.028s) ok 198 - logged client certificate Subjects are truncated if they're too long
[07:38:14.559](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 32237
[07:38:14.851](0.292s) ok 200 - intermediate client certificate is untrusted
[07:38:14.851](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 32284
[07:38:15.120](0.268s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory
[07:38:15.120](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches
[07:38:15.168](0.048s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory
[07:38:15.168](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 32323
[07:38:15.423](0.254s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1
[07:38:15.423](0.000s) 
[07:38:15.423](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1'
#   at t/001_ssltests.pl line 923.
[07:38:15.423](0.000s) #          got: '2'
#     expected: '0'
[07:38:15.424](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[07:38:15.424](0.000s) 
[07:38:15.424](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr'
#   at t/001_ssltests.pl line 923.
[07:38:15.424](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 56269 failed: SSL error: ocsp callback failure'
#     expected: ''
[07:38:15.502](0.078s) ok 208 - connect without requesting ocsp response when sslocspstapling=0
[07:38:15.502](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 32367
[07:38:15.774](0.272s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1
[07:38:15.837](0.063s) ok 211 - connect without requesting ocsp response when sslocspstapling=0
[07:38:15.837](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 32425
[07:38:16.108](0.270s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1
[07:38:16.178](0.070s) ok 214 - connect without requesting ocsp response when sslocspstapling=0
[07:38:16.178](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 32470
[07:38:16.437](0.260s) ok 216 - failed with an expired ocsp response when sslocspstapling=1
[07:38:16.499](0.062s) ok 217 - connect without requesting ocsp response when sslocspstapling=0
[07:38:16.499](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 32526
[07:38:16.838](0.339s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1
[07:38:16.838](0.000s) 
[07:38:16.838](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1'
#   at t/001_ssltests.pl line 1001.
[07:38:16.838](0.000s) #          got: '2'
#     expected: '0'
[07:38:16.839](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[07:38:16.839](0.000s) 
[07:38:16.839](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr'
#   at t/001_ssltests.pl line 1001.
[07:38:16.839](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 56269 failed: SSL error: ocsp callback failure'
#     expected: ''
[07:38:16.992](0.153s) ok 221 - connect without requesting ocsp response when sslocspstapling=0
[07:38:16.992](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 32613
[07:38:17.520](0.528s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1
[07:38:17.641](0.121s) ok 224 - connect without requesting ocsp response when sslocspstapling=0
[07:38:17.641](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 32658
[07:38:18.027](0.386s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1
[07:38:18.165](0.138s) ok 227 - connect without requesting ocsp response when sslocspstapling=0
[07:38:18.165](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 32696
[07:38:18.473](0.308s) ok 229 - failed with an expired ocsp response when sslocspstapling=1
[07:38:18.571](0.097s) ok 230 - connect without requesting ocsp response when sslocspstapling=0
[07:38:18.571](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 32744
[07:38:18.768](0.197s) ok 232 - failed with an expired ocsp response when sslocspstapling=1
[07:38:18.851](0.083s) ok 233 - connect without requesting ocsp response when sslocspstapling=0
[07:38:18.851](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-12 07:38:02.949 UTC [30165][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-12 07:38:02.949 UTC [30165][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:02.958 UTC [30183][startup] LOG:  database system was shut down at 2024-03-12 07:36:03 UTC
2024-03-12 07:38:02.966 UTC [30165][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:03.005 UTC [30196][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.006 UTC [30196][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-12 07:38:03.006 UTC [30196][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.031 UTC [30196][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-12 07:38:03.036 UTC [30196][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.064 UTC [30210][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.065 UTC [30210][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-12 07:38:03.065 UTC [30210][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.067 UTC [30210][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-12 07:38:03.069 UTC [30210][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.117 UTC [30223][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.118 UTC [30223][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-12 07:38:03.118 UTC [30223][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.120 UTC [30223][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-12 07:38:03.122 UTC [30223][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.169 UTC [30236][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.170 UTC [30236][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-12 07:38:03.170 UTC [30236][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.174 UTC [30236][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-12 07:38:03.177 UTC [30236][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.219 UTC [30248][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.219 UTC [30248][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-12 07:38:03.219 UTC [30248][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.223 UTC [30248][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-12 07:38:03.226 UTC [30248][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.254 UTC [30252][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.255 UTC [30252][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-12 07:38:03.255 UTC [30252][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.257 UTC [30252][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-12 07:38:03.343 UTC [30252][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.091 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.400 UTC [30270][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.401 UTC [30270][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-12 07:38:03.401 UTC [30270][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.402 UTC [30270][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-12 07:38:03.521 UTC [30270][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.128 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.712 UTC [30313][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.713 UTC [30313][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-12 07:38:03.713 UTC [30313][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.715 UTC [30313][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-12 07:38:03.798 UTC [30313][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.093 user=postgres database=postgres host=[local]
2024-03-12 07:38:03.813 UTC [30325][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:03.814 UTC [30325][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-12 07:38:03.814 UTC [30325][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:03.815 UTC [30325][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-12 07:38:04.089 UTC [30325][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.276 user=postgres database=postgres host=[local]
2024-03-12 07:38:04.443 UTC [30413][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:04.449 UTC [30413][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-12 07:38:04.449 UTC [30413][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:04.457 UTC [30413][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-12 07:38:04.506 UTC [30413][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.064 user=postgres database=postgres host=[local]
2024-03-12 07:38:04.588 UTC [30430][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:38:04.589 UTC [30430][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-12 07:38:04.589 UTC [30430][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:38:04.592 UTC [30430][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-12 07:38:04.711 UTC [30430][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.129 user=postgres database=postgres host=[local]
2024-03-12 07:38:04.851 UTC [30165][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:04.851 UTC [30165][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:04.855 UTC [30165][postmaster] LOG:  background worker "logical replication launcher" (PID 30190) exited with exit code 1
2024-03-12 07:38:04.858 UTC [30181][checkpointer] LOG:  shutting down
2024-03-12 07:38:04.858 UTC [30181][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:04.947 UTC [30181][checkpointer] LOG:  checkpoint complete: wrote 5650 buffers (34.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.088 s, sync=0.001 s, total=0.089 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45479 kB, estimate=45479 kB; lsn=0/41961F0, redo lsn=0/41961F0
2024-03-12 07:38:04.979 UTC [30165][postmaster] LOG:  database system is shut down
2024-03-12 07:38:05.103 UTC [30498][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-12 07:38:05.103 UTC [30498][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:05.103 UTC [30498][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:05.132 UTC [30511][startup] LOG:  database system was shut down at 2024-03-12 07:38:04 UTC
2024-03-12 07:38:05.144 UTC [30498][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:05.190 UTC [30498][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:05.190 UTC [30498][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:05.195 UTC [30498][postmaster] LOG:  background worker "logical replication launcher" (PID 30516) exited with exit code 1
2024-03-12 07:38:05.198 UTC [30509][checkpointer] LOG:  shutting down
2024-03-12 07:38:05.198 UTC [30509][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:05.199 UTC [30509][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/4196268, redo lsn=0/4196268
2024-03-12 07:38:05.205 UTC [30498][postmaster] LOG:  database system is shut down
2024-03-12 07:38:05.351 UTC [30529][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-12 07:38:05.352 UTC [30529][postmaster] LOG:  database system is shut down
2024-03-12 07:38:05.464 UTC [30549][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-12 07:38:05.464 UTC [30549][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:05.464 UTC [30549][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:05.482 UTC [30557][startup] LOG:  database system was shut down at 2024-03-12 07:38:05 UTC
2024-03-12 07:38:05.494 UTC [30549][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:05.515 UTC [30549][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:05.515 UTC [30549][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:05.520 UTC [30549][postmaster] LOG:  background worker "logical replication launcher" (PID 30562) exited with exit code 1
2024-03-12 07:38:05.526 UTC [30553][checkpointer] LOG:  shutting down
2024-03-12 07:38:05.526 UTC [30553][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:05.526 UTC [30553][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/41962E0, redo lsn=0/41962E0
2024-03-12 07:38:05.533 UTC [30549][postmaster] LOG:  database system is shut down
2024-03-12 07:38:05.666 UTC [30577][postmaster] FATAL:  could not set SSL protocol version range
2024-03-12 07:38:05.666 UTC [30577][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-12 07:38:05.666 UTC [30577][postmaster] LOG:  database system is shut down
2024-03-12 07:38:05.793 UTC [30593][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-12 07:38:05.793 UTC [30593][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:05.793 UTC [30593][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:05.810 UTC [30612][startup] LOG:  database system was shut down at 2024-03-12 07:38:05 UTC
2024-03-12 07:38:05.819 UTC [30593][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:05.843 UTC [30593][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:05.843 UTC [30593][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:05.855 UTC [30593][postmaster] LOG:  background worker "logical replication launcher" (PID 30619) exited with exit code 1
2024-03-12 07:38:05.855 UTC [30609][checkpointer] LOG:  shutting down
2024-03-12 07:38:05.855 UTC [30609][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:05.855 UTC [30609][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/4196358, redo lsn=0/4196358
2024-03-12 07:38:05.861 UTC [30593][postmaster] LOG:  database system is shut down
2024-03-12 07:38:06.039 UTC [30635][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-12 07:38:06.039 UTC [30635][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:06.039 UTC [30635][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:06.054 UTC [30648][startup] LOG:  database system was shut down at 2024-03-12 07:38:05 UTC
2024-03-12 07:38:06.061 UTC [30635][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:06.201 UTC [30673][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33596
2024-03-12 07:38:06.202 UTC [30673][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-12 07:38:06.202 UTC [30673][client backend] [[unknown]][0/1:0] DETAIL:  Client IP address resolved to "localhost", forward lookup not checked.
2024-03-12 07:38:06.240 UTC [30682][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33610
2024-03-12 07:38:06.253 UTC [30682][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-12 07:38:06.253 UTC [30682][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-12 07:38:06.293 UTC [30682][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-12 07:38:06.305 UTC [30682][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.071 user=ssltestuser database=trustdb host=localhost port=33610
2024-03-12 07:38:06.327 UTC [30692][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33618
2024-03-12 07:38:06.342 UTC [30692][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-12 07:38:06.372 UTC [30697][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33630
2024-03-12 07:38:06.376 UTC [30697][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-12 07:38:06.449 UTC [30709][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33634
2024-03-12 07:38:06.456 UTC [30709][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-12 07:38:06.497 UTC [30711][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33640
2024-03-12 07:38:06.508 UTC [30711][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-12 07:38:06.525 UTC [30713][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33644
2024-03-12 07:38:06.535 UTC [30713][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-12 07:38:06.558 UTC [30716][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33650
2024-03-12 07:38:06.568 UTC [30716][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-12 07:38:06.637 UTC [30727][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33662
2024-03-12 07:38:06.650 UTC [30727][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-12 07:38:06.650 UTC [30727][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-12 07:38:06.651 UTC [30727][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-12 07:38:06.654 UTC [30727][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=localhost port=33662
2024-03-12 07:38:06.704 UTC [30732][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33678
2024-03-12 07:38:06.710 UTC [30732][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-12 07:38:06.711 UTC [30732][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-12 07:38:06.712 UTC [30732][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-12 07:38:06.716 UTC [30732][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=33678
2024-03-12 07:38:06.750 UTC [30743][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33684
2024-03-12 07:38:06.760 UTC [30743][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-12 07:38:06.760 UTC [30743][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-12 07:38:06.765 UTC [30743][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-12 07:38:06.767 UTC [30743][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=33684
2024-03-12 07:38:06.798 UTC [30750][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33688
2024-03-12 07:38:06.806 UTC [30750][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-12 07:38:06.806 UTC [30750][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-12 07:38:06.812 UTC [30750][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-12 07:38:06.815 UTC [30750][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=33688
2024-03-12 07:38:06.850 UTC [30760][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33694
2024-03-12 07:38:06.857 UTC [30760][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-12 07:38:06.857 UTC [30760][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-12 07:38:06.859 UTC [30760][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-12 07:38:06.865 UTC [30760][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=33694
2024-03-12 07:38:06.884 UTC [30769][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33698
2024-03-12 07:38:06.900 UTC [30769][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-12 07:38:06.900 UTC [30769][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-12 07:38:06.905 UTC [30769][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-12 07:38:06.906 UTC [30769][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=ssltestuser database=trustdb host=localhost port=33698
2024-03-12 07:38:06.949 UTC [30776][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33700
2024-03-12 07:38:06.961 UTC [30776][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-12 07:38:06.961 UTC [30776][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-12 07:38:06.965 UTC [30776][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-12 07:38:06.969 UTC [30776][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=ssltestuser database=trustdb host=localhost port=33700
2024-03-12 07:38:07.037 UTC [30792][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33714
2024-03-12 07:38:07.049 UTC [30792][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-12 07:38:07.049 UTC [30792][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-12 07:38:07.054 UTC [30792][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=localhost port=33714
2024-03-12 07:38:07.083 UTC [30806][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33728
2024-03-12 07:38:07.092 UTC [30806][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-12 07:38:07.092 UTC [30806][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-12 07:38:07.097 UTC [30806][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-12 07:38:07.101 UTC [30806][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=33728
2024-03-12 07:38:07.132 UTC [30813][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33734
2024-03-12 07:38:07.155 UTC [30813][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-12 07:38:07.202 UTC [30830][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33740
2024-03-12 07:38:07.214 UTC [30830][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-12 07:38:07.237 UTC [30837][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33750
2024-03-12 07:38:07.244 UTC [30837][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-12 07:38:07.244 UTC [30837][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-12 07:38:07.248 UTC [30837][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-12 07:38:07.254 UTC [30837][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=ssltestuser database=trustdb host=localhost port=33750
2024-03-12 07:38:07.286 UTC [30850][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33752
2024-03-12 07:38:07.305 UTC [30850][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-12 07:38:07.305 UTC [30850][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-12 07:38:07.313 UTC [30850][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-12 07:38:07.316 UTC [30850][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=ssltestuser database=trustdb host=localhost port=33752
2024-03-12 07:38:07.385 UTC [30860][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33754
2024-03-12 07:38:07.395 UTC [30860][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-12 07:38:07.395 UTC [30860][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-12 07:38:07.409 UTC [30860][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-12 07:38:07.413 UTC [30860][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=ssltestuser database=trustdb host=localhost port=33754
2024-03-12 07:38:07.460 UTC [30876][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33758
2024-03-12 07:38:07.467 UTC [30876][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-12 07:38:07.467 UTC [30876][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-12 07:38:07.468 UTC [30876][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-12 07:38:07.469 UTC [30876][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=ssltestuser database=trustdb host=localhost port=33758
2024-03-12 07:38:07.491 UTC [30888][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33766
2024-03-12 07:38:07.534 UTC [30635][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:07.534 UTC [30635][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:07.543 UTC [30635][postmaster] LOG:  background worker "logical replication launcher" (PID 30652) exited with exit code 1
2024-03-12 07:38:07.544 UTC [30646][checkpointer] LOG:  shutting down
2024-03-12 07:38:07.544 UTC [30646][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:07.545 UTC [30646][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/41963D0, redo lsn=0/41963D0
2024-03-12 07:38:07.551 UTC [30635][postmaster] LOG:  database system is shut down
2024-03-12 07:38:07.725 UTC [30917][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-12 07:38:07.725 UTC [30917][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:07.725 UTC [30917][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:07.742 UTC [30934][startup] LOG:  database system was shut down at 2024-03-12 07:38:07 UTC
2024-03-12 07:38:07.760 UTC [30917][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:07.871 UTC [30961][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33768
2024-03-12 07:38:07.882 UTC [30961][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-12 07:38:07.882 UTC [30961][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-12 07:38:07.896 UTC [30961][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-12 07:38:07.902 UTC [30961][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=ssltestuser database=trustdb host=localhost port=33768
2024-03-12 07:38:07.920 UTC [30964][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33780
2024-03-12 07:38:07.940 UTC [30917][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:07.940 UTC [30917][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:07.946 UTC [30917][postmaster] LOG:  background worker "logical replication launcher" (PID 30939) exited with exit code 1
2024-03-12 07:38:07.948 UTC [30931][checkpointer] LOG:  shutting down
2024-03-12 07:38:07.948 UTC [30931][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:07.949 UTC [30931][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/4196448, redo lsn=0/4196448
2024-03-12 07:38:07.955 UTC [30917][postmaster] LOG:  database system is shut down
2024-03-12 07:38:08.124 UTC [30989][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-12 07:38:08.124 UTC [30989][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:08.124 UTC [30989][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:08.161 UTC [31015][startup] LOG:  database system was shut down at 2024-03-12 07:38:07 UTC
2024-03-12 07:38:08.179 UTC [30989][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:08.278 UTC [31044][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33788
2024-03-12 07:38:08.286 UTC [31044][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-12 07:38:08.286 UTC [31044][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-12 07:38:08.334 UTC [31044][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-12 07:38:08.340 UTC [31044][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.062 user=ssltestuser database=trustdb host=localhost port=33788
2024-03-12 07:38:08.351 UTC [30989][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:08.351 UTC [30989][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:08.356 UTC [30989][postmaster] LOG:  background worker "logical replication launcher" (PID 31029) exited with exit code 1
2024-03-12 07:38:08.361 UTC [31013][checkpointer] LOG:  shutting down
2024-03-12 07:38:08.361 UTC [31013][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:08.362 UTC [31013][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/41964C0, redo lsn=0/41964C0
2024-03-12 07:38:08.368 UTC [30989][postmaster] LOG:  database system is shut down
2024-03-12 07:38:08.500 UTC [31071][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-12 07:38:08.500 UTC [31071][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:08.500 UTC [31071][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:08.523 UTC [31075][startup] LOG:  database system was shut down at 2024-03-12 07:38:08 UTC
2024-03-12 07:38:08.546 UTC [31071][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:08.574 UTC [31090][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33796
2024-03-12 07:38:08.585 UTC [31090][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-12 07:38:08.585 UTC [31090][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-12 07:38:08.624 UTC [31090][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-12 07:38:08.630 UTC [31090][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.057 user=ssltestuser database=trustdb host=localhost port=33796
2024-03-12 07:38:08.665 UTC [31114][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33810
2024-03-12 07:38:08.699 UTC [31114][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-12 07:38:08.699 UTC [31114][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-12 07:38:08.700 UTC [31114][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-12 07:38:08.701 UTC [31114][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.036 user=ssltestuser database=trustdb host=localhost port=33810
2024-03-12 07:38:08.738 UTC [31132][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33816
2024-03-12 07:38:08.745 UTC [31132][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-12 07:38:08.745 UTC [31132][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-12 07:38:08.746 UTC [31132][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-12 07:38:08.748 UTC [31132][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=33816
2024-03-12 07:38:08.796 UTC [31141][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33822
2024-03-12 07:38:08.865 UTC [31152][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33824
2024-03-12 07:38:08.895 UTC [31071][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:08.895 UTC [31071][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:08.907 UTC [31071][postmaster] LOG:  background worker "logical replication launcher" (PID 31085) exited with exit code 1
2024-03-12 07:38:08.907 UTC [31073][checkpointer] LOG:  shutting down
2024-03-12 07:38:08.907 UTC [31073][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:08.907 UTC [31073][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/4196538, redo lsn=0/4196538
2024-03-12 07:38:08.913 UTC [31071][postmaster] LOG:  database system is shut down
2024-03-12 07:38:08.980 UTC [31164][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-12 07:38:08.980 UTC [31164][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:08.981 UTC [31164][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:08.992 UTC [31184][startup] LOG:  database system was shut down at 2024-03-12 07:38:08 UTC
2024-03-12 07:38:09.000 UTC [31164][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:09.057 UTC [31203][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33830
2024-03-12 07:38:09.064 UTC [31203][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-12 07:38:09.064 UTC [31203][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-12 07:38:09.078 UTC [31203][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-12 07:38:09.085 UTC [31203][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=ssltestuser database=trustdb host=localhost port=33830
2024-03-12 07:38:09.104 UTC [31226][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33844
2024-03-12 07:38:09.169 UTC [31240][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33856
2024-03-12 07:38:09.229 UTC [31164][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:09.229 UTC [31164][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:09.236 UTC [31164][postmaster] LOG:  background worker "logical replication launcher" (PID 31189) exited with exit code 1
2024-03-12 07:38:09.240 UTC [31180][checkpointer] LOG:  shutting down
2024-03-12 07:38:09.240 UTC [31180][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:09.241 UTC [31180][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/41965B0, redo lsn=0/41965B0
2024-03-12 07:38:09.248 UTC [31164][postmaster] LOG:  database system is shut down
2024-03-12 07:38:09.397 UTC [31279][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-12 07:38:09.397 UTC [31279][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:09.397 UTC [31279][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:09.421 UTC [31293][startup] LOG:  database system was shut down at 2024-03-12 07:38:09 UTC
2024-03-12 07:38:09.430 UTC [31279][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:09.450 UTC [31305][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33862
2024-03-12 07:38:09.457 UTC [31305][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-12 07:38:09.457 UTC [31305][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-12 07:38:09.474 UTC [31305][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-12 07:38:09.479 UTC [31305][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=ssltestuser database=trustdb host=localhost port=33862
2024-03-12 07:38:09.514 UTC [31317][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33878
2024-03-12 07:38:09.533 UTC [31317][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-12 07:38:09.533 UTC [31317][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-12 07:38:09.541 UTC [31317][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-12 07:38:09.542 UTC [31317][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=ssltestuser database=trustdb host=localhost port=33878
2024-03-12 07:38:09.611 UTC [31336][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33882
2024-03-12 07:38:09.660 UTC [31346][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33894
2024-03-12 07:38:09.681 UTC [31346][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-12 07:38:09.681 UTC [31346][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-12 07:38:09.685 UTC [31346][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-12 07:38:09.689 UTC [31346][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=ssltestuser database=trustdb host=localhost port=33894
2024-03-12 07:38:09.730 UTC [31357][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33902
2024-03-12 07:38:09.746 UTC [31357][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-12 07:38:09.746 UTC [31357][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-12 07:38:09.750 UTC [31357][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-12 07:38:09.767 UTC [31357][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.052 user=ssltestuser database=trustdb host=localhost port=33902
2024-03-12 07:38:09.803 UTC [31374][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33906
2024-03-12 07:38:09.813 UTC [31374][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-12 07:38:09.813 UTC [31374][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-12 07:38:09.816 UTC [31374][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-12 07:38:09.824 UTC [31374][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.022 user=ssltestuser database=trustdb host=localhost port=33906
2024-03-12 07:38:09.858 UTC [31382][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33920
2024-03-12 07:38:09.898 UTC [31388][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33932
2024-03-12 07:38:09.939 UTC [31279][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:09.939 UTC [31279][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:09.949 UTC [31279][postmaster] LOG:  background worker "logical replication launcher" (PID 31300) exited with exit code 1
2024-03-12 07:38:09.949 UTC [31291][checkpointer] LOG:  shutting down
2024-03-12 07:38:09.950 UTC [31291][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:09.950 UTC [31291][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/4196628, redo lsn=0/4196628
2024-03-12 07:38:09.957 UTC [31279][postmaster] LOG:  database system is shut down
2024-03-12 07:38:10.082 UTC [31424][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-12 07:38:10.083 UTC [31424][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:10.083 UTC [31424][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:10.104 UTC [31436][startup] LOG:  database system was shut down at 2024-03-12 07:38:09 UTC
2024-03-12 07:38:10.113 UTC [31424][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:10.185 UTC [31460][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33936
2024-03-12 07:38:10.196 UTC [31460][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-12 07:38:10.196 UTC [31460][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-12 07:38:10.219 UTC [31460][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-12 07:38:10.226 UTC [31460][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.041 user=ssltestuser database=trustdb host=localhost port=33936
2024-03-12 07:38:10.246 UTC [31466][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33940
2024-03-12 07:38:10.253 UTC [31466][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-12 07:38:10.253 UTC [31466][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-12 07:38:10.255 UTC [31466][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-12 07:38:10.261 UTC [31466][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=33940
2024-03-12 07:38:10.301 UTC [31470][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33950
2024-03-12 07:38:10.343 UTC [31424][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:10.343 UTC [31424][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:10.354 UTC [31424][postmaster] LOG:  background worker "logical replication launcher" (PID 31443) exited with exit code 1
2024-03-12 07:38:10.354 UTC [31434][checkpointer] LOG:  shutting down
2024-03-12 07:38:10.354 UTC [31434][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:10.355 UTC [31434][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/41966A0, redo lsn=0/41966A0
2024-03-12 07:38:10.380 UTC [31424][postmaster] LOG:  database system is shut down
2024-03-12 07:38:10.537 UTC [31495][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-12 07:38:10.537 UTC [31495][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:10.537 UTC [31495][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:10.549 UTC [31509][startup] LOG:  database system was shut down at 2024-03-12 07:38:10 UTC
2024-03-12 07:38:10.559 UTC [31495][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:10.708 UTC [31534][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33958
2024-03-12 07:38:10.714 UTC [31534][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-12 07:38:10.714 UTC [31534][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-12 07:38:10.747 UTC [31534][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-12 07:38:10.754 UTC [31534][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.056 user=ssltestuser database=trustdb host=localhost port=33958
2024-03-12 07:38:10.810 UTC [31554][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33960
2024-03-12 07:38:10.817 UTC [31554][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-12 07:38:10.817 UTC [31554][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-12 07:38:10.819 UTC [31554][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-12 07:38:10.822 UTC [31554][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=33960
2024-03-12 07:38:10.860 UTC [31569][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33966
2024-03-12 07:38:10.868 UTC [31569][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-12 07:38:10.868 UTC [31569][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-12 07:38:10.870 UTC [31569][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-12 07:38:10.872 UTC [31569][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=33966
2024-03-12 07:38:10.878 UTC [31495][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:10.878 UTC [31495][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:10.883 UTC [31495][postmaster] LOG:  background worker "logical replication launcher" (PID 31517) exited with exit code 1
2024-03-12 07:38:10.893 UTC [31507][checkpointer] LOG:  shutting down
2024-03-12 07:38:10.893 UTC [31507][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:10.894 UTC [31507][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/4196718, redo lsn=0/4196718
2024-03-12 07:38:10.901 UTC [31495][postmaster] LOG:  database system is shut down
2024-03-12 07:38:11.080 UTC [31591][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-12 07:38:11.080 UTC [31591][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:11.080 UTC [31591][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:11.097 UTC [31616][startup] LOG:  database system was shut down at 2024-03-12 07:38:10 UTC
2024-03-12 07:38:11.104 UTC [31591][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:11.223 UTC [31656][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33974
2024-03-12 07:38:11.229 UTC [31656][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-12 07:38:11.230 UTC [31656][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-12 07:38:11.247 UTC [31656][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-12 07:38:11.253 UTC [31656][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=ssltestuser database=trustdb host=localhost port=33974
2024-03-12 07:38:11.296 UTC [31665][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33990
2024-03-12 07:38:11.311 UTC [31665][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-12 07:38:11.311 UTC [31665][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-12 07:38:11.313 UTC [31665][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-12 07:38:11.319 UTC [31665][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.028 user=ssltestuser database=trustdb host=localhost port=33990
2024-03-12 07:38:11.365 UTC [31675][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=33994
2024-03-12 07:38:11.393 UTC [31591][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:11.393 UTC [31591][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:11.398 UTC [31591][postmaster] LOG:  background worker "logical replication launcher" (PID 31621) exited with exit code 1
2024-03-12 07:38:11.403 UTC [31614][checkpointer] LOG:  shutting down
2024-03-12 07:38:11.403 UTC [31614][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:11.403 UTC [31614][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/4196790, redo lsn=0/4196790
2024-03-12 07:38:11.410 UTC [31591][postmaster] LOG:  database system is shut down
2024-03-12 07:38:11.591 UTC [31713][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-12 07:38:11.591 UTC [31713][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:11.591 UTC [31713][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:11.612 UTC [31736][startup] LOG:  database system was shut down at 2024-03-12 07:38:11 UTC
2024-03-12 07:38:11.642 UTC [31713][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:11.743 UTC [31773][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34006
2024-03-12 07:38:11.759 UTC [31773][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-12 07:38:11.759 UTC [31773][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-12 07:38:11.789 UTC [31773][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-12 07:38:11.802 UTC [31773][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.060 user=ssltestuser database=trustdb host=localhost port=34006
2024-03-12 07:38:11.816 UTC [31783][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34014
2024-03-12 07:38:11.832 UTC [31783][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-12 07:38:11.832 UTC [31783][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-12 07:38:11.848 UTC [31783][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-12 07:38:11.852 UTC [31783][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.037 user=ssltestuser database=trustdb host=localhost port=34014
2024-03-12 07:38:11.894 UTC [31796][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34016
2024-03-12 07:38:11.904 UTC [31796][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-12 07:38:11.904 UTC [31796][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-12 07:38:11.908 UTC [31796][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-12 07:38:11.916 UTC [31796][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=ssltestuser database=trustdb host=localhost port=34016
2024-03-12 07:38:11.936 UTC [31713][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:11.936 UTC [31713][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:11.948 UTC [31713][postmaster] LOG:  background worker "logical replication launcher" (PID 31749) exited with exit code 1
2024-03-12 07:38:11.948 UTC [31733][checkpointer] LOG:  shutting down
2024-03-12 07:38:11.948 UTC [31733][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:11.949 UTC [31733][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/4196808, redo lsn=0/4196808
2024-03-12 07:38:11.955 UTC [31713][postmaster] LOG:  database system is shut down
2024-03-12 07:38:12.085 UTC [31831][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-12 07:38:12.085 UTC [31831][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:12.086 UTC [31831][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:12.091 UTC [31840][startup] LOG:  database system was shut down at 2024-03-12 07:38:11 UTC
2024-03-12 07:38:12.098 UTC [31831][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:12.148 UTC [31856][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34024
2024-03-12 07:38:12.155 UTC [31856][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-12 07:38:12.155 UTC [31856][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-12 07:38:12.167 UTC [31856][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-12 07:38:12.173 UTC [31856][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=trustdb host=localhost port=34024
2024-03-12 07:38:12.198 UTC [31866][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34032
2024-03-12 07:38:12.215 UTC [31831][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:12.215 UTC [31831][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:12.232 UTC [31831][postmaster] LOG:  background worker "logical replication launcher" (PID 31844) exited with exit code 1
2024-03-12 07:38:12.233 UTC [31837][checkpointer] LOG:  shutting down
2024-03-12 07:38:12.233 UTC [31837][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:12.233 UTC [31837][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/4196880, redo lsn=0/4196880
2024-03-12 07:38:12.239 UTC [31831][postmaster] LOG:  database system is shut down
2024-03-12 07:38:12.355 UTC [31894][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-12 07:38:12.355 UTC [31894][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:12.355 UTC [31894][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:12.375 UTC [31907][startup] LOG:  database system was shut down at 2024-03-12 07:38:12 UTC
2024-03-12 07:38:12.384 UTC [31894][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:12.435 UTC [31918][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34036
2024-03-12 07:38:12.442 UTC [31918][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-12 07:38:12.474 UTC [31924][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34042
2024-03-12 07:38:12.480 UTC [31924][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-12 07:38:12.480 UTC [31924][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-12 07:38:12.494 UTC [31924][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-12 07:38:12.499 UTC [31924][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=ssltestuser database=trustdb host=localhost port=34042
2024-03-12 07:38:12.524 UTC [31927][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34048
2024-03-12 07:38:12.543 UTC [31894][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:12.543 UTC [31894][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:12.555 UTC [31894][postmaster] LOG:  background worker "logical replication launcher" (PID 31913) exited with exit code 1
2024-03-12 07:38:12.555 UTC [31902][checkpointer] LOG:  shutting down
2024-03-12 07:38:12.555 UTC [31902][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:12.555 UTC [31902][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/41968F8, redo lsn=0/41968F8
2024-03-12 07:38:12.561 UTC [31894][postmaster] LOG:  database system is shut down
2024-03-12 07:38:12.697 UTC [31939][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-12 07:38:12.697 UTC [31939][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:12.697 UTC [31939][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:12.702 UTC [31945][startup] LOG:  database system was shut down at 2024-03-12 07:38:12 UTC
2024-03-12 07:38:12.709 UTC [31939][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:12.758 UTC [31957][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=34056
2024-03-12 07:38:12.765 UTC [31957][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-12 07:38:12.765 UTC [31957][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-12 07:38:12.780 UTC [31957][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-12 07:38:12.786 UTC [31957][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=ssltestuser database=trustdb host=localhost port=34056
2024-03-12 07:38:12.817 UTC [31963][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39292
2024-03-12 07:38:12.832 UTC [31963][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-12 07:38:12.870 UTC [31970][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39302
2024-03-12 07:38:12.880 UTC [31970][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-12 07:38:12.913 UTC [31973][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39308
2024-03-12 07:38:12.919 UTC [31973][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-12 07:38:12.919 UTC [31973][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-12 07:38:12.921 UTC [31973][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-12 07:38:12.926 UTC [31973][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=39308
2024-03-12 07:38:12.945 UTC [31982][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39324
2024-03-12 07:38:12.952 UTC [31982][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-12 07:38:12.952 UTC [31982][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-12 07:38:12.966 UTC [31982][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-12 07:38:12.972 UTC [31982][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.028 user=ssltestuser database=trustdb host=localhost port=39324
2024-03-12 07:38:13.100 UTC [32001][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39326
2024-03-12 07:38:13.106 UTC [32001][client backend] [[unknown]][3/1:0] FATAL:  connection requires a valid client certificate
2024-03-12 07:38:13.141 UTC [32008][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39338
2024-03-12 07:38:13.149 UTC [32008][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-12 07:38:13.150 UTC [32008][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-12 07:38:13.166 UTC [32008][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_f2wT/client.key$$
2024-03-12 07:38:13.169 UTC [32008][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=ssltestuser database=certdb host=localhost port=39338
2024-03-12 07:38:13.201 UTC [32020][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39346
2024-03-12 07:38:13.209 UTC [32020][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-12 07:38:13.209 UTC [32020][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-12 07:38:13.210 UTC [32020][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_f2wT/client-der.key$$
2024-03-12 07:38:13.212 UTC [32020][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.019 user=ssltestuser database=certdb host=localhost port=39346
2024-03-12 07:38:13.265 UTC [32026][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39362
2024-03-12 07:38:13.274 UTC [32026][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-12 07:38:13.274 UTC [32026][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-12 07:38:13.276 UTC [32026][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_f2wT/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$
2024-03-12 07:38:13.281 UTC [32026][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=certdb host=localhost port=39362
2024-03-12 07:38:13.303 UTC [32037][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39366
2024-03-12 07:38:13.315 UTC [32037][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-12 07:38:13.315 UTC [32037][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-12 07:38:13.317 UTC [32037][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_f2wT/client-encrypted-der.key sslpassword='dUmmyP^#+'$$
2024-03-12 07:38:13.318 UTC [32037][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=ssltestuser database=certdb host=localhost port=39366
2024-03-12 07:38:13.357 UTC [32042][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39382
2024-03-12 07:38:13.365 UTC [32042][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-12 07:38:13.365 UTC [32042][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-12 07:38:13.369 UTC [32042][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_f2wT/client.key$$
2024-03-12 07:38:13.372 UTC [32042][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.019 user=ssltestuser database=certdb host=localhost port=39382
2024-03-12 07:38:13.413 UTC [32046][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39390
2024-03-12 07:38:13.421 UTC [32046][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-12 07:38:13.421 UTC [32046][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-12 07:38:13.423 UTC [32046][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_f2wT/client.key$$
2024-03-12 07:38:13.427 UTC [32046][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=ssltestuser database=certdb host=localhost port=39390
2024-03-12 07:38:13.497 UTC [32052][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39404
2024-03-12 07:38:13.504 UTC [32052][client backend] [[unknown]][10/1:0] FATAL:  connection requires a valid client certificate
2024-03-12 07:38:13.546 UTC [32058][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39416
2024-03-12 07:38:13.555 UTC [32058][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-12 07:38:13.589 UTC [32061][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39426
2024-03-12 07:38:13.598 UTC [32061][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-12 07:38:13.599 UTC [32061][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-12 07:38:13.640 UTC [32061][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_f2wT/client-dn.key$$
2024-03-12 07:38:13.646 UTC [32061][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.059 user=ssltestuser database=certdb_dn host=localhost port=39426
2024-03-12 07:38:13.682 UTC [32073][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39430
2024-03-12 07:38:13.691 UTC [32073][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-12 07:38:13.691 UTC [32073][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-12 07:38:13.729 UTC [32073][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_f2wT/client-dn.key$$
2024-03-12 07:38:13.735 UTC [32073][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.056 user=ssltestuser database=certdb_dn_re host=localhost port=39430
2024-03-12 07:38:13.758 UTC [32081][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39436
2024-03-12 07:38:13.770 UTC [32081][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-12 07:38:13.770 UTC [32081][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-12 07:38:13.785 UTC [32081][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_f2wT/client-dn.key$$
2024-03-12 07:38:13.792 UTC [32081][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.038 user=ssltestuser database=certdb_cn host=localhost port=39436
2024-03-12 07:38:13.840 UTC [32096][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39452
2024-03-12 07:38:13.848 UTC [32096][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-12 07:38:13.848 UTC [32096][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-12 07:38:13.850 UTC [32096][client backend] [001_ssltests.pl][14/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-12 07:38:13.855 UTC [32096][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.016 user=ssltestuser database=certdb host=localhost port=39452
2024-03-12 07:38:13.882 UTC [32106][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39462
2024-03-12 07:38:13.887 UTC [32106][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-12 07:38:13.930 UTC [32116][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39466
2024-03-12 07:38:13.948 UTC [32116][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-12 07:38:13.948 UTC [32116][client backend] [[unknown]][15/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-12 07:38:13.948 UTC [32116][client backend] [[unknown]][15/1:0] FATAL:  certificate authentication failed for user "anotheruser"
2024-03-12 07:38:13.948 UTC [32116][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-12 07:38:13.971 UTC [32128][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39470
2024-03-12 07:38:13.978 UTC [32128][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-12 07:38:13.978 UTC [32128][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-12 07:38:13.998 UTC [32130][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39482
2024-03-12 07:38:14.007 UTC [32130][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-12 07:38:14.007 UTC [32130][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-12 07:38:14.018 UTC [32130][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_f2wT/client.key$$
2024-03-12 07:38:14.024 UTC [32130][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=ssltestuser database=verifydb host=localhost port=39482
2024-03-12 07:38:14.051 UTC [32135][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39498
2024-03-12 07:38:14.060 UTC [32135][client backend] [[unknown]][17/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-12 07:38:14.060 UTC [32135][client backend] [[unknown]][17/1:0] LOG:  certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch
2024-03-12 07:38:14.060 UTC [32135][client backend] [[unknown]][17/1:0] FATAL:  "trust" authentication failed for user "anotheruser"
2024-03-12 07:38:14.060 UTC [32135][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-12 07:38:14.094 UTC [32141][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39504
2024-03-12 07:38:14.106 UTC [32141][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-12 07:38:14.106 UTC [32141][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-12 07:38:14.111 UTC [32141][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_f2wT/client.key$$
2024-03-12 07:38:14.115 UTC [32141][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.022 user=yetanotheruser database=verifydb host=localhost port=39504
2024-03-12 07:38:14.135 UTC [31939][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:14.135 UTC [31939][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:14.148 UTC [31939][postmaster] LOG:  background worker "logical replication launcher" (PID 31948) exited with exit code 1
2024-03-12 07:38:14.152 UTC [31942][checkpointer] LOG:  shutting down
2024-03-12 07:38:14.152 UTC [31942][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:14.153 UTC [31942][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/4196970, redo lsn=0/4196970
2024-03-12 07:38:14.159 UTC [31939][postmaster] LOG:  database system is shut down
2024-03-12 07:38:14.302 UTC [32165][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-12 07:38:14.302 UTC [32165][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:14.302 UTC [32165][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:14.341 UTC [32177][startup] LOG:  database system was shut down at 2024-03-12 07:38:14 UTC
2024-03-12 07:38:14.357 UTC [32165][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:14.464 UTC [32196][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39518
2024-03-12 07:38:14.472 UTC [32196][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-12 07:38:14.473 UTC [32196][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-12 07:38:14.487 UTC [32196][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_f2wT/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-12 07:38:14.492 UTC [32196][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=ssltestuser database=certdb host=localhost port=39518
2024-03-12 07:38:14.512 UTC [32206][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39524
2024-03-12 07:38:14.526 UTC [32206][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-12 07:38:14.526 UTC [32206][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-12 07:38:14.543 UTC [32215][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39526
2024-03-12 07:38:14.558 UTC [32215][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-12 07:38:14.558 UTC [32215][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-12 07:38:14.575 UTC [32165][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:14.575 UTC [32165][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:14.589 UTC [32165][postmaster] LOG:  background worker "logical replication launcher" (PID 32186) exited with exit code 1
2024-03-12 07:38:14.589 UTC [32175][checkpointer] LOG:  shutting down
2024-03-12 07:38:14.589 UTC [32175][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:14.590 UTC [32175][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/41969E8, redo lsn=0/41969E8
2024-03-12 07:38:14.596 UTC [32165][postmaster] LOG:  database system is shut down
2024-03-12 07:38:14.752 UTC [32237][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-12 07:38:14.752 UTC [32237][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:14.752 UTC [32237][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:14.757 UTC [32256][startup] LOG:  database system was shut down at 2024-03-12 07:38:14 UTC
2024-03-12 07:38:14.766 UTC [32237][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:14.840 UTC [32268][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39540
2024-03-12 07:38:14.847 UTC [32268][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-12 07:38:14.847 UTC [32268][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-12 07:38:14.870 UTC [32237][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:14.870 UTC [32237][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:14.879 UTC [32237][postmaster] LOG:  background worker "logical replication launcher" (PID 32262) exited with exit code 1
2024-03-12 07:38:14.879 UTC [32252][checkpointer] LOG:  shutting down
2024-03-12 07:38:14.879 UTC [32252][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:14.880 UTC [32252][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/4196A60, redo lsn=0/4196A60
2024-03-12 07:38:14.886 UTC [32237][postmaster] LOG:  database system is shut down
2024-03-12 07:38:15.014 UTC [32284][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-12 07:38:15.014 UTC [32284][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:15.015 UTC [32284][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:15.022 UTC [32288][startup] LOG:  database system was shut down at 2024-03-12 07:38:14 UTC
2024-03-12 07:38:15.035 UTC [32284][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:15.109 UTC [32298][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39556
2024-03-12 07:38:15.116 UTC [32298][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-12 07:38:15.116 UTC [32298][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-12 07:38:15.145 UTC [32302][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39558
2024-03-12 07:38:15.160 UTC [32302][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-12 07:38:15.160 UTC [32302][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-12 07:38:15.172 UTC [32284][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:15.172 UTC [32284][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:15.180 UTC [32284][postmaster] LOG:  background worker "logical replication launcher" (PID 32292) exited with exit code 1
2024-03-12 07:38:15.181 UTC [32286][checkpointer] LOG:  shutting down
2024-03-12 07:38:15.181 UTC [32286][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:15.182 UTC [32286][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/4196AD8, redo lsn=0/4196AD8
2024-03-12 07:38:15.187 UTC [32284][postmaster] LOG:  database system is shut down
2024-03-12 07:38:15.305 UTC [32323][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-12 07:38:15.305 UTC [32323][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:15.305 UTC [32323][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:15.310 UTC [32330][startup] LOG:  database system was shut down at 2024-03-12 07:38:15 UTC
2024-03-12 07:38:15.317 UTC [32323][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:15.413 UTC [32346][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39564
2024-03-12 07:38:15.416 UTC [32346][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-12 07:38:15.417 UTC [32346][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-12 07:38:15.424 UTC [32346][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert internal error
2024-03-12 07:38:15.456 UTC [32352][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39574
2024-03-12 07:38:15.467 UTC [32352][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-12 07:38:15.467 UTC [32352][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-12 07:38:15.497 UTC [32352][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-12 07:38:15.509 UTC [32352][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.053 user=ssltestuser database=trustdb host=localhost port=39574
2024-03-12 07:38:15.519 UTC [32323][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:15.519 UTC [32323][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:15.524 UTC [32323][postmaster] LOG:  background worker "logical replication launcher" (PID 32333) exited with exit code 1
2024-03-12 07:38:15.532 UTC [32327][checkpointer] LOG:  shutting down
2024-03-12 07:38:15.532 UTC [32327][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:15.533 UTC [32327][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/4196B50, redo lsn=0/4196B50
2024-03-12 07:38:15.539 UTC [32323][postmaster] LOG:  database system is shut down
2024-03-12 07:38:15.650 UTC [32367][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-12 07:38:15.650 UTC [32367][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:15.650 UTC [32367][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:15.655 UTC [32375][startup] LOG:  database system was shut down at 2024-03-12 07:38:15 UTC
2024-03-12 07:38:15.666 UTC [32367][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:15.750 UTC [32388][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39580
2024-03-12 07:38:15.752 UTC [32388][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-12 07:38:15.752 UTC [32388][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-12 07:38:15.812 UTC [32396][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39588
2024-03-12 07:38:15.819 UTC [32396][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-12 07:38:15.819 UTC [32396][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-12 07:38:15.833 UTC [32396][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-12 07:38:15.839 UTC [32396][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=ssltestuser database=trustdb host=localhost port=39588
2024-03-12 07:38:15.847 UTC [32367][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:15.847 UTC [32367][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:15.859 UTC [32367][postmaster] LOG:  background worker "logical replication launcher" (PID 32379) exited with exit code 1
2024-03-12 07:38:15.860 UTC [32373][checkpointer] LOG:  shutting down
2024-03-12 07:38:15.860 UTC [32373][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:15.861 UTC [32373][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/4196BC8, redo lsn=0/4196BC8
2024-03-12 07:38:15.867 UTC [32367][postmaster] LOG:  database system is shut down
2024-03-12 07:38:16.008 UTC [32425][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-12 07:38:16.008 UTC [32425][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:16.008 UTC [32425][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:16.044 UTC [32436][startup] LOG:  database system was shut down at 2024-03-12 07:38:15 UTC
2024-03-12 07:38:16.055 UTC [32425][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:16.093 UTC [32445][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39604
2024-03-12 07:38:16.096 UTC [32445][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-12 07:38:16.096 UTC [32445][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-12 07:38:16.149 UTC [32450][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39610
2024-03-12 07:38:16.155 UTC [32450][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-12 07:38:16.156 UTC [32450][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-12 07:38:16.173 UTC [32450][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-12 07:38:16.179 UTC [32450][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=ssltestuser database=trustdb host=localhost port=39610
2024-03-12 07:38:16.189 UTC [32425][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:16.189 UTC [32425][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:16.198 UTC [32425][postmaster] LOG:  background worker "logical replication launcher" (PID 32441) exited with exit code 1
2024-03-12 07:38:16.199 UTC [32434][checkpointer] LOG:  shutting down
2024-03-12 07:38:16.199 UTC [32434][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:16.199 UTC [32434][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/4196C40, redo lsn=0/4196C40
2024-03-12 07:38:16.205 UTC [32425][postmaster] LOG:  database system is shut down
2024-03-12 07:38:16.332 UTC [32470][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-12 07:38:16.332 UTC [32470][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:16.332 UTC [32470][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:16.347 UTC [32481][startup] LOG:  database system was shut down at 2024-03-12 07:38:16 UTC
2024-03-12 07:38:16.356 UTC [32470][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:16.428 UTC [32498][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39624
2024-03-12 07:38:16.431 UTC [32498][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-12 07:38:16.432 UTC [32498][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-12 07:38:16.446 UTC [32498][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert internal error
2024-03-12 07:38:16.470 UTC [32511][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39632
2024-03-12 07:38:16.476 UTC [32511][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-12 07:38:16.477 UTC [32511][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-12 07:38:16.495 UTC [32511][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-12 07:38:16.500 UTC [32511][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=ssltestuser database=trustdb host=localhost port=39632
2024-03-12 07:38:16.508 UTC [32470][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:16.508 UTC [32470][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:16.513 UTC [32470][postmaster] LOG:  background worker "logical replication launcher" (PID 32487) exited with exit code 1
2024-03-12 07:38:16.517 UTC [32479][checkpointer] LOG:  shutting down
2024-03-12 07:38:16.517 UTC [32479][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:16.518 UTC [32479][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/4196CB8, redo lsn=0/4196CB8
2024-03-12 07:38:16.524 UTC [32470][postmaster] LOG:  database system is shut down
2024-03-12 07:38:16.661 UTC [32526][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-12 07:38:16.661 UTC [32526][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:16.661 UTC [32526][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:16.689 UTC [32535][startup] LOG:  database system was shut down at 2024-03-12 07:38:16 UTC
2024-03-12 07:38:16.699 UTC [32526][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:16.810 UTC [32576][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39634
2024-03-12 07:38:16.817 UTC [32576][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-12 07:38:16.824 UTC [32576][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-12 07:38:16.829 UTC [32576][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-12 07:38:16.920 UTC [32596][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39636
2024-03-12 07:38:16.932 UTC [32596][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-12 07:38:16.932 UTC [32596][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-12 07:38:16.987 UTC [32596][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-12 07:38:16.993 UTC [32596][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.076 user=ssltestuser database=trustdb host=localhost port=39636
2024-03-12 07:38:17.005 UTC [32526][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:17.005 UTC [32526][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:17.013 UTC [32526][postmaster] LOG:  background worker "logical replication launcher" (PID 32544) exited with exit code 1
2024-03-12 07:38:17.038 UTC [32533][checkpointer] LOG:  shutting down
2024-03-12 07:38:17.038 UTC [32533][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:17.038 UTC [32533][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/4196D30, redo lsn=0/4196D30
2024-03-12 07:38:17.045 UTC [32526][postmaster] LOG:  database system is shut down
2024-03-12 07:38:17.288 UTC [32613][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-12 07:38:17.288 UTC [32613][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:17.288 UTC [32613][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:17.338 UTC [32626][startup] LOG:  database system was shut down at 2024-03-12 07:38:17 UTC
2024-03-12 07:38:17.379 UTC [32613][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:17.498 UTC [32649][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39646
2024-03-12 07:38:17.501 UTC [32649][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-12 07:38:17.501 UTC [32649][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-12 07:38:17.555 UTC [32652][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39660
2024-03-12 07:38:17.572 UTC [32652][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-12 07:38:17.572 UTC [32652][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-12 07:38:17.636 UTC [32652][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-12 07:38:17.637 UTC [32652][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.084 user=ssltestuser database=trustdb host=localhost port=39660
2024-03-12 07:38:17.655 UTC [32613][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:17.655 UTC [32613][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:17.664 UTC [32613][postmaster] LOG:  background worker "logical replication launcher" (PID 32639) exited with exit code 1
2024-03-12 07:38:17.665 UTC [32624][checkpointer] LOG:  shutting down
2024-03-12 07:38:17.665 UTC [32624][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:17.666 UTC [32624][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/4196DA8, redo lsn=0/4196DA8
2024-03-12 07:38:17.673 UTC [32613][postmaster] LOG:  database system is shut down
2024-03-12 07:38:17.854 UTC [32658][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-12 07:38:17.854 UTC [32658][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:17.854 UTC [32658][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:17.891 UTC [32662][startup] LOG:  database system was shut down at 2024-03-12 07:38:17 UTC
2024-03-12 07:38:17.924 UTC [32658][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:18.002 UTC [32671][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39662
2024-03-12 07:38:18.006 UTC [32671][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-12 07:38:18.006 UTC [32671][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-12 07:38:18.082 UTC [32678][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39672
2024-03-12 07:38:18.098 UTC [32678][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-12 07:38:18.098 UTC [32678][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-12 07:38:18.159 UTC [32678][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-12 07:38:18.164 UTC [32678][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.085 user=ssltestuser database=trustdb host=localhost port=39672
2024-03-12 07:38:18.172 UTC [32658][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:18.172 UTC [32658][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:18.177 UTC [32658][postmaster] LOG:  background worker "logical replication launcher" (PID 32667) exited with exit code 1
2024-03-12 07:38:18.190 UTC [32660][checkpointer] LOG:  shutting down
2024-03-12 07:38:18.190 UTC [32660][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:18.191 UTC [32660][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/4196E20, redo lsn=0/4196E20
2024-03-12 07:38:18.197 UTC [32658][postmaster] LOG:  database system is shut down
2024-03-12 07:38:18.356 UTC [32696][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-12 07:38:18.356 UTC [32696][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:18.356 UTC [32696][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:18.369 UTC [32703][startup] LOG:  database system was shut down at 2024-03-12 07:38:18 UTC
2024-03-12 07:38:18.383 UTC [32696][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:18.453 UTC [32714][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39688
2024-03-12 07:38:18.457 UTC [32714][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-12 07:38:18.457 UTC [32714][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-12 07:38:18.511 UTC [32721][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39690
2024-03-12 07:38:18.532 UTC [32721][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-12 07:38:18.532 UTC [32721][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-12 07:38:18.566 UTC [32721][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-12 07:38:18.570 UTC [32721][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.060 user=ssltestuser database=trustdb host=localhost port=39690
2024-03-12 07:38:18.589 UTC [32696][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:18.589 UTC [32696][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:18.605 UTC [32696][postmaster] LOG:  background worker "logical replication launcher" (PID 32710) exited with exit code 1
2024-03-12 07:38:18.607 UTC [32701][checkpointer] LOG:  shutting down
2024-03-12 07:38:18.607 UTC [32701][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:18.608 UTC [32701][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/4196E98, redo lsn=0/4196E98
2024-03-12 07:38:18.614 UTC [32696][postmaster] LOG:  database system is shut down
2024-03-12 07:38:18.678 UTC [32744][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-12 07:38:18.678 UTC [32744][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 56269
2024-03-12 07:38:18.678 UTC [32744][postmaster] LOG:  listening on Unix socket "/tmp/7aj4gFw1rW/.s.PGSQL.56269"
2024-03-12 07:38:18.703 UTC [32751][startup] LOG:  database system was shut down at 2024-03-12 07:38:18 UTC
2024-03-12 07:38:18.714 UTC [32744][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:38:18.746 UTC [32767][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39700
2024-03-12 07:38:18.759 UTC [32767][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-12 07:38:18.759 UTC [32767][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-12 07:38:18.798 UTC [32782][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39702
2024-03-12 07:38:18.807 UTC [32782][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-12 07:38:18.807 UTC [32782][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-12 07:38:18.847 UTC [32782][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-12 07:38:18.852 UTC [32782][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.055 user=ssltestuser database=trustdb host=localhost port=39702
2024-03-12 07:38:18.859 UTC [32744][postmaster] LOG:  received fast shutdown request
2024-03-12 07:38:18.859 UTC [32744][postmaster] LOG:  aborting any active transactions
2024-03-12 07:38:18.868 UTC [32744][postmaster] LOG:  background worker "logical replication launcher" (PID 32757) exited with exit code 1
2024-03-12 07:38:18.868 UTC [32749][checkpointer] LOG:  shutting down
2024-03-12 07:38:18.868 UTC [32749][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:38:18.869 UTC [32749][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/4196F10, redo lsn=0/4196F10
2024-03-12 07:38:18.875 UTC [32744][postmaster] LOG:  database system is shut down
2024-03-12 07:38:19.026 UTC [32798][postmaster] FATAL:  could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory
2024-03-12 07:38:19.026 UTC [32798][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[07:38:19.061](0.210s) Bail out!  pg_ctl restart failed
# No postmaster PID for node "primary"