[08:08:40.766](0.041s) # setting up data directory
# Checking port 55014
# Found port 55014
Name: primary
Data directory: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/backup
Archive directory: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/archives
Connection string: port=55014 host=/tmp/iYp9tCK5SO
Log file: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log
[08:08:40.769](0.003s) # initializing database system by copying initdb template
# Running: cp -RPp /tmp/cirrus-ci-build/build/tmp_install/initdb-template /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata
# Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata
### Starting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log -o --cluster-name=primary start
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 37492
[08:08:40.997](0.228s) ok 1 - ssl_library parameter
psql:<stdin>:1: WARNING:  roles created by regression test cases should have names starting with "regress_"
psql:<stdin>:1: WARNING:  roles created by regression test cases should have names starting with "regress_"
psql:<stdin>:1: WARNING:  roles created by regression test cases should have names starting with "regress_"
psql:<stdin>:1: WARNING:  roles created by regression test cases should have names starting with "regress_"
psql:<stdin>:1: WARNING:  databases created by regression test cases should have names including "regression"
psql:<stdin>:1: WARNING:  databases created by regression test cases should have names including "regression"
psql:<stdin>:1: WARNING:  databases created by regression test cases should have names including "regression"
psql:<stdin>:1: WARNING:  databases created by regression test cases should have names including "regression"
psql:<stdin>:1: WARNING:  databases created by regression test cases should have names including "regression"
psql:<stdin>:1: WARNING:  databases created by regression test cases should have names including "regression"
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 37742
[08:08:41.829](0.832s) # testing password-protected keys
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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-30 08:08:40.880 UTC [37492][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:40.880 UTC [37492][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:40.883 UTC [37496][startup] LOG:  database system was shut down at 2024-03-30 08:06:20 UTC
2024-03-30 08:08:40.886 UTC [37492][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:40.990 UTC [37532][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:40.990 UTC [37532][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:40.990 UTC [37532][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:40.995 UTC [37532][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-30 08:08:40.996 UTC [37532][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.004 UTC [37544][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.004 UTC [37544][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.004 UTC [37544][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.005 UTC [37544][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-30 08:08:41.005 UTC [37544][client backend] [001_ssltests.pl][1/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-30 08:08:41.006 UTC [37544][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.048 UTC [37552][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.049 UTC [37552][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.049 UTC [37552][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.050 UTC [37552][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-30 08:08:41.050 UTC [37552][client backend] [001_ssltests.pl][2/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-30 08:08:41.050 UTC [37552][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.093 UTC [37559][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.093 UTC [37559][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.093 UTC [37559][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.094 UTC [37559][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-30 08:08:41.094 UTC [37559][client backend] [001_ssltests.pl][3/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-30 08:08:41.094 UTC [37559][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.137 UTC [37568][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.137 UTC [37568][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.137 UTC [37568][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.138 UTC [37568][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-30 08:08:41.138 UTC [37568][client backend] [001_ssltests.pl][4/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-30 08:08:41.138 UTC [37568][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.199 UTC [37590][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.199 UTC [37590][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.199 UTC [37590][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.200 UTC [37590][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-30 08:08:41.200 UTC [37590][client backend] [001_ssltests.pl][5/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-30 08:08:41.231 UTC [37590][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.261 UTC [37604][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.261 UTC [37604][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.261 UTC [37604][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.262 UTC [37604][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-30 08:08:41.262 UTC [37604][client backend] [001_ssltests.pl][6/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-30 08:08:41.341 UTC [37604][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.080 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.383 UTC [37642][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.384 UTC [37642][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.384 UTC [37642][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.384 UTC [37642][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-30 08:08:41.384 UTC [37642][client backend] [001_ssltests.pl][7/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-30 08:08:41.413 UTC [37642][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.453 UTC [37655][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.454 UTC [37655][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.454 UTC [37655][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.458 UTC [37655][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-30 08:08:41.458 UTC [37655][client backend] [001_ssltests.pl][8/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-30 08:08:41.488 UTC [37655][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.035 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.503 UTC [37665][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.504 UTC [37665][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.504 UTC [37665][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.505 UTC [37665][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-30 08:08:41.505 UTC [37665][client backend] [001_ssltests.pl][9/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-30 08:08:41.533 UTC [37665][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.556 UTC [37681][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.556 UTC [37681][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.556 UTC [37681][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.562 UTC [37681][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-30 08:08:41.562 UTC [37681][client backend] [001_ssltests.pl][10/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-30 08:08:41.588 UTC [37681][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.620 UTC [37492][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:41.620 UTC [37492][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:41.624 UTC [37492][postmaster] LOG:  background worker "logical replication launcher" (PID 37499) exited with exit code 1
2024-03-30 08:08:41.624 UTC [37494][checkpointer] LOG:  shutting down
2024-03-30 08:08:41.625 UTC [37494][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:41.695 UTC [37494][checkpointer] LOG:  checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.070 s, sync=0.001 s, total=0.071 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4100A58, redo lsn=0/4100A58
2024-03-30 08:08:41.699 UTC [37492][postmaster] LOG:  database system is shut down
2024-03-30 08:08:41.734 UTC [37742][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:41.734 UTC [37742][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:41.735 UTC [37742][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:41.738 UTC [37749][startup] LOG:  database system was shut down at 2024-03-30 08:08:41 UTC
2024-03-30 08:08:41.741 UTC [37742][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:41.832 UTC [37742][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:41.832 UTC [37742][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:41.835 UTC [37742][postmaster] LOG:  background worker "logical replication launcher" (PID 37753) exited with exit code 1
2024-03-30 08:08:41.835 UTC [37747][checkpointer] LOG:  shutting down
2024-03-30 08:08:41.835 UTC [37747][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:41.835 UTC [37747][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/4100AD0, redo lsn=0/4100AD0
2024-03-30 08:08:41.837 UTC [37742][postmaster] LOG:  database system is shut down
2024-03-30 08:08:41.948 UTC [37812][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-30 08:08:41.948 UTC [37812][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[08:08:42.040](0.211s) ok 2 - restart fails with password-protected key file with wrong password
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart
pg_ctl: PID file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/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 37833
[08:08:42.149](0.109s) ok 3 - restart succeeds with password-protected key file
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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-30 08:08:40.880 UTC [37492][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:40.880 UTC [37492][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:40.883 UTC [37496][startup] LOG:  database system was shut down at 2024-03-30 08:06:20 UTC
2024-03-30 08:08:40.886 UTC [37492][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:40.990 UTC [37532][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:40.990 UTC [37532][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:40.990 UTC [37532][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:40.995 UTC [37532][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-30 08:08:40.996 UTC [37532][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.004 UTC [37544][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.004 UTC [37544][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.004 UTC [37544][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.005 UTC [37544][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-30 08:08:41.005 UTC [37544][client backend] [001_ssltests.pl][1/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-30 08:08:41.006 UTC [37544][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.048 UTC [37552][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.049 UTC [37552][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.049 UTC [37552][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.050 UTC [37552][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-30 08:08:41.050 UTC [37552][client backend] [001_ssltests.pl][2/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-30 08:08:41.050 UTC [37552][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.093 UTC [37559][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.093 UTC [37559][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.093 UTC [37559][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.094 UTC [37559][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-30 08:08:41.094 UTC [37559][client backend] [001_ssltests.pl][3/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-30 08:08:41.094 UTC [37559][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.137 UTC [37568][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.137 UTC [37568][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.137 UTC [37568][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.138 UTC [37568][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-30 08:08:41.138 UTC [37568][client backend] [001_ssltests.pl][4/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-30 08:08:41.138 UTC [37568][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.199 UTC [37590][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.199 UTC [37590][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.199 UTC [37590][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.200 UTC [37590][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-30 08:08:41.200 UTC [37590][client backend] [001_ssltests.pl][5/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-30 08:08:41.231 UTC [37590][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.261 UTC [37604][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.261 UTC [37604][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.261 UTC [37604][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.262 UTC [37604][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-30 08:08:41.262 UTC [37604][client backend] [001_ssltests.pl][6/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-30 08:08:41.341 UTC [37604][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.080 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.383 UTC [37642][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.384 UTC [37642][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.384 UTC [37642][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.384 UTC [37642][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-30 08:08:41.384 UTC [37642][client backend] [001_ssltests.pl][7/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-30 08:08:41.413 UTC [37642][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.453 UTC [37655][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.454 UTC [37655][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.454 UTC [37655][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.458 UTC [37655][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-30 08:08:41.458 UTC [37655][client backend] [001_ssltests.pl][8/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-30 08:08:41.488 UTC [37655][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.035 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.503 UTC [37665][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.504 UTC [37665][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.504 UTC [37665][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.505 UTC [37665][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-30 08:08:41.505 UTC [37665][client backend] [001_ssltests.pl][9/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-30 08:08:41.533 UTC [37665][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.556 UTC [37681][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.556 UTC [37681][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.556 UTC [37681][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.562 UTC [37681][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-30 08:08:41.562 UTC [37681][client backend] [001_ssltests.pl][10/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-30 08:08:41.588 UTC [37681][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.620 UTC [37492][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:41.620 UTC [37492][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:41.624 UTC [37492][postmaster] LOG:  background worker "logical replication launcher" (PID 37499) exited with exit code 1
2024-03-30 08:08:41.624 UTC [37494][checkpointer] LOG:  shutting down
2024-03-30 08:08:41.625 UTC [37494][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:41.695 UTC [37494][checkpointer] LOG:  checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.070 s, sync=0.001 s, total=0.071 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4100A58, redo lsn=0/4100A58
2024-03-30 08:08:41.699 UTC [37492][postmaster] LOG:  database system is shut down
2024-03-30 08:08:41.734 UTC [37742][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:41.734 UTC [37742][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:41.735 UTC [37742][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:41.738 UTC [37749][startup] LOG:  database system was shut down at 2024-03-30 08:08:41 UTC
2024-03-30 08:08:41.741 UTC [37742][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:41.832 UTC [37742][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:41.832 UTC [37742][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:41.835 UTC [37742][postmaster] LOG:  background worker "logical replication launcher" (PID 37753) exited with exit code 1
2024-03-30 08:08:41.835 UTC [37747][checkpointer] LOG:  shutting down
2024-03-30 08:08:41.835 UTC [37747][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:41.835 UTC [37747][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/4100AD0, redo lsn=0/4100AD0
2024-03-30 08:08:41.837 UTC [37742][postmaster] LOG:  database system is shut down
2024-03-30 08:08:41.948 UTC [37812][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-30 08:08:41.948 UTC [37812][postmaster] LOG:  database system is shut down
2024-03-30 08:08:42.057 UTC [37833][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:42.057 UTC [37833][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:42.057 UTC [37833][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:42.060 UTC [37839][startup] LOG:  database system was shut down at 2024-03-30 08:08:41 UTC
2024-03-30 08:08:42.063 UTC [37833][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:42.152 UTC [37833][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:42.152 UTC [37833][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:42.154 UTC [37833][postmaster] LOG:  background worker "logical replication launcher" (PID 37842) exited with exit code 1
2024-03-30 08:08:42.154 UTC [37837][checkpointer] LOG:  shutting down
2024-03-30 08:08:42.154 UTC [37837][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:42.155 UTC [37837][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/4100B48, redo lsn=0/4100B48
2024-03-30 08:08:42.157 UTC [37833][postmaster] LOG:  database system is shut down
2024-03-30 08:08:42.269 UTC [37884][postmaster] FATAL:  could not set SSL protocol version range
2024-03-30 08:08:42.269 UTC [37884][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-30 08:08:42.269 UTC [37884][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[08:08:42.369](0.219s) ok 4 - restart fails with incorrect SSL protocol bounds
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart
pg_ctl: PID file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/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 37913
[08:08:42.474](0.105s) ok 5 - restart succeeds with correct SSL protocol bounds
[08:08:42.474](0.000s) # running client tests
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 37970
[08:08:42.692](0.218s) ok 6 - server doesn't accept non-SSL connections
[08:08:42.692](0.000s) ok 7 - server doesn't accept non-SSL connections: matches
[08:08:42.710](0.018s) ok 8 - connect without server root cert sslmode=require
[08:08:42.711](0.001s) ok 9 - connect without server root cert sslmode=require: no stderr
[08:08:42.720](0.010s) ok 10 - connect without server root cert sslmode=verify-ca
[08:08:42.720](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches
[08:08:42.729](0.009s) ok 12 - connect without server root cert sslmode=verify-full
[08:08:42.730](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches
[08:08:42.741](0.011s) ok 14 - connect with wrong server root cert sslmode=require
[08:08:42.741](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches
[08:08:42.752](0.011s) ok 16 - connect with wrong server root cert sslmode=verify-ca
[08:08:42.752](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches
[08:08:42.762](0.010s) ok 18 - connect with wrong server root cert sslmode=verify-full
[08:08:42.762](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches
[08:08:42.773](0.011s) ok 20 - connect with server CA cert, without root CA
[08:08:42.774](0.000s) ok 21 - connect with server CA cert, without root CA: matches
[08:08:42.786](0.012s) ok 22 - connect with correct server CA cert file sslmode=require
[08:08:42.786](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr
[08:08:42.799](0.013s) ok 24 - connect with correct server CA cert file sslmode=verify-ca
[08:08:42.799](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr
[08:08:42.812](0.012s) ok 26 - connect with correct server CA cert file sslmode=verify-full
[08:08:42.812](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr
[08:08:42.825](0.013s) ok 28 - cert root file that contains two certificates, order 1
[08:08:42.825](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr
[08:08:42.839](0.014s) ok 30 - cert root file that contains two certificates, order 2
[08:08:42.840](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr
[08:08:42.853](0.013s) ok 32 - connect with sslcertmode=disable
[08:08:42.853](0.000s) ok 33 - connect with sslcertmode=disable: no stderr
[08:08:42.866](0.013s) ok 34 - connect with sslcertmode=allow
[08:08:42.867](0.000s) ok 35 - connect with sslcertmode=allow: no stderr
[08:08:42.879](0.012s) ok 36 - connect with sslcertmode=require fails without a client certificate
[08:08:42.879](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches
[08:08:42.892](0.013s) ok 38 - sslcrl option with invalid file name
[08:08:42.892](0.000s) ok 39 - sslcrl option with invalid file name: no stderr
[08:08:42.904](0.011s) ok 40 - CRL belonging to a different CA
[08:08:42.904](0.000s) ok 41 - CRL belonging to a different CA: matches
[08:08:42.917](0.013s) ok 42 - directory CRL belonging to a different CA
[08:08:42.917](0.000s) ok 43 - directory CRL belonging to a different CA: matches
[08:08:42.931](0.014s) ok 44 - CRL with a non-revoked cert
[08:08:42.931](0.000s) ok 45 - CRL with a non-revoked cert: no stderr
[08:08:42.945](0.014s) ok 46 - directory CRL with a non-revoked cert
[08:08:42.946](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr
[08:08:42.960](0.014s) ok 48 - mismatch between host name and server certificate sslmode=require
[08:08:42.960](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr
[08:08:42.974](0.014s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca
[08:08:42.975](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr
[08:08:42.988](0.013s) ok 52 - mismatch between host name and server certificate sslmode=verify-full
[08:08:42.988](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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38087
[08:08:43.222](0.234s) ok 54 - IP address in the Common Name
[08:08:43.222](0.000s) ok 55 - IP address in the Common Name: no stderr
[08:08:43.235](0.013s) ok 56 - mismatch between host name and server certificate IP address
[08:08:43.235](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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38111
[08:08:43.469](0.234s) ok 58 - IP address in a dNSName
[08:08:43.469](0.000s) ok 59 - IP address in a dNSName: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38136
[08:08:43.697](0.228s) ok 60 - host name matching with X.509 Subject Alternative Names 1
[08:08:43.697](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr
[08:08:43.711](0.014s) ok 62 - host name matching with X.509 Subject Alternative Names 2
[08:08:43.711](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr
[08:08:43.726](0.015s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard
[08:08:43.726](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr
[08:08:43.739](0.012s) ok 66 - host name not matching with X.509 Subject Alternative Names
[08:08:43.739](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches
[08:08:43.752](0.013s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard
[08:08:43.752](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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38171
[08:08:43.978](0.226s) ok 70 - host name matching with a single X.509 Subject Alternative Name
[08:08:43.978](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr
[08:08:43.991](0.013s) ok 72 - host name not matching with a single X.509 Subject Alternative Name
[08:08:43.992](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches
[08:08:44.004](0.012s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard
[08:08:44.004](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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38200
[08:08:44.236](0.232s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1)
[08:08:44.236](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr
[08:08:44.250](0.014s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1)
[08:08:44.251](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr
[08:08:44.263](0.012s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1)
[08:08:44.263](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches
[08:08:44.278](0.015s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2)
[08:08:44.279](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr
[08:08:44.292](0.013s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2)
[08:08:44.292](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr
[08:08:44.307](0.014s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2)
[08:08:44.307](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr
[08:08:44.320](0.013s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2)
[08:08:44.320](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches
[08:08:44.331](0.011s) ok 90 - IPv6 host with CIDR mask does not match
[08:08:44.332](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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38238
[08:08:44.554](0.223s) ok 92 - certificate with both a CN and SANs 1
[08:08:44.555](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr
[08:08:44.567](0.013s) ok 94 - certificate with both a CN and SANs 2
[08:08:44.568](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr
[08:08:44.580](0.013s) ok 96 - certificate with both a CN and SANs ignores CN
[08:08:44.581](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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38255
[08:08:44.816](0.235s) ok 98 - certificate with both a CN and IP SANs matches CN
[08:08:44.816](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr
[08:08:44.831](0.015s) ok 100 - certificate with both a CN and IP SANs matches SAN 1
[08:08:44.832](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr
[08:08:44.845](0.014s) ok 102 - certificate with both a CN and IP SANs matches SAN 2
[08:08:44.846](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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38273
[08:08:45.067](0.222s) ok 104 - certificate with both an IP CN and IP SANs 1
[08:08:45.068](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr
[08:08:45.082](0.014s) ok 106 - certificate with both an IP CN and IP SANs 2
[08:08:45.082](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr
[08:08:45.094](0.012s) ok 108 - certificate with both an IP CN and IP SANs ignores CN
[08:08:45.094](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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38289
[08:08:45.326](0.231s) ok 110 - certificate with both an IP CN and DNS SANs matches CN
[08:08:45.326](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr
[08:08:45.340](0.014s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1
[08:08:45.341](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr
[08:08:45.355](0.014s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2
[08:08:45.355](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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38306
[08:08:45.589](0.234s) ok 116 - server certificate without CN or SANs sslmode=verify-ca
[08:08:45.589](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr
[08:08:45.601](0.012s) ok 118 - server certificate without CN or SANs sslmode=verify-full
[08:08:45.601](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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38318
[08:08:45.829](0.228s) ok 120 - sslrootcert=system does not connect with private CA
[08:08:45.829](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches
[08:08:45.836](0.006s) ok 122 - sslrootcert=system only accepts sslmode=verify-full
[08:08:45.836](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches
[08:08:45.855](0.019s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE
[08:08:45.855](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr
[08:08:45.867](0.012s) ok 126 - sslrootcert=system defaults to sslmode=verify-full
[08:08:45.867](0.000s) ok 127 - sslrootcert=system defaults to sslmode=verify-full: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38333
[08:08:46.101](0.234s) ok 128 - connects without client-side CRL
[08:08:46.101](0.000s) ok 129 - connects without client-side CRL: no stderr
[08:08:46.113](0.012s) ok 130 - does not connect with client-side CRL file
[08:08:46.113](0.000s) ok 131 - does not connect with client-side CRL file: matches
[08:08:46.126](0.012s) ok 132 - does not connect with client-side CRL directory
[08:08:46.126](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()
[08:08:46.140](0.015s) ok 134 - pg_stat_ssl view without client certificate: exit code 0
[08:08:46.141](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr
[08:08:46.141](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches
[08:08:46.154](0.014s) ok 137 - connection success with correct range of TLS protocol versions
[08:08:46.155](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr
[08:08:46.160](0.006s) ok 139 - connection failure with incorrect range of TLS protocol versions
[08:08:46.161](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches
[08:08:46.167](0.006s) ok 141 - connection failure with an incorrect SSL protocol minimum bound
[08:08:46.167](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches
[08:08:46.173](0.006s) ok 143 - connection failure with an incorrect SSL protocol maximum bound
[08:08:46.173](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches
[08:08:46.173](0.000s) # running server tests
[08:08:46.186](0.013s) ok 145 - certificate authorization fails without client cert
[08:08:46.186](0.000s) ok 146 - certificate authorization fails without client cert: matches
[08:08:46.204](0.018s) ok 147 - certificate authorization succeeds with correct client cert in PEM format
[08:08:46.205](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr
[08:08:46.220](0.016s) ok 149 - certificate authorization succeeds with correct client cert in DER format
[08:08:46.221](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr
[08:08:46.238](0.017s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format
[08:08:46.238](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr
[08:08:46.254](0.015s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format
[08:08:46.254](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr
[08:08:46.271](0.016s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require
[08:08:46.271](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr
[08:08:46.287](0.016s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow
[08:08:46.287](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr
[08:08:46.300](0.013s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable
[08:08:46.300](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches
[08:08:46.312](0.011s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format
[08:08:46.312](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches
[08:08:46.331](0.019s) ok 163 - certificate authorization succeeds with DN mapping
[08:08:46.331](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr
[08:08:46.331](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches
[08:08:46.352](0.021s) ok 166 - certificate authorization succeeds with DN regex mapping
[08:08:46.352](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr
[08:08:46.372](0.020s) ok 168 - certificate authorization succeeds with CN mapping
[08:08:46.372](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr
[08:08:46.372](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches
[08:08:46.373](0.000s) not ok 171 # TODO & SKIP Need Pty support
[08:08:46.373](0.000s) not ok 172 # TODO & SKIP Need Pty support
[08:08:46.373](0.000s) not ok 173 # TODO & SKIP Need Pty support
[08:08:46.373](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/build/testrun/ssl/001_ssltests/data/tmp_test_ZxXc/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
[08:08:46.409](0.036s) ok 175 - pg_stat_ssl with client certificate: exit code 0
[08:08:46.409](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr
[08:08:46.410](0.000s) ok 177 - pg_stat_ssl with client certificate: matches
[08:08:46.419](0.009s) ok 178 - certificate authorization fails because of file permissions
[08:08:46.419](0.000s) ok 179 - certificate authorization fails because of file permissions: matches
[08:08:46.434](0.015s) ok 180 - certificate authorization fails with client cert belonging to another user
[08:08:46.434](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches
[08:08:46.448](0.014s) ok 182 - certificate authorization fails with revoked client cert
[08:08:46.448](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches
[08:08:46.448](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match
[08:08:46.467](0.019s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name
[08:08:46.467](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr
[08:08:46.467](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches
[08:08:46.482](0.015s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name
[08:08:46.482](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches
[08:08:46.482](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match
[08:08:46.498](0.016s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name
[08:08:46.498](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr
[08:08:46.499](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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38393
[08:08:46.735](0.236s) ok 194 - intermediate client certificate is provided by client
[08:08:46.735](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr
[08:08:46.749](0.014s) ok 196 - intermediate client certificate is missing
[08:08:46.750](0.000s) ok 197 - intermediate client certificate is missing: matches
[08:08:46.763](0.013s) ok 198 - logged client certificate Subjects are truncated if they're too long
[08:08:46.763](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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38407
[08:08:46.993](0.230s) ok 200 - intermediate client certificate is untrusted
[08:08:46.994](0.000s) ok 201 - intermediate client certificate is untrusted: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38417
[08:08:47.224](0.230s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory
[08:08:47.224](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches
[08:08:47.236](0.012s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory
[08:08:47.236](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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38429
[08:08:47.463](0.227s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1
[08:08:47.463](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1'
#   at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 923.
[08:08:47.463](0.000s) #          got: '2'
#     expected: '0'
[08:08:47.464](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[08:08:47.464](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr'
#   at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 923.
[08:08:47.464](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 55014 failed: SSL SYSCALL error: EOF detected'
#     expected: ''
[08:08:47.482](0.018s) ok 208 - connect without requesting ocsp response when sslocspstapling=0
[08:08:47.482](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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38441
[08:08:47.708](0.226s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1
[08:08:47.723](0.015s) ok 211 - connect without requesting ocsp response when sslocspstapling=0
[08:08:47.723](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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38453
[08:08:47.948](0.225s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1
[08:08:47.964](0.016s) ok 214 - connect without requesting ocsp response when sslocspstapling=0
[08:08:47.964](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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38465
[08:08:48.191](0.227s) ok 216 - failed with an expired ocsp response when sslocspstapling=1
[08:08:48.208](0.018s) ok 217 - connect without requesting ocsp response when sslocspstapling=0
[08:08:48.209](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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38477
[08:08:48.434](0.226s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1
[08:08:48.435](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1'
#   at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 1001.
[08:08:48.435](0.000s) #          got: '2'
#     expected: '0'
[08:08:48.435](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[08:08:48.435](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr'
#   at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 1001.
[08:08:48.435](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 55014 failed: SSL SYSCALL error: EOF detected'
#     expected: ''
[08:08:48.453](0.018s) ok 221 - connect without requesting ocsp response when sslocspstapling=0
[08:08:48.453](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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38489
[08:08:48.675](0.222s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1
[08:08:48.691](0.016s) ok 224 - connect without requesting ocsp response when sslocspstapling=0
[08:08:48.691](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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38501
[08:08:48.916](0.225s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1
[08:08:48.932](0.016s) ok 227 - connect without requesting ocsp response when sslocspstapling=0
[08:08:48.933](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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38513
[08:08:49.159](0.226s) ok 229 - failed with an expired ocsp response when sslocspstapling=1
[08:08:49.175](0.017s) ok 230 - connect without requesting ocsp response when sslocspstapling=0
[08:08:49.176](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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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 38525
[08:08:49.402](0.226s) ok 232 - failed with an expired ocsp response when sslocspstapling=1
[08:08:49.420](0.018s) ok 233 - connect without requesting ocsp response when sslocspstapling=0
[08:08:49.420](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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/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-30 08:08:40.880 UTC [37492][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:40.880 UTC [37492][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:40.883 UTC [37496][startup] LOG:  database system was shut down at 2024-03-30 08:06:20 UTC
2024-03-30 08:08:40.886 UTC [37492][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:40.990 UTC [37532][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:40.990 UTC [37532][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:40.990 UTC [37532][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:40.995 UTC [37532][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-30 08:08:40.996 UTC [37532][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.004 UTC [37544][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.004 UTC [37544][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.004 UTC [37544][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.005 UTC [37544][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-30 08:08:41.005 UTC [37544][client backend] [001_ssltests.pl][1/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-30 08:08:41.006 UTC [37544][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.048 UTC [37552][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.049 UTC [37552][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.049 UTC [37552][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.050 UTC [37552][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-30 08:08:41.050 UTC [37552][client backend] [001_ssltests.pl][2/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-30 08:08:41.050 UTC [37552][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.093 UTC [37559][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.093 UTC [37559][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.093 UTC [37559][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.094 UTC [37559][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-30 08:08:41.094 UTC [37559][client backend] [001_ssltests.pl][3/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-30 08:08:41.094 UTC [37559][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.137 UTC [37568][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.137 UTC [37568][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.137 UTC [37568][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.138 UTC [37568][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-30 08:08:41.138 UTC [37568][client backend] [001_ssltests.pl][4/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-30 08:08:41.138 UTC [37568][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.199 UTC [37590][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.199 UTC [37590][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.199 UTC [37590][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.200 UTC [37590][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-30 08:08:41.200 UTC [37590][client backend] [001_ssltests.pl][5/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-30 08:08:41.231 UTC [37590][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.261 UTC [37604][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.261 UTC [37604][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.261 UTC [37604][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.262 UTC [37604][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-30 08:08:41.262 UTC [37604][client backend] [001_ssltests.pl][6/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-30 08:08:41.341 UTC [37604][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.080 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.383 UTC [37642][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.384 UTC [37642][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.384 UTC [37642][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.384 UTC [37642][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-30 08:08:41.384 UTC [37642][client backend] [001_ssltests.pl][7/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-30 08:08:41.413 UTC [37642][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.453 UTC [37655][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.454 UTC [37655][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.454 UTC [37655][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.458 UTC [37655][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-30 08:08:41.458 UTC [37655][client backend] [001_ssltests.pl][8/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-30 08:08:41.488 UTC [37655][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.035 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.503 UTC [37665][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.504 UTC [37665][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.504 UTC [37665][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.505 UTC [37665][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-30 08:08:41.505 UTC [37665][client backend] [001_ssltests.pl][9/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-30 08:08:41.533 UTC [37665][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.556 UTC [37681][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-30 08:08:41.556 UTC [37681][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-30 08:08:41.556 UTC [37681][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-30 08:08:41.562 UTC [37681][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-30 08:08:41.562 UTC [37681][client backend] [001_ssltests.pl][10/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-30 08:08:41.588 UTC [37681][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=postgres database=postgres host=[local]
2024-03-30 08:08:41.620 UTC [37492][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:41.620 UTC [37492][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:41.624 UTC [37492][postmaster] LOG:  background worker "logical replication launcher" (PID 37499) exited with exit code 1
2024-03-30 08:08:41.624 UTC [37494][checkpointer] LOG:  shutting down
2024-03-30 08:08:41.625 UTC [37494][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:41.695 UTC [37494][checkpointer] LOG:  checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.070 s, sync=0.001 s, total=0.071 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4100A58, redo lsn=0/4100A58
2024-03-30 08:08:41.699 UTC [37492][postmaster] LOG:  database system is shut down
2024-03-30 08:08:41.734 UTC [37742][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:41.734 UTC [37742][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:41.735 UTC [37742][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:41.738 UTC [37749][startup] LOG:  database system was shut down at 2024-03-30 08:08:41 UTC
2024-03-30 08:08:41.741 UTC [37742][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:41.832 UTC [37742][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:41.832 UTC [37742][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:41.835 UTC [37742][postmaster] LOG:  background worker "logical replication launcher" (PID 37753) exited with exit code 1
2024-03-30 08:08:41.835 UTC [37747][checkpointer] LOG:  shutting down
2024-03-30 08:08:41.835 UTC [37747][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:41.835 UTC [37747][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/4100AD0, redo lsn=0/4100AD0
2024-03-30 08:08:41.837 UTC [37742][postmaster] LOG:  database system is shut down
2024-03-30 08:08:41.948 UTC [37812][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-30 08:08:41.948 UTC [37812][postmaster] LOG:  database system is shut down
2024-03-30 08:08:42.057 UTC [37833][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:42.057 UTC [37833][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:42.057 UTC [37833][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:42.060 UTC [37839][startup] LOG:  database system was shut down at 2024-03-30 08:08:41 UTC
2024-03-30 08:08:42.063 UTC [37833][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:42.152 UTC [37833][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:42.152 UTC [37833][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:42.154 UTC [37833][postmaster] LOG:  background worker "logical replication launcher" (PID 37842) exited with exit code 1
2024-03-30 08:08:42.154 UTC [37837][checkpointer] LOG:  shutting down
2024-03-30 08:08:42.154 UTC [37837][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:42.155 UTC [37837][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/4100B48, redo lsn=0/4100B48
2024-03-30 08:08:42.157 UTC [37833][postmaster] LOG:  database system is shut down
2024-03-30 08:08:42.269 UTC [37884][postmaster] FATAL:  could not set SSL protocol version range
2024-03-30 08:08:42.269 UTC [37884][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-30 08:08:42.269 UTC [37884][postmaster] LOG:  database system is shut down
2024-03-30 08:08:42.387 UTC [37913][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:42.387 UTC [37913][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:42.387 UTC [37913][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:42.389 UTC [37920][startup] LOG:  database system was shut down at 2024-03-30 08:08:42 UTC
2024-03-30 08:08:42.392 UTC [37913][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:42.476 UTC [37913][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:42.477 UTC [37913][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:42.479 UTC [37913][postmaster] LOG:  background worker "logical replication launcher" (PID 37924) exited with exit code 1
2024-03-30 08:08:42.480 UTC [37918][checkpointer] LOG:  shutting down
2024-03-30 08:08:42.480 UTC [37918][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:42.481 UTC [37918][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/4100BC0, redo lsn=0/4100BC0
2024-03-30 08:08:42.482 UTC [37913][postmaster] LOG:  database system is shut down
2024-03-30 08:08:42.590 UTC [37970][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:42.590 UTC [37970][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:42.590 UTC [37970][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:42.593 UTC [37975][startup] LOG:  database system was shut down at 2024-03-30 08:08:42 UTC
2024-03-30 08:08:42.596 UTC [37970][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:42.689 UTC [38010][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20833
2024-03-30 08:08:42.689 UTC [38010][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-30 08:08:42.689 UTC [38010][client backend] [[unknown]][0/1:0] DETAIL:  Client IP address resolved to "localhost", forward lookup not checked.
2024-03-30 08:08:42.698 UTC [38014][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20836
2024-03-30 08:08:42.703 UTC [38014][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:42.703 UTC [38014][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-30 08:08:42.708 UTC [38014][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-30 08:08:42.710 UTC [38014][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=20836
2024-03-30 08:08:42.718 UTC [38019][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20837
2024-03-30 08:08:42.719 UTC [38019][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-30 08:08:42.727 UTC [38021][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20838
2024-03-30 08:08:42.728 UTC [38021][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-30 08:08:42.735 UTC [38023][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20839
2024-03-30 08:08:42.739 UTC [38023][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-30 08:08:42.747 UTC [38025][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20840
2024-03-30 08:08:42.751 UTC [38025][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-30 08:08:42.757 UTC [38027][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20841
2024-03-30 08:08:42.761 UTC [38027][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-30 08:08:42.768 UTC [38029][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20842
2024-03-30 08:08:42.772 UTC [38029][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-30 08:08:42.779 UTC [38031][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20843
2024-03-30 08:08:42.784 UTC [38031][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:42.784 UTC [38031][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-30 08:08:42.784 UTC [38031][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-30 08:08:42.785 UTC [38031][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=20843
2024-03-30 08:08:42.792 UTC [38033][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20844
2024-03-30 08:08:42.797 UTC [38033][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:42.797 UTC [38033][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-30 08:08:42.797 UTC [38033][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-30 08:08:42.798 UTC [38033][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20844
2024-03-30 08:08:42.804 UTC [38039][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20845
2024-03-30 08:08:42.809 UTC [38039][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:42.809 UTC [38039][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-30 08:08:42.810 UTC [38039][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-30 08:08:42.810 UTC [38039][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20845
2024-03-30 08:08:42.818 UTC [38044][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20846
2024-03-30 08:08:42.822 UTC [38044][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:42.822 UTC [38044][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-30 08:08:42.823 UTC [38044][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-30 08:08:42.824 UTC [38044][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20846
2024-03-30 08:08:42.832 UTC [38047][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20847
2024-03-30 08:08:42.837 UTC [38047][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:42.837 UTC [38047][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-30 08:08:42.838 UTC [38047][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-30 08:08:42.838 UTC [38047][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20847
2024-03-30 08:08:42.846 UTC [38049][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20848
2024-03-30 08:08:42.850 UTC [38049][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:42.850 UTC [38049][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-30 08:08:42.851 UTC [38049][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-30 08:08:42.860 UTC [38053][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20849
2024-03-30 08:08:42.860 UTC [38049][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=20848
2024-03-30 08:08:42.864 UTC [38053][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:42.864 UTC [38053][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-30 08:08:42.865 UTC [38053][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-30 08:08:42.866 UTC [38053][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20849
2024-03-30 08:08:42.873 UTC [38055][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20850
2024-03-30 08:08:42.877 UTC [38055][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:42.877 UTC [38055][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-30 08:08:42.878 UTC [38055][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=20850
2024-03-30 08:08:42.885 UTC [38057][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20851
2024-03-30 08:08:42.889 UTC [38057][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:42.889 UTC [38057][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-30 08:08:42.890 UTC [38057][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-30 08:08:42.890 UTC [38057][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=20851
2024-03-30 08:08:42.898 UTC [38060][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20852
2024-03-30 08:08:42.902 UTC [38060][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-30 08:08:42.911 UTC [38063][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20854
2024-03-30 08:08:42.915 UTC [38063][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-30 08:08:42.924 UTC [38068][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20856
2024-03-30 08:08:42.928 UTC [38068][client backend] [[unknown]][11/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:42.928 UTC [38068][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-30 08:08:42.929 UTC [38068][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-30 08:08:42.929 UTC [38068][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20856
2024-03-30 08:08:42.938 UTC [38070][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20857
2024-03-30 08:08:42.943 UTC [38070][client backend] [[unknown]][12/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:42.943 UTC [38070][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-30 08:08:42.944 UTC [38070][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-30 08:08:42.944 UTC [38070][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20857
2024-03-30 08:08:42.952 UTC [38072][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20858
2024-03-30 08:08:42.957 UTC [38072][client backend] [[unknown]][13/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:42.957 UTC [38072][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-30 08:08:42.958 UTC [38072][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-30 08:08:42.958 UTC [38072][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20858
2024-03-30 08:08:42.967 UTC [38074][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20859
2024-03-30 08:08:42.972 UTC [38074][client backend] [[unknown]][14/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:42.972 UTC [38074][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-30 08:08:42.972 UTC [38074][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-30 08:08:42.973 UTC [38074][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20859
2024-03-30 08:08:42.981 UTC [38076][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20860
2024-03-30 08:08:42.990 UTC [37970][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:42.990 UTC [37970][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:42.992 UTC [37970][postmaster] LOG:  background worker "logical replication launcher" (PID 37983) exited with exit code 1
2024-03-30 08:08:42.992 UTC [37973][checkpointer] LOG:  shutting down
2024-03-30 08:08:42.992 UTC [37973][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:42.992 UTC [37973][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/4100C38, redo lsn=0/4100C38
2024-03-30 08:08:42.994 UTC [37970][postmaster] LOG:  database system is shut down
2024-03-30 08:08:43.109 UTC [38087][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:43.109 UTC [38087][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:43.109 UTC [38087][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:43.111 UTC [38090][startup] LOG:  database system was shut down at 2024-03-30 08:08:42 UTC
2024-03-30 08:08:43.114 UTC [38087][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:43.211 UTC [38100][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20862
2024-03-30 08:08:43.215 UTC [38100][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:43.215 UTC [38100][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-30 08:08:43.220 UTC [38100][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-30 08:08:43.220 UTC [38100][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20862
2024-03-30 08:08:43.229 UTC [38102][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20863
2024-03-30 08:08:43.237 UTC [38087][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:43.237 UTC [38087][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:43.239 UTC [38087][postmaster] LOG:  background worker "logical replication launcher" (PID 38093) exited with exit code 1
2024-03-30 08:08:43.239 UTC [38088][checkpointer] LOG:  shutting down
2024-03-30 08:08:43.239 UTC [38088][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:43.240 UTC [38088][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/4100CB0, redo lsn=0/4100CB0
2024-03-30 08:08:43.242 UTC [38087][postmaster] LOG:  database system is shut down
2024-03-30 08:08:43.355 UTC [38111][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:43.355 UTC [38111][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:43.355 UTC [38111][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:43.357 UTC [38117][startup] LOG:  database system was shut down at 2024-03-30 08:08:43 UTC
2024-03-30 08:08:43.360 UTC [38111][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:43.458 UTC [38124][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20865
2024-03-30 08:08:43.463 UTC [38124][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:43.463 UTC [38124][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-30 08:08:43.467 UTC [38124][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-30 08:08:43.468 UTC [38124][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=20865
2024-03-30 08:08:43.472 UTC [38111][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:43.472 UTC [38111][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:43.473 UTC [38111][postmaster] LOG:  background worker "logical replication launcher" (PID 38121) exited with exit code 1
2024-03-30 08:08:43.474 UTC [38115][checkpointer] LOG:  shutting down
2024-03-30 08:08:43.474 UTC [38115][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:43.475 UTC [38115][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/4100D28, redo lsn=0/4100D28
2024-03-30 08:08:43.476 UTC [38111][postmaster] LOG:  database system is shut down
2024-03-30 08:08:43.584 UTC [38136][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:43.584 UTC [38136][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:43.584 UTC [38136][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:43.586 UTC [38140][startup] LOG:  database system was shut down at 2024-03-30 08:08:43 UTC
2024-03-30 08:08:43.588 UTC [38136][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:43.685 UTC [38147][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20867
2024-03-30 08:08:43.690 UTC [38147][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:43.690 UTC [38147][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-30 08:08:43.695 UTC [38147][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-30 08:08:43.695 UTC [38147][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=20867
2024-03-30 08:08:43.704 UTC [38151][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20868
2024-03-30 08:08:43.708 UTC [38151][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:43.708 UTC [38151][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-30 08:08:43.709 UTC [38151][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-30 08:08:43.710 UTC [38151][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20868
2024-03-30 08:08:43.718 UTC [38159][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20869
2024-03-30 08:08:43.723 UTC [38159][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:43.723 UTC [38159][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-30 08:08:43.724 UTC [38159][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-30 08:08:43.724 UTC [38159][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20869
2024-03-30 08:08:43.733 UTC [38161][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20870
2024-03-30 08:08:43.745 UTC [38163][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20871
2024-03-30 08:08:43.755 UTC [38136][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:43.755 UTC [38136][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:43.757 UTC [38136][postmaster] LOG:  background worker "logical replication launcher" (PID 38143) exited with exit code 1
2024-03-30 08:08:43.757 UTC [38138][checkpointer] LOG:  shutting down
2024-03-30 08:08:43.757 UTC [38138][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:43.758 UTC [38138][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/4100DA0, redo lsn=0/4100DA0
2024-03-30 08:08:43.760 UTC [38136][postmaster] LOG:  database system is shut down
2024-03-30 08:08:43.867 UTC [38171][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:43.867 UTC [38171][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:43.867 UTC [38171][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:43.869 UTC [38174][startup] LOG:  database system was shut down at 2024-03-30 08:08:43 UTC
2024-03-30 08:08:43.872 UTC [38171][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:43.967 UTC [38188][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20873
2024-03-30 08:08:43.972 UTC [38188][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:43.972 UTC [38188][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-30 08:08:43.976 UTC [38188][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-30 08:08:43.977 UTC [38188][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=20873
2024-03-30 08:08:43.985 UTC [38190][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20874
2024-03-30 08:08:43.998 UTC [38192][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20875
2024-03-30 08:08:44.010 UTC [38171][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:44.010 UTC [38171][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:44.011 UTC [38171][postmaster] LOG:  background worker "logical replication launcher" (PID 38177) exited with exit code 1
2024-03-30 08:08:44.011 UTC [38172][checkpointer] LOG:  shutting down
2024-03-30 08:08:44.011 UTC [38172][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:44.012 UTC [38172][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/4100E18, redo lsn=0/4100E18
2024-03-30 08:08:44.014 UTC [38171][postmaster] LOG:  database system is shut down
2024-03-30 08:08:44.121 UTC [38200][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:44.122 UTC [38200][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:44.122 UTC [38200][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:44.123 UTC [38203][startup] LOG:  database system was shut down at 2024-03-30 08:08:44 UTC
2024-03-30 08:08:44.127 UTC [38200][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:44.224 UTC [38217][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20877
2024-03-30 08:08:44.229 UTC [38217][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:44.229 UTC [38217][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-30 08:08:44.234 UTC [38217][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-30 08:08:44.235 UTC [38217][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=20877
2024-03-30 08:08:44.243 UTC [38219][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20878
2024-03-30 08:08:44.248 UTC [38219][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:44.248 UTC [38219][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-30 08:08:44.249 UTC [38219][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-30 08:08:44.249 UTC [38219][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20878
2024-03-30 08:08:44.257 UTC [38221][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20879
2024-03-30 08:08:44.270 UTC [38224][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20880
2024-03-30 08:08:44.275 UTC [38224][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:44.275 UTC [38224][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-30 08:08:44.276 UTC [38224][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-30 08:08:44.277 UTC [38224][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=20880
2024-03-30 08:08:44.285 UTC [38228][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20882
2024-03-30 08:08:44.289 UTC [38228][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:44.289 UTC [38228][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-30 08:08:44.290 UTC [38228][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-30 08:08:44.290 UTC [38228][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20882
2024-03-30 08:08:44.299 UTC [38230][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20883
2024-03-30 08:08:44.304 UTC [38230][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:44.304 UTC [38230][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-30 08:08:44.305 UTC [38230][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-30 08:08:44.305 UTC [38230][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20883
2024-03-30 08:08:44.314 UTC [38232][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20884
2024-03-30 08:08:44.326 UTC [38234][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20885
2024-03-30 08:08:44.333 UTC [38200][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:44.334 UTC [38200][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:44.335 UTC [38200][postmaster] LOG:  background worker "logical replication launcher" (PID 38206) exited with exit code 1
2024-03-30 08:08:44.335 UTC [38201][checkpointer] LOG:  shutting down
2024-03-30 08:08:44.335 UTC [38201][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:44.335 UTC [38201][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/4100E90, redo lsn=0/4100E90
2024-03-30 08:08:44.337 UTC [38200][postmaster] LOG:  database system is shut down
2024-03-30 08:08:44.446 UTC [38238][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:44.446 UTC [38238][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:44.446 UTC [38238][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:44.448 UTC [38241][startup] LOG:  database system was shut down at 2024-03-30 08:08:44 UTC
2024-03-30 08:08:44.452 UTC [38238][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:44.544 UTC [38247][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20886
2024-03-30 08:08:44.548 UTC [38247][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:44.548 UTC [38247][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-30 08:08:44.553 UTC [38247][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-30 08:08:44.553 UTC [38247][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20886
2024-03-30 08:08:44.560 UTC [38250][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20887
2024-03-30 08:08:44.565 UTC [38250][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:44.565 UTC [38250][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-30 08:08:44.566 UTC [38250][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-30 08:08:44.566 UTC [38250][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20887
2024-03-30 08:08:44.574 UTC [38252][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20888
2024-03-30 08:08:44.587 UTC [38238][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:44.587 UTC [38238][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:44.588 UTC [38238][postmaster] LOG:  background worker "logical replication launcher" (PID 38244) exited with exit code 1
2024-03-30 08:08:44.589 UTC [38239][checkpointer] LOG:  shutting down
2024-03-30 08:08:44.589 UTC [38239][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:44.589 UTC [38239][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/4100F08, redo lsn=0/4100F08
2024-03-30 08:08:44.591 UTC [38238][postmaster] LOG:  database system is shut down
2024-03-30 08:08:44.702 UTC [38255][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:44.702 UTC [38255][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:44.702 UTC [38255][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:44.704 UTC [38259][startup] LOG:  database system was shut down at 2024-03-30 08:08:44 UTC
2024-03-30 08:08:44.707 UTC [38255][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:44.804 UTC [38265][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20889
2024-03-30 08:08:44.809 UTC [38265][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:44.809 UTC [38265][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-30 08:08:44.814 UTC [38265][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-30 08:08:44.814 UTC [38265][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=20889
2024-03-30 08:08:44.823 UTC [38267][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20890
2024-03-30 08:08:44.828 UTC [38267][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:44.829 UTC [38267][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-30 08:08:44.829 UTC [38267][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-30 08:08:44.830 UTC [38267][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=20890
2024-03-30 08:08:44.838 UTC [38269][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20891
2024-03-30 08:08:44.843 UTC [38269][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:44.843 UTC [38269][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-30 08:08:44.844 UTC [38269][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-30 08:08:44.844 UTC [38269][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20891
2024-03-30 08:08:44.848 UTC [38255][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:44.848 UTC [38255][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:44.850 UTC [38255][postmaster] LOG:  background worker "logical replication launcher" (PID 38262) exited with exit code 1
2024-03-30 08:08:44.850 UTC [38257][checkpointer] LOG:  shutting down
2024-03-30 08:08:44.850 UTC [38257][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:44.850 UTC [38257][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/4100F80, redo lsn=0/4100F80
2024-03-30 08:08:44.852 UTC [38255][postmaster] LOG:  database system is shut down
2024-03-30 08:08:44.961 UTC [38273][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:44.961 UTC [38273][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:44.961 UTC [38273][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:44.963 UTC [38276][startup] LOG:  database system was shut down at 2024-03-30 08:08:44 UTC
2024-03-30 08:08:44.966 UTC [38273][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:45.056 UTC [38283][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20892
2024-03-30 08:08:45.061 UTC [38283][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:45.061 UTC [38283][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-30 08:08:45.065 UTC [38283][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-30 08:08:45.066 UTC [38283][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=20892
2024-03-30 08:08:45.074 UTC [38285][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20893
2024-03-30 08:08:45.079 UTC [38285][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:45.079 UTC [38285][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-30 08:08:45.080 UTC [38285][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-30 08:08:45.080 UTC [38285][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20893
2024-03-30 08:08:45.089 UTC [38287][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20894
2024-03-30 08:08:45.097 UTC [38273][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:45.097 UTC [38273][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:45.098 UTC [38273][postmaster] LOG:  background worker "logical replication launcher" (PID 38279) exited with exit code 1
2024-03-30 08:08:45.099 UTC [38274][checkpointer] LOG:  shutting down
2024-03-30 08:08:45.099 UTC [38274][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:45.100 UTC [38274][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/4100FF8, redo lsn=0/4100FF8
2024-03-30 08:08:45.101 UTC [38273][postmaster] LOG:  database system is shut down
2024-03-30 08:08:45.211 UTC [38289][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:45.212 UTC [38289][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:45.212 UTC [38289][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:45.214 UTC [38294][startup] LOG:  database system was shut down at 2024-03-30 08:08:45 UTC
2024-03-30 08:08:45.218 UTC [38289][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:45.313 UTC [38299][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20895
2024-03-30 08:08:45.319 UTC [38299][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:45.319 UTC [38299][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-30 08:08:45.323 UTC [38299][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-30 08:08:45.324 UTC [38299][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=20895
2024-03-30 08:08:45.333 UTC [38301][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20896
2024-03-30 08:08:45.338 UTC [38301][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:45.338 UTC [38301][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-30 08:08:45.338 UTC [38301][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-30 08:08:45.339 UTC [38301][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20896
2024-03-30 08:08:45.347 UTC [38303][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20897
2024-03-30 08:08:45.352 UTC [38303][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:45.352 UTC [38303][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-30 08:08:45.353 UTC [38303][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-30 08:08:45.353 UTC [38303][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20897
2024-03-30 08:08:45.357 UTC [38289][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:45.357 UTC [38289][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:45.358 UTC [38289][postmaster] LOG:  background worker "logical replication launcher" (PID 38297) exited with exit code 1
2024-03-30 08:08:45.359 UTC [38292][checkpointer] LOG:  shutting down
2024-03-30 08:08:45.359 UTC [38292][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:45.359 UTC [38292][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/4101070, redo lsn=0/4101070
2024-03-30 08:08:45.361 UTC [38289][postmaster] LOG:  database system is shut down
2024-03-30 08:08:45.475 UTC [38306][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:45.475 UTC [38306][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:45.475 UTC [38306][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:45.477 UTC [38309][startup] LOG:  database system was shut down at 2024-03-30 08:08:45 UTC
2024-03-30 08:08:45.481 UTC [38306][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:45.578 UTC [38314][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20898
2024-03-30 08:08:45.582 UTC [38314][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:45.582 UTC [38314][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-30 08:08:45.587 UTC [38314][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-30 08:08:45.587 UTC [38314][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=20898
2024-03-30 08:08:45.595 UTC [38316][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20899
2024-03-30 08:08:45.603 UTC [38306][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:45.603 UTC [38306][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:45.604 UTC [38306][postmaster] LOG:  background worker "logical replication launcher" (PID 38312) exited with exit code 1
2024-03-30 08:08:45.604 UTC [38307][checkpointer] LOG:  shutting down
2024-03-30 08:08:45.604 UTC [38307][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:45.605 UTC [38307][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/41010E8, redo lsn=0/41010E8
2024-03-30 08:08:45.607 UTC [38306][postmaster] LOG:  database system is shut down
2024-03-30 08:08:45.721 UTC [38318][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:45.721 UTC [38318][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:45.721 UTC [38318][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:45.723 UTC [38321][startup] LOG:  database system was shut down at 2024-03-30 08:08:45 UTC
2024-03-30 08:08:45.726 UTC [38318][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:45.823 UTC [38326][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20900
2024-03-30 08:08:45.828 UTC [38326][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-30 08:08:45.843 UTC [38329][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20901
2024-03-30 08:08:45.848 UTC [38329][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:45.848 UTC [38329][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-30 08:08:45.853 UTC [38329][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-30 08:08:45.853 UTC [38329][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=20901
2024-03-30 08:08:45.862 UTC [38331][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20902
2024-03-30 08:08:45.870 UTC [38318][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:45.870 UTC [38318][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:45.871 UTC [38318][postmaster] LOG:  background worker "logical replication launcher" (PID 38324) exited with exit code 1
2024-03-30 08:08:45.872 UTC [38319][checkpointer] LOG:  shutting down
2024-03-30 08:08:45.872 UTC [38319][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:45.872 UTC [38319][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/4101160, redo lsn=0/4101160
2024-03-30 08:08:45.874 UTC [38318][postmaster] LOG:  database system is shut down
2024-03-30 08:08:45.989 UTC [38333][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:45.989 UTC [38333][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:45.989 UTC [38333][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:45.991 UTC [38336][startup] LOG:  database system was shut down at 2024-03-30 08:08:45 UTC
2024-03-30 08:08:45.995 UTC [38333][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:46.090 UTC [38341][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20903
2024-03-30 08:08:46.094 UTC [38341][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:46.094 UTC [38341][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-30 08:08:46.099 UTC [38341][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-30 08:08:46.100 UTC [38341][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=20903
2024-03-30 08:08:46.108 UTC [38343][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20904
2024-03-30 08:08:46.112 UTC [38343][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-30 08:08:46.120 UTC [38345][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20905
2024-03-30 08:08:46.124 UTC [38345][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-30 08:08:46.132 UTC [38347][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20906
2024-03-30 08:08:46.137 UTC [38347][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:46.137 UTC [38347][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-30 08:08:46.138 UTC [38347][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-30 08:08:46.140 UTC [38347][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=20906
2024-03-30 08:08:46.147 UTC [38349][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20907
2024-03-30 08:08:46.152 UTC [38349][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:46.152 UTC [38349][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-30 08:08:46.153 UTC [38349][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-30 08:08:46.153 UTC [38349][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20907
2024-03-30 08:08:46.180 UTC [38354][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20908
2024-03-30 08:08:46.184 UTC [38354][client backend] [[unknown]][3/1:0] FATAL:  connection requires a valid client certificate
2024-03-30 08:08:46.192 UTC [38356][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20909
2024-03-30 08:08:46.198 UTC [38356][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-30 08:08:46.198 UTC [38356][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-30 08:08:46.202 UTC [38356][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/build/testrun/ssl/001_ssltests/data/tmp_test_ZxXc/client.key$$
2024-03-30 08:08:46.203 UTC [38356][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=20909
2024-03-30 08:08:46.211 UTC [38358][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20910
2024-03-30 08:08:46.217 UTC [38358][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-30 08:08:46.217 UTC [38358][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-30 08:08:46.218 UTC [38358][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/build/testrun/ssl/001_ssltests/data/tmp_test_ZxXc/client-der.key$$
2024-03-30 08:08:46.219 UTC [38358][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=20910
2024-03-30 08:08:46.228 UTC [38360][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20911
2024-03-30 08:08:46.235 UTC [38360][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-30 08:08:46.235 UTC [38360][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-30 08:08:46.236 UTC [38360][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/build/testrun/ssl/001_ssltests/data/tmp_test_ZxXc/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$
2024-03-30 08:08:46.236 UTC [38360][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=20911
2024-03-30 08:08:46.245 UTC [38362][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20912
2024-03-30 08:08:46.251 UTC [38362][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-30 08:08:46.251 UTC [38362][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-30 08:08:46.252 UTC [38362][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/build/testrun/ssl/001_ssltests/data/tmp_test_ZxXc/client-encrypted-der.key sslpassword='dUmmyP^#+'$$
2024-03-30 08:08:46.252 UTC [38362][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=20912
2024-03-30 08:08:46.261 UTC [38364][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20913
2024-03-30 08:08:46.268 UTC [38364][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-30 08:08:46.268 UTC [38364][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-30 08:08:46.269 UTC [38364][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/build/testrun/ssl/001_ssltests/data/tmp_test_ZxXc/client.key$$
2024-03-30 08:08:46.269 UTC [38364][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=20913
2024-03-30 08:08:46.277 UTC [38366][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20914
2024-03-30 08:08:46.284 UTC [38366][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-30 08:08:46.284 UTC [38366][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-30 08:08:46.285 UTC [38366][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/build/testrun/ssl/001_ssltests/data/tmp_test_ZxXc/client.key$$
2024-03-30 08:08:46.285 UTC [38366][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=20914
2024-03-30 08:08:46.294 UTC [38368][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20915
2024-03-30 08:08:46.298 UTC [38368][client backend] [[unknown]][10/1:0] FATAL:  connection requires a valid client certificate
2024-03-30 08:08:46.308 UTC [38370][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20916
2024-03-30 08:08:46.310 UTC [38370][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-30 08:08:46.318 UTC [38372][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20917
2024-03-30 08:08:46.324 UTC [38372][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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:8)
2024-03-30 08:08:46.324 UTC [38372][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-30 08:08:46.329 UTC [38372][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/build/testrun/ssl/001_ssltests/data/tmp_test_ZxXc/client-dn.key$$
2024-03-30 08:08:46.329 UTC [38372][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_dn host=localhost port=20917
2024-03-30 08:08:46.338 UTC [38374][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20918
2024-03-30 08:08:46.345 UTC [38374][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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:9)
2024-03-30 08:08:46.345 UTC [38374][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-30 08:08:46.350 UTC [38374][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/build/testrun/ssl/001_ssltests/data/tmp_test_ZxXc/client-dn.key$$
2024-03-30 08:08:46.350 UTC [38374][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb_dn_re host=localhost port=20918
2024-03-30 08:08:46.359 UTC [38376][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20919
2024-03-30 08:08:46.365 UTC [38376][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/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:10)
2024-03-30 08:08:46.365 UTC [38376][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-30 08:08:46.370 UTC [38376][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/build/testrun/ssl/001_ssltests/data/tmp_test_ZxXc/client-dn.key$$
2024-03-30 08:08:46.370 UTC [38376][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_cn host=localhost port=20919
2024-03-30 08:08:46.399 UTC [38379][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20920
2024-03-30 08:08:46.405 UTC [38379][client backend] [[unknown]][14/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-30 08:08:46.405 UTC [38379][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-30 08:08:46.406 UTC [38379][client backend] [001_ssltests.pl][14/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-30 08:08:46.408 UTC [38379][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=20920
2024-03-30 08:08:46.416 UTC [38381][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20921
2024-03-30 08:08:46.418 UTC [38381][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-30 08:08:46.426 UTC [38383][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20922
2024-03-30 08:08:46.432 UTC [38383][client backend] [[unknown]][15/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-30 08:08:46.432 UTC [38383][client backend] [[unknown]][15/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-30 08:08:46.432 UTC [38383][client backend] [[unknown]][15/1:0] FATAL:  certificate authentication failed for user "anotheruser"
2024-03-30 08:08:46.432 UTC [38383][client backend] [[unknown]][15/1:0] DETAIL:  Connection matched file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf" line 7: "hostssl certdb          all             127.0.0.1/32            cert"
2024-03-30 08:08:46.441 UTC [38385][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20923
2024-03-30 08:08:46.447 UTC [38385][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-30 08:08:46.447 UTC [38385][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-30 08:08:46.455 UTC [38387][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20924
2024-03-30 08:08:46.461 UTC [38387][client backend] [[unknown]][16/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:4)
2024-03-30 08:08:46.461 UTC [38387][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-30 08:08:46.465 UTC [38387][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/build/testrun/ssl/001_ssltests/data/tmp_test_ZxXc/client.key$$
2024-03-30 08:08:46.466 UTC [38387][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=verifydb host=localhost port=20924
2024-03-30 08:08:46.474 UTC [38389][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20925
2024-03-30 08:08:46.481 UTC [38389][client backend] [[unknown]][17/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-30 08:08:46.481 UTC [38389][client backend] [[unknown]][17/1:0] LOG:  certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch
2024-03-30 08:08:46.481 UTC [38389][client backend] [[unknown]][17/1:0] FATAL:  "trust" authentication failed for user "anotheruser"
2024-03-30 08:08:46.481 UTC [38389][client backend] [[unknown]][17/1:0] DETAIL:  Connection matched file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/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-30 08:08:46.489 UTC [38391][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20926
2024-03-30 08:08:46.495 UTC [38391][client backend] [[unknown]][18/1:0] LOG:  connection authenticated: user="yetanotheruser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:6)
2024-03-30 08:08:46.495 UTC [38391][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-30 08:08:46.496 UTC [38391][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/build/testrun/ssl/001_ssltests/data/tmp_test_ZxXc/client.key$$
2024-03-30 08:08:46.496 UTC [38391][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=yetanotheruser database=verifydb host=localhost port=20926
2024-03-30 08:08:46.501 UTC [38333][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:46.501 UTC [38333][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:46.503 UTC [38333][postmaster] LOG:  background worker "logical replication launcher" (PID 38339) exited with exit code 1
2024-03-30 08:08:46.503 UTC [38334][checkpointer] LOG:  shutting down
2024-03-30 08:08:46.503 UTC [38334][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:46.503 UTC [38334][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/41011D8, redo lsn=0/41011D8
2024-03-30 08:08:46.505 UTC [38333][postmaster] LOG:  database system is shut down
2024-03-30 08:08:46.619 UTC [38393][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:46.619 UTC [38393][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:46.619 UTC [38393][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:46.621 UTC [38396][startup] LOG:  database system was shut down at 2024-03-30 08:08:46 UTC
2024-03-30 08:08:46.625 UTC [38393][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:46.721 UTC [38401][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20927
2024-03-30 08:08:46.728 UTC [38401][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-30 08:08:46.728 UTC [38401][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-30 08:08:46.733 UTC [38401][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/build/testrun/ssl/001_ssltests/data/tmp_test_ZxXc/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-30 08:08:46.733 UTC [38401][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb host=localhost port=20927
2024-03-30 08:08:46.742 UTC [38403][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20928
2024-03-30 08:08:46.748 UTC [38403][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-30 08:08:46.748 UTC [38403][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-30 08:08:46.756 UTC [38405][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20929
2024-03-30 08:08:46.762 UTC [38405][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-30 08:08:46.762 UTC [38405][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-30 08:08:46.765 UTC [38393][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:46.765 UTC [38393][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:46.766 UTC [38393][postmaster] LOG:  background worker "logical replication launcher" (PID 38399) exited with exit code 1
2024-03-30 08:08:46.766 UTC [38394][checkpointer] LOG:  shutting down
2024-03-30 08:08:46.766 UTC [38394][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:46.767 UTC [38394][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/4101250, redo lsn=0/4101250
2024-03-30 08:08:46.769 UTC [38393][postmaster] LOG:  database system is shut down
2024-03-30 08:08:46.882 UTC [38407][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:46.882 UTC [38407][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:46.882 UTC [38407][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:46.884 UTC [38410][startup] LOG:  database system was shut down at 2024-03-30 08:08:46 UTC
2024-03-30 08:08:46.887 UTC [38407][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:46.985 UTC [38415][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20930
2024-03-30 08:08:46.992 UTC [38415][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-30 08:08:46.992 UTC [38415][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-30 08:08:46.996 UTC [38407][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:46.996 UTC [38407][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:46.997 UTC [38407][postmaster] LOG:  background worker "logical replication launcher" (PID 38413) exited with exit code 1
2024-03-30 08:08:46.997 UTC [38408][checkpointer] LOG:  shutting down
2024-03-30 08:08:46.997 UTC [38408][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:46.998 UTC [38408][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/41012C8, redo lsn=0/41012C8
2024-03-30 08:08:46.999 UTC [38407][postmaster] LOG:  database system is shut down
2024-03-30 08:08:47.114 UTC [38417][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:47.114 UTC [38417][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:47.114 UTC [38417][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:47.116 UTC [38420][startup] LOG:  database system was shut down at 2024-03-30 08:08:46 UTC
2024-03-30 08:08:47.120 UTC [38417][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:47.216 UTC [38425][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20931
2024-03-30 08:08:47.222 UTC [38425][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-30 08:08:47.222 UTC [38425][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-30 08:08:47.230 UTC [38427][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20932
2024-03-30 08:08:47.235 UTC [38427][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-30 08:08:47.235 UTC [38427][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-30 08:08:47.238 UTC [38417][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:47.238 UTC [38417][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:47.240 UTC [38417][postmaster] LOG:  background worker "logical replication launcher" (PID 38423) exited with exit code 1
2024-03-30 08:08:47.240 UTC [38418][checkpointer] LOG:  shutting down
2024-03-30 08:08:47.240 UTC [38418][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:47.240 UTC [38418][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/4101340, redo lsn=0/4101340
2024-03-30 08:08:47.242 UTC [38417][postmaster] LOG:  database system is shut down
2024-03-30 08:08:47.357 UTC [38429][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:47.357 UTC [38429][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:47.357 UTC [38429][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:47.359 UTC [38432][startup] LOG:  database system was shut down at 2024-03-30 08:08:47 UTC
2024-03-30 08:08:47.363 UTC [38429][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:47.458 UTC [38437][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20933
2024-03-30 08:08:47.460 UTC [38437][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-30 08:08:47.460 UTC [38437][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-30 08:08:47.471 UTC [38439][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20934
2024-03-30 08:08:47.476 UTC [38439][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:47.476 UTC [38439][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-30 08:08:47.480 UTC [38439][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-30 08:08:47.481 UTC [38439][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=20934
2024-03-30 08:08:47.484 UTC [38429][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:47.484 UTC [38429][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:47.486 UTC [38429][postmaster] LOG:  background worker "logical replication launcher" (PID 38435) exited with exit code 1
2024-03-30 08:08:47.486 UTC [38430][checkpointer] LOG:  shutting down
2024-03-30 08:08:47.486 UTC [38430][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:47.487 UTC [38430][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/41013B8, redo lsn=0/41013B8
2024-03-30 08:08:47.488 UTC [38429][postmaster] LOG:  database system is shut down
2024-03-30 08:08:47.602 UTC [38441][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:47.602 UTC [38441][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:47.602 UTC [38441][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:47.604 UTC [38444][startup] LOG:  database system was shut down at 2024-03-30 08:08:47 UTC
2024-03-30 08:08:47.607 UTC [38441][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:47.704 UTC [38449][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20935
2024-03-30 08:08:47.706 UTC [38449][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-30 08:08:47.706 UTC [38449][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-30 08:08:47.713 UTC [38451][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20936
2024-03-30 08:08:47.717 UTC [38451][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:47.717 UTC [38451][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-30 08:08:47.721 UTC [38451][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-30 08:08:47.722 UTC [38451][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20936
2024-03-30 08:08:47.725 UTC [38441][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:47.725 UTC [38441][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:47.726 UTC [38441][postmaster] LOG:  background worker "logical replication launcher" (PID 38447) exited with exit code 1
2024-03-30 08:08:47.726 UTC [38442][checkpointer] LOG:  shutting down
2024-03-30 08:08:47.727 UTC [38442][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:47.727 UTC [38442][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/4101430, redo lsn=0/4101430
2024-03-30 08:08:47.729 UTC [38441][postmaster] LOG:  database system is shut down
2024-03-30 08:08:47.843 UTC [38453][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:47.843 UTC [38453][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:47.843 UTC [38453][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:47.845 UTC [38456][startup] LOG:  database system was shut down at 2024-03-30 08:08:47 UTC
2024-03-30 08:08:47.849 UTC [38453][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:47.944 UTC [38461][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20937
2024-03-30 08:08:47.946 UTC [38461][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-30 08:08:47.946 UTC [38461][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-30 08:08:47.954 UTC [38463][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20938
2024-03-30 08:08:47.958 UTC [38463][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:47.958 UTC [38463][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-30 08:08:47.962 UTC [38463][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-30 08:08:47.963 UTC [38463][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20938
2024-03-30 08:08:47.966 UTC [38453][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:47.966 UTC [38453][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:47.968 UTC [38453][postmaster] LOG:  background worker "logical replication launcher" (PID 38459) exited with exit code 1
2024-03-30 08:08:47.968 UTC [38454][checkpointer] LOG:  shutting down
2024-03-30 08:08:47.968 UTC [38454][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:47.968 UTC [38454][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/41014A8, redo lsn=0/41014A8
2024-03-30 08:08:47.970 UTC [38453][postmaster] LOG:  database system is shut down
2024-03-30 08:08:48.083 UTC [38465][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:48.083 UTC [38465][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:48.083 UTC [38465][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:48.085 UTC [38468][startup] LOG:  database system was shut down at 2024-03-30 08:08:47 UTC
2024-03-30 08:08:48.088 UTC [38465][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:48.186 UTC [38473][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20939
2024-03-30 08:08:48.188 UTC [38473][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-30 08:08:48.188 UTC [38473][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-30 08:08:48.197 UTC [38475][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20940
2024-03-30 08:08:48.202 UTC [38475][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:48.202 UTC [38475][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-30 08:08:48.207 UTC [38475][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-30 08:08:48.207 UTC [38475][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=20940
2024-03-30 08:08:48.210 UTC [38465][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:48.210 UTC [38465][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:48.212 UTC [38465][postmaster] LOG:  background worker "logical replication launcher" (PID 38471) exited with exit code 1
2024-03-30 08:08:48.212 UTC [38466][checkpointer] LOG:  shutting down
2024-03-30 08:08:48.212 UTC [38466][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:48.212 UTC [38466][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/4101520, redo lsn=0/4101520
2024-03-30 08:08:48.214 UTC [38465][postmaster] LOG:  database system is shut down
2024-03-30 08:08:48.328 UTC [38477][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:48.328 UTC [38477][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:48.328 UTC [38477][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:48.330 UTC [38480][startup] LOG:  database system was shut down at 2024-03-30 08:08:48 UTC
2024-03-30 08:08:48.332 UTC [38477][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:48.430 UTC [38485][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20941
2024-03-30 08:08:48.432 UTC [38485][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-30 08:08:48.432 UTC [38485][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-30 08:08:48.442 UTC [38487][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20942
2024-03-30 08:08:48.447 UTC [38487][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:48.447 UTC [38487][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-30 08:08:48.451 UTC [38487][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-30 08:08:48.452 UTC [38487][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20942
2024-03-30 08:08:48.456 UTC [38477][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:48.456 UTC [38477][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:48.457 UTC [38477][postmaster] LOG:  background worker "logical replication launcher" (PID 38483) exited with exit code 1
2024-03-30 08:08:48.457 UTC [38478][checkpointer] LOG:  shutting down
2024-03-30 08:08:48.457 UTC [38478][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:48.458 UTC [38478][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/4101598, redo lsn=0/4101598
2024-03-30 08:08:48.459 UTC [38477][postmaster] LOG:  database system is shut down
2024-03-30 08:08:48.573 UTC [38489][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:48.573 UTC [38489][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:48.573 UTC [38489][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:48.575 UTC [38492][startup] LOG:  database system was shut down at 2024-03-30 08:08:48 UTC
2024-03-30 08:08:48.578 UTC [38489][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:48.671 UTC [38497][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20943
2024-03-30 08:08:48.673 UTC [38497][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-30 08:08:48.673 UTC [38497][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-30 08:08:48.681 UTC [38499][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20944
2024-03-30 08:08:48.685 UTC [38499][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:48.685 UTC [38499][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-30 08:08:48.689 UTC [38499][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-30 08:08:48.690 UTC [38499][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20944
2024-03-30 08:08:48.693 UTC [38489][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:48.693 UTC [38489][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:48.694 UTC [38489][postmaster] LOG:  background worker "logical replication launcher" (PID 38495) exited with exit code 1
2024-03-30 08:08:48.694 UTC [38490][checkpointer] LOG:  shutting down
2024-03-30 08:08:48.694 UTC [38490][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:48.695 UTC [38490][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/4101610, redo lsn=0/4101610
2024-03-30 08:08:48.697 UTC [38489][postmaster] LOG:  database system is shut down
2024-03-30 08:08:48.810 UTC [38501][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:48.810 UTC [38501][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:48.810 UTC [38501][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:48.812 UTC [38504][startup] LOG:  database system was shut down at 2024-03-30 08:08:48 UTC
2024-03-30 08:08:48.815 UTC [38501][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:48.913 UTC [38509][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20945
2024-03-30 08:08:48.914 UTC [38509][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-30 08:08:48.914 UTC [38509][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-30 08:08:48.922 UTC [38511][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20946
2024-03-30 08:08:48.926 UTC [38511][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:48.926 UTC [38511][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-30 08:08:48.931 UTC [38511][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-30 08:08:48.931 UTC [38511][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20946
2024-03-30 08:08:48.935 UTC [38501][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:48.935 UTC [38501][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:48.936 UTC [38501][postmaster] LOG:  background worker "logical replication launcher" (PID 38507) exited with exit code 1
2024-03-30 08:08:48.936 UTC [38502][checkpointer] LOG:  shutting down
2024-03-30 08:08:48.936 UTC [38502][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:48.937 UTC [38502][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/4101688, redo lsn=0/4101688
2024-03-30 08:08:48.939 UTC [38501][postmaster] LOG:  database system is shut down
2024-03-30 08:08:49.053 UTC [38513][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:49.053 UTC [38513][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:49.053 UTC [38513][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:49.055 UTC [38516][startup] LOG:  database system was shut down at 2024-03-30 08:08:48 UTC
2024-03-30 08:08:49.057 UTC [38513][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:49.155 UTC [38521][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20947
2024-03-30 08:08:49.157 UTC [38521][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-30 08:08:49.157 UTC [38521][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-30 08:08:49.165 UTC [38523][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20948
2024-03-30 08:08:49.169 UTC [38523][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:49.169 UTC [38523][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-30 08:08:49.174 UTC [38523][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-30 08:08:49.174 UTC [38523][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20948
2024-03-30 08:08:49.178 UTC [38513][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:49.178 UTC [38513][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:49.179 UTC [38513][postmaster] LOG:  background worker "logical replication launcher" (PID 38519) exited with exit code 1
2024-03-30 08:08:49.179 UTC [38514][checkpointer] LOG:  shutting down
2024-03-30 08:08:49.180 UTC [38514][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:49.180 UTC [38514][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/4101700, redo lsn=0/4101700
2024-03-30 08:08:49.182 UTC [38513][postmaster] LOG:  database system is shut down
2024-03-30 08:08:49.296 UTC [38525][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-30 08:08:49.296 UTC [38525][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55014
2024-03-30 08:08:49.296 UTC [38525][postmaster] LOG:  listening on Unix socket "/tmp/iYp9tCK5SO/.s.PGSQL.55014"
2024-03-30 08:08:49.298 UTC [38528][startup] LOG:  database system was shut down at 2024-03-30 08:08:49 UTC
2024-03-30 08:08:49.301 UTC [38525][postmaster] LOG:  database system is ready to accept connections
2024-03-30 08:08:49.398 UTC [38533][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20949
2024-03-30 08:08:49.399 UTC [38533][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-30 08:08:49.400 UTC [38533][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-30 08:08:49.408 UTC [38535][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=20950
2024-03-30 08:08:49.413 UTC [38535][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-30 08:08:49.413 UTC [38535][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-30 08:08:49.417 UTC [38535][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-30 08:08:49.418 UTC [38535][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=20950
2024-03-30 08:08:49.422 UTC [38525][postmaster] LOG:  received fast shutdown request
2024-03-30 08:08:49.422 UTC [38525][postmaster] LOG:  aborting any active transactions
2024-03-30 08:08:49.424 UTC [38525][postmaster] LOG:  background worker "logical replication launcher" (PID 38531) exited with exit code 1
2024-03-30 08:08:49.424 UTC [38526][checkpointer] LOG:  shutting down
2024-03-30 08:08:49.424 UTC [38526][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-30 08:08:49.425 UTC [38526][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/4101778, redo lsn=0/4101778
2024-03-30 08:08:49.426 UTC [38525][postmaster] LOG:  database system is shut down
2024-03-30 08:08:49.537 UTC [38537][postmaster] FATAL:  could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory
2024-03-30 08:08:49.537 UTC [38537][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[08:08:49.636](0.216s) Bail out!  pg_ctl restart failed
# No postmaster PID for node "primary"