[05:11:02.064](0.028s) # setting up data directory
# Checking port 57504
# Found port 57504
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=57504 host=/tmp/O9pBkcq9_9
Log file: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log
[05:11:02.073](0.009s) # 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 37983
[05:11:02.360](0.288s) 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 38372
[05:11:03.210](0.850s) # 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-04-06 05:11:02.231 UTC [37983][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:02.231 UTC [37983][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:02.237 UTC [37994][startup] LOG:  database system was shut down at 2024-04-06 05:08:39 UTC
2024-04-06 05:11:02.240 UTC [37983][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:02.348 UTC [38040][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.348 UTC [38040][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-04-06 05:11:02.348 UTC [38040][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.354 UTC [38040][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-04-06 05:11:02.358 UTC [38040][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.369 UTC [38050][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.374 UTC [38050][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-04-06 05:11:02.374 UTC [38050][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.377 UTC [38050][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-04-06 05:11:02.377 UTC [38050][client backend] [001_ssltests.pl][1/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-04-06 05:11:02.378 UTC [38050][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.417 UTC [38068][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.417 UTC [38068][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-04-06 05:11:02.417 UTC [38068][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.418 UTC [38068][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-04-06 05:11:02.418 UTC [38068][client backend] [001_ssltests.pl][2/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-04-06 05:11:02.418 UTC [38068][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.463 UTC [38085][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.464 UTC [38085][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-04-06 05:11:02.464 UTC [38085][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.465 UTC [38085][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-04-06 05:11:02.465 UTC [38085][client backend] [001_ssltests.pl][3/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-04-06 05:11:02.465 UTC [38085][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.506 UTC [38114][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.506 UTC [38114][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-04-06 05:11:02.506 UTC [38114][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.507 UTC [38114][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-04-06 05:11:02.507 UTC [38114][client backend] [001_ssltests.pl][4/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-04-06 05:11:02.509 UTC [38114][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.556 UTC [38132][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.557 UTC [38132][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-04-06 05:11:02.557 UTC [38132][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.557 UTC [38132][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-04-06 05:11:02.557 UTC [38132][client backend] [001_ssltests.pl][5/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-04-06 05:11:02.594 UTC [38132][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.037 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.690 UTC [38183][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.690 UTC [38183][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-04-06 05:11:02.690 UTC [38183][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.691 UTC [38183][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-04-06 05:11:02.691 UTC [38183][client backend] [001_ssltests.pl][6/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-04-06 05:11:02.775 UTC [38183][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.085 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.815 UTC [38225][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.816 UTC [38225][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-04-06 05:11:02.816 UTC [38225][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.816 UTC [38225][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-04-06 05:11:02.817 UTC [38225][client backend] [001_ssltests.pl][7/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-04-06 05:11:02.832 UTC [38225][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.016 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.869 UTC [38257][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.870 UTC [38257][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-04-06 05:11:02.870 UTC [38257][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.871 UTC [38257][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-04-06 05:11:02.871 UTC [38257][client backend] [001_ssltests.pl][8/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-04-06 05:11:02.899 UTC [38257][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.911 UTC [38278][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.912 UTC [38278][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-04-06 05:11:02.912 UTC [38278][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.913 UTC [38278][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-04-06 05:11:02.913 UTC [38278][client backend] [001_ssltests.pl][9/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-04-06 05:11:02.928 UTC [38278][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.017 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.963 UTC [38306][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.964 UTC [38306][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-04-06 05:11:02.964 UTC [38306][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.967 UTC [38306][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-04-06 05:11:02.967 UTC [38306][client backend] [001_ssltests.pl][10/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-04-06 05:11:02.990 UTC [38306][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local]
2024-04-06 05:11:03.003 UTC [37983][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:03.003 UTC [37983][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:03.005 UTC [37983][postmaster] LOG:  background worker "logical replication launcher" (PID 37998) exited with exit code 1
2024-04-06 05:11:03.006 UTC [37992][checkpointer] LOG:  shutting down
2024-04-06 05:11:03.006 UTC [37992][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:03.046 UTC [37992][checkpointer] LOG:  checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.040 s, sync=0.001 s, total=0.040 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/41012D8, redo lsn=0/41012D8
2024-04-06 05:11:03.049 UTC [37983][postmaster] LOG:  database system is shut down
2024-04-06 05:11:03.116 UTC [38372][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:03.116 UTC [38372][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:03.116 UTC [38372][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:03.118 UTC [38379][startup] LOG:  database system was shut down at 2024-04-06 05:11:03 UTC
2024-04-06 05:11:03.120 UTC [38372][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:03.222 UTC [38372][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:03.223 UTC [38372][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:03.231 UTC [38372][postmaster] LOG:  background worker "logical replication launcher" (PID 38382) exited with exit code 1
2024-04-06 05:11:03.231 UTC [38377][checkpointer] LOG:  shutting down
2024-04-06 05:11:03.231 UTC [38377][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:03.231 UTC [38377][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/4101350, redo lsn=0/4101350
2024-04-06 05:11:03.234 UTC [38372][postmaster] LOG:  database system is shut down
2024-04-06 05:11:03.343 UTC [38468][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-04-06 05:11:03.343 UTC [38468][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[05:11:03.441](0.231s) 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 38502
[05:11:03.559](0.118s) 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-04-06 05:11:02.231 UTC [37983][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:02.231 UTC [37983][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:02.237 UTC [37994][startup] LOG:  database system was shut down at 2024-04-06 05:08:39 UTC
2024-04-06 05:11:02.240 UTC [37983][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:02.348 UTC [38040][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.348 UTC [38040][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-04-06 05:11:02.348 UTC [38040][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.354 UTC [38040][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-04-06 05:11:02.358 UTC [38040][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.369 UTC [38050][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.374 UTC [38050][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-04-06 05:11:02.374 UTC [38050][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.377 UTC [38050][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-04-06 05:11:02.377 UTC [38050][client backend] [001_ssltests.pl][1/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-04-06 05:11:02.378 UTC [38050][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.417 UTC [38068][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.417 UTC [38068][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-04-06 05:11:02.417 UTC [38068][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.418 UTC [38068][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-04-06 05:11:02.418 UTC [38068][client backend] [001_ssltests.pl][2/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-04-06 05:11:02.418 UTC [38068][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.463 UTC [38085][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.464 UTC [38085][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-04-06 05:11:02.464 UTC [38085][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.465 UTC [38085][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-04-06 05:11:02.465 UTC [38085][client backend] [001_ssltests.pl][3/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-04-06 05:11:02.465 UTC [38085][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.506 UTC [38114][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.506 UTC [38114][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-04-06 05:11:02.506 UTC [38114][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.507 UTC [38114][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-04-06 05:11:02.507 UTC [38114][client backend] [001_ssltests.pl][4/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-04-06 05:11:02.509 UTC [38114][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.556 UTC [38132][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.557 UTC [38132][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-04-06 05:11:02.557 UTC [38132][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.557 UTC [38132][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-04-06 05:11:02.557 UTC [38132][client backend] [001_ssltests.pl][5/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-04-06 05:11:02.594 UTC [38132][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.037 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.690 UTC [38183][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.690 UTC [38183][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-04-06 05:11:02.690 UTC [38183][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.691 UTC [38183][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-04-06 05:11:02.691 UTC [38183][client backend] [001_ssltests.pl][6/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-04-06 05:11:02.775 UTC [38183][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.085 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.815 UTC [38225][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.816 UTC [38225][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-04-06 05:11:02.816 UTC [38225][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.816 UTC [38225][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-04-06 05:11:02.817 UTC [38225][client backend] [001_ssltests.pl][7/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-04-06 05:11:02.832 UTC [38225][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.016 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.869 UTC [38257][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.870 UTC [38257][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-04-06 05:11:02.870 UTC [38257][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.871 UTC [38257][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-04-06 05:11:02.871 UTC [38257][client backend] [001_ssltests.pl][8/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-04-06 05:11:02.899 UTC [38257][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.911 UTC [38278][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.912 UTC [38278][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-04-06 05:11:02.912 UTC [38278][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.913 UTC [38278][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-04-06 05:11:02.913 UTC [38278][client backend] [001_ssltests.pl][9/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-04-06 05:11:02.928 UTC [38278][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.017 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.963 UTC [38306][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.964 UTC [38306][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-04-06 05:11:02.964 UTC [38306][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.967 UTC [38306][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-04-06 05:11:02.967 UTC [38306][client backend] [001_ssltests.pl][10/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-04-06 05:11:02.990 UTC [38306][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local]
2024-04-06 05:11:03.003 UTC [37983][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:03.003 UTC [37983][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:03.005 UTC [37983][postmaster] LOG:  background worker "logical replication launcher" (PID 37998) exited with exit code 1
2024-04-06 05:11:03.006 UTC [37992][checkpointer] LOG:  shutting down
2024-04-06 05:11:03.006 UTC [37992][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:03.046 UTC [37992][checkpointer] LOG:  checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.040 s, sync=0.001 s, total=0.040 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/41012D8, redo lsn=0/41012D8
2024-04-06 05:11:03.049 UTC [37983][postmaster] LOG:  database system is shut down
2024-04-06 05:11:03.116 UTC [38372][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:03.116 UTC [38372][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:03.116 UTC [38372][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:03.118 UTC [38379][startup] LOG:  database system was shut down at 2024-04-06 05:11:03 UTC
2024-04-06 05:11:03.120 UTC [38372][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:03.222 UTC [38372][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:03.223 UTC [38372][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:03.231 UTC [38372][postmaster] LOG:  background worker "logical replication launcher" (PID 38382) exited with exit code 1
2024-04-06 05:11:03.231 UTC [38377][checkpointer] LOG:  shutting down
2024-04-06 05:11:03.231 UTC [38377][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:03.231 UTC [38377][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/4101350, redo lsn=0/4101350
2024-04-06 05:11:03.234 UTC [38372][postmaster] LOG:  database system is shut down
2024-04-06 05:11:03.343 UTC [38468][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-04-06 05:11:03.343 UTC [38468][postmaster] LOG:  database system is shut down
2024-04-06 05:11:03.457 UTC [38502][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:03.457 UTC [38502][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:03.457 UTC [38502][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:03.459 UTC [38508][startup] LOG:  database system was shut down at 2024-04-06 05:11:03 UTC
2024-04-06 05:11:03.462 UTC [38502][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:03.562 UTC [38502][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:03.562 UTC [38502][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:03.565 UTC [38502][postmaster] LOG:  background worker "logical replication launcher" (PID 38511) exited with exit code 1
2024-04-06 05:11:03.568 UTC [38506][checkpointer] LOG:  shutting down
2024-04-06 05:11:03.568 UTC [38506][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:03.568 UTC [38506][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/41013C8, redo lsn=0/41013C8
2024-04-06 05:11:03.569 UTC [38502][postmaster] LOG:  database system is shut down
2024-04-06 05:11:03.678 UTC [38567][postmaster] FATAL:  could not set SSL protocol version range
2024-04-06 05:11:03.678 UTC [38567][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-04-06 05:11:03.678 UTC [38567][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[05:11:03.768](0.209s) 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 38592
[05:11:03.872](0.104s) ok 5 - restart succeeds with correct SSL protocol bounds
[05:11:03.873](0.001s) # 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 38647
[05:11:04.093](0.220s) ok 6 - server doesn't accept non-SSL connections
[05:11:04.094](0.001s) ok 7 - server doesn't accept non-SSL connections: matches
[05:11:04.114](0.020s) ok 8 - connect without server root cert sslmode=require
[05:11:04.115](0.001s) ok 9 - connect without server root cert sslmode=require: no stderr
[05:11:04.126](0.011s) ok 10 - connect without server root cert sslmode=verify-ca
[05:11:04.127](0.001s) ok 11 - connect without server root cert sslmode=verify-ca: matches
[05:11:04.137](0.010s) ok 12 - connect without server root cert sslmode=verify-full
[05:11:04.137](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches
[05:11:04.151](0.013s) ok 14 - connect with wrong server root cert sslmode=require
[05:11:04.151](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches
[05:11:04.164](0.013s) ok 16 - connect with wrong server root cert sslmode=verify-ca
[05:11:04.164](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches
[05:11:04.177](0.013s) ok 18 - connect with wrong server root cert sslmode=verify-full
[05:11:04.177](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches
[05:11:04.190](0.012s) ok 20 - connect with server CA cert, without root CA
[05:11:04.190](0.000s) ok 21 - connect with server CA cert, without root CA: matches
[05:11:04.204](0.014s) ok 22 - connect with correct server CA cert file sslmode=require
[05:11:04.204](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr
[05:11:04.217](0.013s) ok 24 - connect with correct server CA cert file sslmode=verify-ca
[05:11:04.217](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr
[05:11:04.229](0.012s) ok 26 - connect with correct server CA cert file sslmode=verify-full
[05:11:04.229](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr
[05:11:04.244](0.014s) ok 28 - cert root file that contains two certificates, order 1
[05:11:04.244](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr
[05:11:04.259](0.015s) ok 30 - cert root file that contains two certificates, order 2
[05:11:04.259](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr
[05:11:04.273](0.014s) ok 32 - connect with sslcertmode=disable
[05:11:04.273](0.000s) ok 33 - connect with sslcertmode=disable: no stderr
[05:11:04.288](0.014s) ok 34 - connect with sslcertmode=allow
[05:11:04.288](0.000s) ok 35 - connect with sslcertmode=allow: no stderr
[05:11:04.302](0.014s) ok 36 - connect with sslcertmode=require fails without a client certificate
[05:11:04.302](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches
[05:11:04.317](0.015s) ok 38 - sslcrl option with invalid file name
[05:11:04.318](0.000s) ok 39 - sslcrl option with invalid file name: no stderr
[05:11:04.330](0.012s) ok 40 - CRL belonging to a different CA
[05:11:04.330](0.000s) ok 41 - CRL belonging to a different CA: matches
[05:11:04.343](0.013s) ok 42 - directory CRL belonging to a different CA
[05:11:04.343](0.000s) ok 43 - directory CRL belonging to a different CA: matches
[05:11:04.358](0.014s) ok 44 - CRL with a non-revoked cert
[05:11:04.358](0.000s) ok 45 - CRL with a non-revoked cert: no stderr
[05:11:04.373](0.015s) ok 46 - directory CRL with a non-revoked cert
[05:11:04.373](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr
[05:11:04.387](0.014s) ok 48 - mismatch between host name and server certificate sslmode=require
[05:11:04.387](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr
[05:11:04.402](0.015s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca
[05:11:04.402](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr
[05:11:04.415](0.013s) ok 52 - mismatch between host name and server certificate sslmode=verify-full
[05:11:04.415](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 38738
[05:11:04.641](0.226s) ok 54 - IP address in the Common Name
[05:11:04.641](0.000s) ok 55 - IP address in the Common Name: no stderr
[05:11:04.654](0.013s) ok 56 - mismatch between host name and server certificate IP address
[05:11:04.654](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 38767
[05:11:04.884](0.230s) ok 58 - IP address in a dNSName
[05:11:04.884](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 38789
[05:11:05.115](0.230s) ok 60 - host name matching with X.509 Subject Alternative Names 1
[05:11:05.115](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr
[05:11:05.130](0.015s) ok 62 - host name matching with X.509 Subject Alternative Names 2
[05:11:05.131](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr
[05:11:05.147](0.016s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard
[05:11:05.147](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr
[05:11:05.161](0.014s) ok 66 - host name not matching with X.509 Subject Alternative Names
[05:11:05.162](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches
[05:11:05.175](0.014s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard
[05:11:05.176](0.001s) 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 38822
[05:11:05.404](0.228s) ok 70 - host name matching with a single X.509 Subject Alternative Name
[05:11:05.405](0.001s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr
[05:11:05.418](0.014s) ok 72 - host name not matching with a single X.509 Subject Alternative Name
[05:11:05.419](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches
[05:11:05.432](0.013s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard
[05:11:05.432](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 38851
[05:11:05.661](0.229s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1)
[05:11:05.661](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr
[05:11:05.674](0.013s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1)
[05:11:05.675](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr
[05:11:05.687](0.012s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1)
[05:11:05.687](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches
[05:11:05.701](0.014s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2)
[05:11:05.701](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr
[05:11:05.715](0.014s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2)
[05:11:05.715](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr
[05:11:05.730](0.015s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2)
[05:11:05.731](0.001s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr
[05:11:05.744](0.013s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2)
[05:11:05.744](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches
[05:11:05.758](0.013s) ok 90 - IPv6 host with CIDR mask does not match
[05:11:05.758](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 38879
[05:11:05.990](0.232s) ok 92 - certificate with both a CN and SANs 1
[05:11:05.990](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr
[05:11:06.002](0.012s) ok 94 - certificate with both a CN and SANs 2
[05:11:06.002](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr
[05:11:06.014](0.012s) ok 96 - certificate with both a CN and SANs ignores CN
[05:11:06.014](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 38897
[05:11:06.252](0.238s) ok 98 - certificate with both a CN and IP SANs matches CN
[05:11:06.252](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr
[05:11:06.266](0.014s) ok 100 - certificate with both a CN and IP SANs matches SAN 1
[05:11:06.266](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr
[05:11:06.280](0.014s) ok 102 - certificate with both a CN and IP SANs matches SAN 2
[05:11:06.280](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 38911
[05:11:06.514](0.234s) ok 104 - certificate with both an IP CN and IP SANs 1
[05:11:06.515](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr
[05:11:06.530](0.015s) ok 106 - certificate with both an IP CN and IP SANs 2
[05:11:06.530](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr
[05:11:06.542](0.013s) ok 108 - certificate with both an IP CN and IP SANs ignores CN
[05:11:06.543](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 38925
[05:11:06.778](0.236s) ok 110 - certificate with both an IP CN and DNS SANs matches CN
[05:11:06.778](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr
[05:11:06.793](0.014s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1
[05:11:06.793](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr
[05:11:06.808](0.015s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2
[05:11:06.809](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 38939
[05:11:07.043](0.234s) ok 116 - server certificate without CN or SANs sslmode=verify-ca
[05:11:07.043](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr
[05:11:07.056](0.013s) ok 118 - server certificate without CN or SANs sslmode=verify-full
[05:11:07.057](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 38951
[05:11:07.285](0.228s) ok 120 - sslrootcert=system does not connect with private CA
[05:11:07.285](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches
[05:11:07.292](0.007s) ok 122 - sslrootcert=system only accepts sslmode=verify-full
[05:11:07.293](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches
[05:11:07.313](0.020s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE
[05:11:07.313](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr
[05:11:07.327](0.014s) ok 126 - sslrootcert=system defaults to sslmode=verify-full
[05:11:07.327](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 38966
[05:11:07.564](0.237s) ok 128 - connects without client-side CRL
[05:11:07.565](0.000s) ok 129 - connects without client-side CRL: no stderr
[05:11:07.578](0.014s) ok 130 - does not connect with client-side CRL file
[05:11:07.578](0.000s) ok 131 - does not connect with client-side CRL file: matches
[05:11:07.591](0.013s) ok 132 - does not connect with client-side CRL directory
[05:11:07.591](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()
[05:11:07.606](0.015s) ok 134 - pg_stat_ssl view without client certificate: exit code 0
[05:11:07.607](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr
[05:11:07.607](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches
[05:11:07.621](0.014s) ok 137 - connection success with correct range of TLS protocol versions
[05:11:07.621](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr
[05:11:07.627](0.005s) ok 139 - connection failure with incorrect range of TLS protocol versions
[05:11:07.627](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches
[05:11:07.633](0.006s) ok 141 - connection failure with an incorrect SSL protocol minimum bound
[05:11:07.633](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches
[05:11:07.639](0.006s) ok 143 - connection failure with an incorrect SSL protocol maximum bound
[05:11:07.639](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches
[05:11:07.639](0.000s) # running server tests
[05:11:07.651](0.012s) ok 145 - certificate authorization fails without client cert
[05:11:07.652](0.000s) ok 146 - certificate authorization fails without client cert: matches
[05:11:07.672](0.021s) ok 147 - certificate authorization succeeds with correct client cert in PEM format
[05:11:07.673](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr
[05:11:07.688](0.016s) ok 149 - certificate authorization succeeds with correct client cert in DER format
[05:11:07.688](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr
[05:11:07.704](0.016s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format
[05:11:07.704](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr
[05:11:07.720](0.015s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format
[05:11:07.720](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr
[05:11:07.736](0.017s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require
[05:11:07.737](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr
[05:11:07.753](0.016s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow
[05:11:07.753](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr
[05:11:07.766](0.013s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable
[05:11:07.766](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches
[05:11:07.775](0.009s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format
[05:11:07.776](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches
[05:11:07.795](0.020s) ok 163 - certificate authorization succeeds with DN mapping
[05:11:07.795](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr
[05:11:07.796](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches
[05:11:07.816](0.020s) ok 166 - certificate authorization succeeds with DN regex mapping
[05:11:07.816](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr
[05:11:07.838](0.021s) ok 168 - certificate authorization succeeds with CN mapping
[05:11:07.838](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr
[05:11:07.838](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches
[05:11:07.839](0.000s) not ok 171 # TODO & SKIP Need Pty support
[05:11:07.839](0.000s) not ok 172 # TODO & SKIP Need Pty support
[05:11:07.839](0.000s) not ok 173 # TODO & SKIP Need Pty support
[05:11:07.839](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_G1Xe/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
[05:11:07.876](0.036s) ok 175 - pg_stat_ssl with client certificate: exit code 0
[05:11:07.876](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr
[05:11:07.876](0.000s) ok 177 - pg_stat_ssl with client certificate: matches
[05:11:07.886](0.010s) ok 178 - certificate authorization fails because of file permissions
[05:11:07.886](0.000s) ok 179 - certificate authorization fails because of file permissions: matches
[05:11:07.902](0.015s) ok 180 - certificate authorization fails with client cert belonging to another user
[05:11:07.902](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches
[05:11:07.916](0.014s) ok 182 - certificate authorization fails with revoked client cert
[05:11:07.916](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches
[05:11:07.916](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match
[05:11:07.935](0.019s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name
[05:11:07.935](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr
[05:11:07.935](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches
[05:11:07.949](0.014s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name
[05:11:07.949](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches
[05:11:07.950](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match
[05:11:07.965](0.015s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name
[05:11:07.965](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr
[05:11:07.965](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 39026
[05:11:08.204](0.238s) ok 194 - intermediate client certificate is provided by client
[05:11:08.204](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr
[05:11:08.220](0.016s) ok 196 - intermediate client certificate is missing
[05:11:08.220](0.000s) ok 197 - intermediate client certificate is missing: matches
[05:11:08.239](0.019s) ok 198 - logged client certificate Subjects are truncated if they're too long
[05:11:08.239](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 39040
[05:11:08.471](0.231s) ok 200 - intermediate client certificate is untrusted
[05:11:08.471](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 39050
[05:11:08.703](0.232s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory
[05:11:08.703](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches
[05:11:08.717](0.014s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory
[05:11:08.718](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 39062
[05:11:08.946](0.228s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1
[05:11:08.946](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.
[05:11:08.946](0.000s) #          got: '2'
#     expected: '0'
[05:11:08.946](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[05:11:08.947](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.
[05:11:08.947](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 57504 failed: SSL SYSCALL error: EOF detected'
#     expected: ''
[05:11:08.966](0.019s) ok 208 - connect without requesting ocsp response when sslocspstapling=0
[05:11:08.966](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 39074
[05:11:09.190](0.224s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1
[05:11:09.209](0.019s) ok 211 - connect without requesting ocsp response when sslocspstapling=0
[05:11:09.209](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 39086
[05:11:09.434](0.225s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1
[05:11:09.452](0.018s) ok 214 - connect without requesting ocsp response when sslocspstapling=0
[05:11:09.453](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 39098
[05:11:09.679](0.226s) ok 216 - failed with an expired ocsp response when sslocspstapling=1
[05:11:09.698](0.019s) ok 217 - connect without requesting ocsp response when sslocspstapling=0
[05:11:09.698](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 39110
[05:11:09.926](0.228s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1
[05:11:09.926](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.
[05:11:09.926](0.000s) #          got: '2'
#     expected: '0'
[05:11:09.927](0.001s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[05:11:09.927](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.
[05:11:09.927](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 57504 failed: SSL SYSCALL error: EOF detected'
#     expected: ''
[05:11:09.947](0.020s) ok 221 - connect without requesting ocsp response when sslocspstapling=0
[05:11:09.948](0.000s) ok 222 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/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 39122
[05:11:10.176](0.228s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1
[05:11:10.195](0.020s) ok 224 - connect without requesting ocsp response when sslocspstapling=0
[05:11:10.196](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 39134
[05:11:10.423](0.227s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1
[05:11:10.441](0.018s) ok 227 - connect without requesting ocsp response when sslocspstapling=0
[05:11:10.442](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 39146
[05:11:10.669](0.227s) ok 229 - failed with an expired ocsp response when sslocspstapling=1
[05:11:10.688](0.019s) ok 230 - connect without requesting ocsp response when sslocspstapling=0
[05:11:10.689](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 39158
[05:11:10.917](0.229s) ok 232 - failed with an expired ocsp response when sslocspstapling=1
[05:11:10.937](0.019s) ok 233 - connect without requesting ocsp response when sslocspstapling=0
[05:11:10.937](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-04-06 05:11:02.231 UTC [37983][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:02.231 UTC [37983][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:02.237 UTC [37994][startup] LOG:  database system was shut down at 2024-04-06 05:08:39 UTC
2024-04-06 05:11:02.240 UTC [37983][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:02.348 UTC [38040][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.348 UTC [38040][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-04-06 05:11:02.348 UTC [38040][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.354 UTC [38040][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-04-06 05:11:02.358 UTC [38040][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.369 UTC [38050][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.374 UTC [38050][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-04-06 05:11:02.374 UTC [38050][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.377 UTC [38050][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-04-06 05:11:02.377 UTC [38050][client backend] [001_ssltests.pl][1/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-04-06 05:11:02.378 UTC [38050][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.417 UTC [38068][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.417 UTC [38068][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-04-06 05:11:02.417 UTC [38068][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.418 UTC [38068][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-04-06 05:11:02.418 UTC [38068][client backend] [001_ssltests.pl][2/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-04-06 05:11:02.418 UTC [38068][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.463 UTC [38085][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.464 UTC [38085][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-04-06 05:11:02.464 UTC [38085][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.465 UTC [38085][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-04-06 05:11:02.465 UTC [38085][client backend] [001_ssltests.pl][3/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-04-06 05:11:02.465 UTC [38085][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.506 UTC [38114][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.506 UTC [38114][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-04-06 05:11:02.506 UTC [38114][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.507 UTC [38114][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-04-06 05:11:02.507 UTC [38114][client backend] [001_ssltests.pl][4/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-04-06 05:11:02.509 UTC [38114][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.556 UTC [38132][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.557 UTC [38132][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-04-06 05:11:02.557 UTC [38132][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.557 UTC [38132][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-04-06 05:11:02.557 UTC [38132][client backend] [001_ssltests.pl][5/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-04-06 05:11:02.594 UTC [38132][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.037 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.690 UTC [38183][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.690 UTC [38183][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-04-06 05:11:02.690 UTC [38183][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.691 UTC [38183][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-04-06 05:11:02.691 UTC [38183][client backend] [001_ssltests.pl][6/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-04-06 05:11:02.775 UTC [38183][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.085 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.815 UTC [38225][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.816 UTC [38225][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-04-06 05:11:02.816 UTC [38225][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.816 UTC [38225][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-04-06 05:11:02.817 UTC [38225][client backend] [001_ssltests.pl][7/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-04-06 05:11:02.832 UTC [38225][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.016 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.869 UTC [38257][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.870 UTC [38257][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-04-06 05:11:02.870 UTC [38257][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.871 UTC [38257][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-04-06 05:11:02.871 UTC [38257][client backend] [001_ssltests.pl][8/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-04-06 05:11:02.899 UTC [38257][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.911 UTC [38278][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.912 UTC [38278][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-04-06 05:11:02.912 UTC [38278][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.913 UTC [38278][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-04-06 05:11:02.913 UTC [38278][client backend] [001_ssltests.pl][9/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-04-06 05:11:02.928 UTC [38278][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.017 user=postgres database=postgres host=[local]
2024-04-06 05:11:02.963 UTC [38306][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-06 05:11:02.964 UTC [38306][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-04-06 05:11:02.964 UTC [38306][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-06 05:11:02.967 UTC [38306][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-04-06 05:11:02.967 UTC [38306][client backend] [001_ssltests.pl][10/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-04-06 05:11:02.990 UTC [38306][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local]
2024-04-06 05:11:03.003 UTC [37983][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:03.003 UTC [37983][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:03.005 UTC [37983][postmaster] LOG:  background worker "logical replication launcher" (PID 37998) exited with exit code 1
2024-04-06 05:11:03.006 UTC [37992][checkpointer] LOG:  shutting down
2024-04-06 05:11:03.006 UTC [37992][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:03.046 UTC [37992][checkpointer] LOG:  checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.040 s, sync=0.001 s, total=0.040 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/41012D8, redo lsn=0/41012D8
2024-04-06 05:11:03.049 UTC [37983][postmaster] LOG:  database system is shut down
2024-04-06 05:11:03.116 UTC [38372][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:03.116 UTC [38372][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:03.116 UTC [38372][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:03.118 UTC [38379][startup] LOG:  database system was shut down at 2024-04-06 05:11:03 UTC
2024-04-06 05:11:03.120 UTC [38372][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:03.222 UTC [38372][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:03.223 UTC [38372][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:03.231 UTC [38372][postmaster] LOG:  background worker "logical replication launcher" (PID 38382) exited with exit code 1
2024-04-06 05:11:03.231 UTC [38377][checkpointer] LOG:  shutting down
2024-04-06 05:11:03.231 UTC [38377][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:03.231 UTC [38377][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/4101350, redo lsn=0/4101350
2024-04-06 05:11:03.234 UTC [38372][postmaster] LOG:  database system is shut down
2024-04-06 05:11:03.343 UTC [38468][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-04-06 05:11:03.343 UTC [38468][postmaster] LOG:  database system is shut down
2024-04-06 05:11:03.457 UTC [38502][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:03.457 UTC [38502][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:03.457 UTC [38502][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:03.459 UTC [38508][startup] LOG:  database system was shut down at 2024-04-06 05:11:03 UTC
2024-04-06 05:11:03.462 UTC [38502][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:03.562 UTC [38502][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:03.562 UTC [38502][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:03.565 UTC [38502][postmaster] LOG:  background worker "logical replication launcher" (PID 38511) exited with exit code 1
2024-04-06 05:11:03.568 UTC [38506][checkpointer] LOG:  shutting down
2024-04-06 05:11:03.568 UTC [38506][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:03.568 UTC [38506][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/41013C8, redo lsn=0/41013C8
2024-04-06 05:11:03.569 UTC [38502][postmaster] LOG:  database system is shut down
2024-04-06 05:11:03.678 UTC [38567][postmaster] FATAL:  could not set SSL protocol version range
2024-04-06 05:11:03.678 UTC [38567][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-04-06 05:11:03.678 UTC [38567][postmaster] LOG:  database system is shut down
2024-04-06 05:11:03.785 UTC [38592][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:03.785 UTC [38592][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:03.785 UTC [38592][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:03.787 UTC [38596][startup] LOG:  database system was shut down at 2024-04-06 05:11:03 UTC
2024-04-06 05:11:03.791 UTC [38592][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:03.876 UTC [38592][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:03.876 UTC [38592][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:03.878 UTC [38592][postmaster] LOG:  background worker "logical replication launcher" (PID 38599) exited with exit code 1
2024-04-06 05:11:03.878 UTC [38594][checkpointer] LOG:  shutting down
2024-04-06 05:11:03.878 UTC [38594][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:03.878 UTC [38594][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/4101440, redo lsn=0/4101440
2024-04-06 05:11:03.880 UTC [38592][postmaster] LOG:  database system is shut down
2024-04-06 05:11:03.991 UTC [38647][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:03.991 UTC [38647][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:03.991 UTC [38647][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:03.994 UTC [38650][startup] LOG:  database system was shut down at 2024-04-06 05:11:03 UTC
2024-04-06 05:11:03.997 UTC [38647][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:04.091 UTC [38664][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52902
2024-04-06 05:11:04.091 UTC [38664][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-04-06 05:11:04.091 UTC [38664][client backend] [[unknown]][0/1:0] DETAIL:  Client IP address resolved to "localhost", forward lookup not checked.
2024-04-06 05:11:04.101 UTC [38666][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52903
2024-04-06 05:11:04.107 UTC [38666][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-04-06 05:11:04.107 UTC [38666][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-04-06 05:11:04.112 UTC [38666][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-04-06 05:11:04.123 UTC [38668][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52904
2024-04-06 05:11:04.123 UTC [38666][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=ssltestuser database=trustdb host=localhost port=52903
2024-04-06 05:11:04.124 UTC [38668][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-04-06 05:11:04.134 UTC [38670][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52905
2024-04-06 05:11:04.136 UTC [38670][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-04-06 05:11:04.144 UTC [38673][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52906
2024-04-06 05:11:04.149 UTC [38673][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-04-06 05:11:04.158 UTC [38677][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52908
2024-04-06 05:11:04.162 UTC [38677][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-04-06 05:11:04.171 UTC [38681][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52910
2024-04-06 05:11:04.175 UTC [38681][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-04-06 05:11:04.184 UTC [38683][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52911
2024-04-06 05:11:04.188 UTC [38683][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-04-06 05:11:04.196 UTC [38687][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52912
2024-04-06 05:11:04.201 UTC [38687][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-04-06 05:11:04.201 UTC [38687][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-04-06 05:11:04.202 UTC [38687][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-04-06 05:11:04.202 UTC [38687][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=52912
2024-04-06 05:11:04.210 UTC [38689][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52913
2024-04-06 05:11:04.214 UTC [38689][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-04-06 05:11:04.214 UTC [38689][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-04-06 05:11:04.215 UTC [38689][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-04-06 05:11:04.216 UTC [38689][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=52913
2024-04-06 05:11:04.222 UTC [38691][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52914
2024-04-06 05:11:04.227 UTC [38691][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-04-06 05:11:04.227 UTC [38691][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-04-06 05:11:04.228 UTC [38691][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-04-06 05:11:04.228 UTC [38691][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=52914
2024-04-06 05:11:04.236 UTC [38693][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52915
2024-04-06 05:11:04.241 UTC [38693][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-04-06 05:11:04.241 UTC [38693][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-04-06 05:11:04.242 UTC [38693][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-04-06 05:11:04.242 UTC [38693][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=52915
2024-04-06 05:11:04.251 UTC [38695][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52916
2024-04-06 05:11:04.256 UTC [38695][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-04-06 05:11:04.256 UTC [38695][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-04-06 05:11:04.257 UTC [38695][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-04-06 05:11:04.257 UTC [38695][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=52916
2024-04-06 05:11:04.265 UTC [38697][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52917
2024-04-06 05:11:04.270 UTC [38697][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-04-06 05:11:04.270 UTC [38697][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-04-06 05:11:04.271 UTC [38697][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-04-06 05:11:04.271 UTC [38697][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=52917
2024-04-06 05:11:04.280 UTC [38700][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52918
2024-04-06 05:11:04.285 UTC [38700][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-04-06 05:11:04.285 UTC [38700][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-04-06 05:11:04.285 UTC [38700][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-04-06 05:11:04.286 UTC [38700][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=52918
2024-04-06 05:11:04.295 UTC [38708][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52919
2024-04-06 05:11:04.300 UTC [38708][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-04-06 05:11:04.300 UTC [38708][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-04-06 05:11:04.301 UTC [38708][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=52919
2024-04-06 05:11:04.309 UTC [38710][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52920
2024-04-06 05:11:04.315 UTC [38710][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-04-06 05:11:04.315 UTC [38710][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-04-06 05:11:04.316 UTC [38710][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-04-06 05:11:04.316 UTC [38710][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=52920
2024-04-06 05:11:04.324 UTC [38712][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52921
2024-04-06 05:11:04.328 UTC [38712][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-04-06 05:11:04.337 UTC [38714][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52922
2024-04-06 05:11:04.342 UTC [38714][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-04-06 05:11:04.350 UTC [38716][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52923
2024-04-06 05:11:04.355 UTC [38716][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-04-06 05:11:04.355 UTC [38716][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-04-06 05:11:04.356 UTC [38716][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-04-06 05:11:04.356 UTC [38716][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=52923
2024-04-06 05:11:04.365 UTC [38720][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52924
2024-04-06 05:11:04.370 UTC [38720][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-04-06 05:11:04.370 UTC [38720][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-04-06 05:11:04.371 UTC [38720][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-04-06 05:11:04.371 UTC [38720][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=52924
2024-04-06 05:11:04.379 UTC [38724][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52925
2024-04-06 05:11:04.384 UTC [38724][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-04-06 05:11:04.384 UTC [38724][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-04-06 05:11:04.385 UTC [38724][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-04-06 05:11:04.386 UTC [38724][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=52925
2024-04-06 05:11:04.394 UTC [38727][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52927
2024-04-06 05:11:04.399 UTC [38727][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-04-06 05:11:04.399 UTC [38727][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-04-06 05:11:04.400 UTC [38727][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-04-06 05:11:04.400 UTC [38727][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=52927
2024-04-06 05:11:04.409 UTC [38729][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52928
2024-04-06 05:11:04.417 UTC [38647][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:04.417 UTC [38647][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:04.420 UTC [38647][postmaster] LOG:  background worker "logical replication launcher" (PID 38653) exited with exit code 1
2024-04-06 05:11:04.420 UTC [38648][checkpointer] LOG:  shutting down
2024-04-06 05:11:04.420 UTC [38648][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:04.420 UTC [38648][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/41014B8, redo lsn=0/41014B8
2024-04-06 05:11:04.422 UTC [38647][postmaster] LOG:  database system is shut down
2024-04-06 05:11:04.531 UTC [38738][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:04.531 UTC [38738][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:04.531 UTC [38738][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:04.533 UTC [38744][startup] LOG:  database system was shut down at 2024-04-06 05:11:04 UTC
2024-04-06 05:11:04.537 UTC [38738][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:04.629 UTC [38754][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52930
2024-04-06 05:11:04.633 UTC [38754][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-04-06 05:11:04.634 UTC [38754][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-04-06 05:11:04.638 UTC [38754][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-04-06 05:11:04.639 UTC [38754][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=52930
2024-04-06 05:11:04.647 UTC [38756][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52931
2024-04-06 05:11:04.656 UTC [38738][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:04.656 UTC [38738][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:04.658 UTC [38738][postmaster] LOG:  background worker "logical replication launcher" (PID 38747) exited with exit code 1
2024-04-06 05:11:04.658 UTC [38742][checkpointer] LOG:  shutting down
2024-04-06 05:11:04.658 UTC [38742][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:04.659 UTC [38742][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/4101530, redo lsn=0/4101530
2024-04-06 05:11:04.660 UTC [38738][postmaster] LOG:  database system is shut down
2024-04-06 05:11:04.770 UTC [38767][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:04.770 UTC [38767][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:04.770 UTC [38767][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:04.772 UTC [38770][startup] LOG:  database system was shut down at 2024-04-06 05:11:04 UTC
2024-04-06 05:11:04.776 UTC [38767][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:04.872 UTC [38778][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52933
2024-04-06 05:11:04.876 UTC [38778][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-04-06 05:11:04.877 UTC [38778][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-04-06 05:11:04.881 UTC [38778][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-04-06 05:11:04.882 UTC [38778][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=52933
2024-04-06 05:11:04.887 UTC [38767][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:04.887 UTC [38767][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:04.889 UTC [38767][postmaster] LOG:  background worker "logical replication launcher" (PID 38773) exited with exit code 1
2024-04-06 05:11:04.889 UTC [38768][checkpointer] LOG:  shutting down
2024-04-06 05:11:04.889 UTC [38768][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:04.889 UTC [38768][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/41015A8, redo lsn=0/41015A8
2024-04-06 05:11:04.891 UTC [38767][postmaster] LOG:  database system is shut down
2024-04-06 05:11:05.005 UTC [38789][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:05.005 UTC [38789][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:05.005 UTC [38789][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:05.007 UTC [38792][startup] LOG:  database system was shut down at 2024-04-06 05:11:04 UTC
2024-04-06 05:11:05.010 UTC [38789][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:05.102 UTC [38802][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52935
2024-04-06 05:11:05.108 UTC [38802][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-04-06 05:11:05.108 UTC [38802][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-04-06 05:11:05.113 UTC [38802][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-04-06 05:11:05.113 UTC [38802][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=52935
2024-04-06 05:11:05.122 UTC [38804][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52936
2024-04-06 05:11:05.127 UTC [38804][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-04-06 05:11:05.127 UTC [38804][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-04-06 05:11:05.128 UTC [38804][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-04-06 05:11:05.128 UTC [38804][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=52936
2024-04-06 05:11:05.138 UTC [38806][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52937
2024-04-06 05:11:05.144 UTC [38806][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-04-06 05:11:05.144 UTC [38806][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-04-06 05:11:05.145 UTC [38806][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-04-06 05:11:05.145 UTC [38806][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=52937
2024-04-06 05:11:05.155 UTC [38808][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52938
2024-04-06 05:11:05.169 UTC [38810][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52939
2024-04-06 05:11:05.179 UTC [38789][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:05.179 UTC [38789][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:05.180 UTC [38789][postmaster] LOG:  background worker "logical replication launcher" (PID 38795) exited with exit code 1
2024-04-06 05:11:05.181 UTC [38790][checkpointer] LOG:  shutting down
2024-04-06 05:11:05.181 UTC [38790][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:05.181 UTC [38790][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/4101620, redo lsn=0/4101620
2024-04-06 05:11:05.183 UTC [38789][postmaster] LOG:  database system is shut down
2024-04-06 05:11:05.290 UTC [38822][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:05.290 UTC [38822][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:05.290 UTC [38822][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:05.293 UTC [38826][startup] LOG:  database system was shut down at 2024-04-06 05:11:05 UTC
2024-04-06 05:11:05.296 UTC [38822][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:05.392 UTC [38834][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52941
2024-04-06 05:11:05.397 UTC [38834][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-04-06 05:11:05.397 UTC [38834][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-04-06 05:11:05.402 UTC [38834][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-04-06 05:11:05.403 UTC [38834][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=52941
2024-04-06 05:11:05.412 UTC [38840][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52942
2024-04-06 05:11:05.425 UTC [38845][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52943
2024-04-06 05:11:05.439 UTC [38822][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:05.439 UTC [38822][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:05.440 UTC [38822][postmaster] LOG:  background worker "logical replication launcher" (PID 38829) exited with exit code 1
2024-04-06 05:11:05.441 UTC [38824][checkpointer] LOG:  shutting down
2024-04-06 05:11:05.441 UTC [38824][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:05.441 UTC [38824][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/4101698, redo lsn=0/4101698
2024-04-06 05:11:05.443 UTC [38822][postmaster] LOG:  database system is shut down
2024-04-06 05:11:05.551 UTC [38851][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:05.551 UTC [38851][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:05.552 UTC [38851][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:05.553 UTC [38856][startup] LOG:  database system was shut down at 2024-04-06 05:11:05 UTC
2024-04-06 05:11:05.557 UTC [38851][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:05.649 UTC [38861][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52945
2024-04-06 05:11:05.654 UTC [38861][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-04-06 05:11:05.654 UTC [38861][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-04-06 05:11:05.659 UTC [38861][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-04-06 05:11:05.659 UTC [38861][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=52945
2024-04-06 05:11:05.667 UTC [38863][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52946
2024-04-06 05:11:05.672 UTC [38863][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-04-06 05:11:05.672 UTC [38863][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-04-06 05:11:05.673 UTC [38863][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-04-06 05:11:05.673 UTC [38863][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=52946
2024-04-06 05:11:05.681 UTC [38865][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52947
2024-04-06 05:11:05.693 UTC [38867][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52948
2024-04-06 05:11:05.698 UTC [38867][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-04-06 05:11:05.698 UTC [38867][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-04-06 05:11:05.699 UTC [38867][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-04-06 05:11:05.699 UTC [38867][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=52948
2024-04-06 05:11:05.707 UTC [38870][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52949
2024-04-06 05:11:05.712 UTC [38870][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-04-06 05:11:05.712 UTC [38870][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-04-06 05:11:05.713 UTC [38870][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-04-06 05:11:05.713 UTC [38870][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=52949
2024-04-06 05:11:05.722 UTC [38873][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52950
2024-04-06 05:11:05.727 UTC [38873][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-04-06 05:11:05.727 UTC [38873][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-04-06 05:11:05.728 UTC [38873][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-04-06 05:11:05.728 UTC [38873][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=52950
2024-04-06 05:11:05.738 UTC [38875][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52951
2024-04-06 05:11:05.751 UTC [38877][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52952
2024-04-06 05:11:05.760 UTC [38851][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:05.760 UTC [38851][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:05.762 UTC [38851][postmaster] LOG:  background worker "logical replication launcher" (PID 38859) exited with exit code 1
2024-04-06 05:11:05.762 UTC [38854][checkpointer] LOG:  shutting down
2024-04-06 05:11:05.762 UTC [38854][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:05.763 UTC [38854][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/4101710, redo lsn=0/4101710
2024-04-06 05:11:05.765 UTC [38851][postmaster] LOG:  database system is shut down
2024-04-06 05:11:05.878 UTC [38879][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:05.878 UTC [38879][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:05.878 UTC [38879][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:05.880 UTC [38884][startup] LOG:  database system was shut down at 2024-04-06 05:11:05 UTC
2024-04-06 05:11:05.883 UTC [38879][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:05.979 UTC [38889][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52953
2024-04-06 05:11:05.984 UTC [38889][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-04-06 05:11:05.984 UTC [38889][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-04-06 05:11:05.988 UTC [38889][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-04-06 05:11:05.988 UTC [38889][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=52953
2024-04-06 05:11:05.995 UTC [38891][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52954
2024-04-06 05:11:06.000 UTC [38891][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-04-06 05:11:06.000 UTC [38891][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-04-06 05:11:06.001 UTC [38891][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-04-06 05:11:06.001 UTC [38891][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=52954
2024-04-06 05:11:06.009 UTC [38893][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52955
2024-04-06 05:11:06.020 UTC [38879][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:06.020 UTC [38879][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:06.022 UTC [38879][postmaster] LOG:  background worker "logical replication launcher" (PID 38887) exited with exit code 1
2024-04-06 05:11:06.022 UTC [38882][checkpointer] LOG:  shutting down
2024-04-06 05:11:06.022 UTC [38882][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:06.022 UTC [38882][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/4101788, redo lsn=0/4101788
2024-04-06 05:11:06.024 UTC [38879][postmaster] LOG:  database system is shut down
2024-04-06 05:11:06.138 UTC [38897][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:06.139 UTC [38897][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:06.139 UTC [38897][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:06.140 UTC [38900][startup] LOG:  database system was shut down at 2024-04-06 05:11:06 UTC
2024-04-06 05:11:06.143 UTC [38897][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:06.241 UTC [38905][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52956
2024-04-06 05:11:06.246 UTC [38905][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-04-06 05:11:06.246 UTC [38905][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-04-06 05:11:06.250 UTC [38905][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-04-06 05:11:06.251 UTC [38905][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=52956
2024-04-06 05:11:06.258 UTC [38907][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52957
2024-04-06 05:11:06.264 UTC [38907][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-04-06 05:11:06.264 UTC [38907][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-04-06 05:11:06.264 UTC [38907][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-04-06 05:11:06.265 UTC [38907][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=52957
2024-04-06 05:11:06.273 UTC [38909][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52958
2024-04-06 05:11:06.278 UTC [38909][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-04-06 05:11:06.278 UTC [38909][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-04-06 05:11:06.279 UTC [38909][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-04-06 05:11:06.279 UTC [38909][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=52958
2024-04-06 05:11:06.283 UTC [38897][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:06.283 UTC [38897][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:06.284 UTC [38897][postmaster] LOG:  background worker "logical replication launcher" (PID 38903) exited with exit code 1
2024-04-06 05:11:06.284 UTC [38898][checkpointer] LOG:  shutting down
2024-04-06 05:11:06.284 UTC [38898][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:06.285 UTC [38898][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/4101800, redo lsn=0/4101800
2024-04-06 05:11:06.286 UTC [38897][postmaster] LOG:  database system is shut down
2024-04-06 05:11:06.401 UTC [38911][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:06.401 UTC [38911][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:06.401 UTC [38911][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:06.402 UTC [38914][startup] LOG:  database system was shut down at 2024-04-06 05:11:06 UTC
2024-04-06 05:11:06.406 UTC [38911][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:06.503 UTC [38919][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52959
2024-04-06 05:11:06.508 UTC [38919][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-04-06 05:11:06.508 UTC [38919][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-04-06 05:11:06.513 UTC [38919][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-04-06 05:11:06.513 UTC [38919][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=52959
2024-04-06 05:11:06.521 UTC [38921][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52960
2024-04-06 05:11:06.527 UTC [38921][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-04-06 05:11:06.527 UTC [38921][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-04-06 05:11:06.528 UTC [38921][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-04-06 05:11:06.528 UTC [38921][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=52960
2024-04-06 05:11:06.537 UTC [38923][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52961
2024-04-06 05:11:06.545 UTC [38911][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:06.545 UTC [38911][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:06.547 UTC [38911][postmaster] LOG:  background worker "logical replication launcher" (PID 38917) exited with exit code 1
2024-04-06 05:11:06.547 UTC [38912][checkpointer] LOG:  shutting down
2024-04-06 05:11:06.547 UTC [38912][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:06.547 UTC [38912][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/4101878, redo lsn=0/4101878
2024-04-06 05:11:06.549 UTC [38911][postmaster] LOG:  database system is shut down
2024-04-06 05:11:06.664 UTC [38925][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:06.664 UTC [38925][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:06.664 UTC [38925][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:06.666 UTC [38928][startup] LOG:  database system was shut down at 2024-04-06 05:11:06 UTC
2024-04-06 05:11:06.669 UTC [38925][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:06.766 UTC [38933][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52962
2024-04-06 05:11:06.772 UTC [38933][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-04-06 05:11:06.772 UTC [38933][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-04-06 05:11:06.776 UTC [38933][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-04-06 05:11:06.777 UTC [38933][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=52962
2024-04-06 05:11:06.785 UTC [38935][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52963
2024-04-06 05:11:06.790 UTC [38935][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-04-06 05:11:06.790 UTC [38935][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-04-06 05:11:06.791 UTC [38935][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-04-06 05:11:06.792 UTC [38935][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=52963
2024-04-06 05:11:06.800 UTC [38937][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52964
2024-04-06 05:11:06.806 UTC [38937][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-04-06 05:11:06.806 UTC [38937][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-04-06 05:11:06.806 UTC [38937][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-04-06 05:11:06.807 UTC [38937][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=52964
2024-04-06 05:11:06.811 UTC [38925][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:06.811 UTC [38925][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:06.813 UTC [38925][postmaster] LOG:  background worker "logical replication launcher" (PID 38931) exited with exit code 1
2024-04-06 05:11:06.813 UTC [38926][checkpointer] LOG:  shutting down
2024-04-06 05:11:06.813 UTC [38926][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:06.813 UTC [38926][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/41018F0, redo lsn=0/41018F0
2024-04-06 05:11:06.815 UTC [38925][postmaster] LOG:  database system is shut down
2024-04-06 05:11:06.929 UTC [38939][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:06.929 UTC [38939][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:06.929 UTC [38939][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:06.931 UTC [38942][startup] LOG:  database system was shut down at 2024-04-06 05:11:06 UTC
2024-04-06 05:11:06.934 UTC [38939][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:07.031 UTC [38947][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52965
2024-04-06 05:11:07.036 UTC [38947][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-04-06 05:11:07.036 UTC [38947][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-04-06 05:11:07.041 UTC [38947][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-04-06 05:11:07.042 UTC [38947][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=52965
2024-04-06 05:11:07.050 UTC [38949][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52966
2024-04-06 05:11:07.059 UTC [38939][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:07.059 UTC [38939][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:07.060 UTC [38939][postmaster] LOG:  background worker "logical replication launcher" (PID 38945) exited with exit code 1
2024-04-06 05:11:07.061 UTC [38940][checkpointer] LOG:  shutting down
2024-04-06 05:11:07.061 UTC [38940][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:07.061 UTC [38940][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/4101968, redo lsn=0/4101968
2024-04-06 05:11:07.063 UTC [38939][postmaster] LOG:  database system is shut down
2024-04-06 05:11:07.178 UTC [38951][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:07.178 UTC [38951][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:07.178 UTC [38951][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:07.180 UTC [38954][startup] LOG:  database system was shut down at 2024-04-06 05:11:07 UTC
2024-04-06 05:11:07.184 UTC [38951][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:07.279 UTC [38959][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52967
2024-04-06 05:11:07.284 UTC [38959][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-04-06 05:11:07.299 UTC [38962][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52968
2024-04-06 05:11:07.305 UTC [38962][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-04-06 05:11:07.305 UTC [38962][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-04-06 05:11:07.310 UTC [38962][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-04-06 05:11:07.311 UTC [38962][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=52968
2024-04-06 05:11:07.321 UTC [38964][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52969
2024-04-06 05:11:07.330 UTC [38951][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:07.330 UTC [38951][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:07.332 UTC [38951][postmaster] LOG:  background worker "logical replication launcher" (PID 38957) exited with exit code 1
2024-04-06 05:11:07.332 UTC [38952][checkpointer] LOG:  shutting down
2024-04-06 05:11:07.332 UTC [38952][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:07.332 UTC [38952][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/41019E0, redo lsn=0/41019E0
2024-04-06 05:11:07.334 UTC [38951][postmaster] LOG:  database system is shut down
2024-04-06 05:11:07.449 UTC [38966][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:07.449 UTC [38966][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:07.449 UTC [38966][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:07.451 UTC [38969][startup] LOG:  database system was shut down at 2024-04-06 05:11:07 UTC
2024-04-06 05:11:07.455 UTC [38966][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:07.551 UTC [38974][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52970
2024-04-06 05:11:07.557 UTC [38974][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-04-06 05:11:07.557 UTC [38974][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-04-06 05:11:07.562 UTC [38974][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-04-06 05:11:07.563 UTC [38974][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=52970
2024-04-06 05:11:07.571 UTC [38976][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52971
2024-04-06 05:11:07.576 UTC [38976][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-04-06 05:11:07.585 UTC [38978][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52972
2024-04-06 05:11:07.590 UTC [38978][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-04-06 05:11:07.598 UTC [38980][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52973
2024-04-06 05:11:07.602 UTC [38980][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-04-06 05:11:07.603 UTC [38980][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-04-06 05:11:07.603 UTC [38980][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-04-06 05:11:07.605 UTC [38980][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=52973
2024-04-06 05:11:07.614 UTC [38982][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52974
2024-04-06 05:11:07.618 UTC [38982][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-04-06 05:11:07.618 UTC [38982][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-04-06 05:11:07.619 UTC [38982][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-04-06 05:11:07.620 UTC [38982][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=52974
2024-04-06 05:11:07.645 UTC [38987][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52975
2024-04-06 05:11:07.650 UTC [38987][client backend] [[unknown]][3/1:0] FATAL:  connection requires a valid client certificate
2024-04-06 05:11:07.658 UTC [38989][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52976
2024-04-06 05:11:07.666 UTC [38989][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-04-06 05:11:07.666 UTC [38989][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-04-06 05:11:07.670 UTC [38989][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_G1Xe/client.key$$
2024-04-06 05:11:07.671 UTC [38989][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb host=localhost port=52976
2024-04-06 05:11:07.679 UTC [38991][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52977
2024-04-06 05:11:07.686 UTC [38991][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-04-06 05:11:07.686 UTC [38991][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-04-06 05:11:07.686 UTC [38991][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_G1Xe/client-der.key$$
2024-04-06 05:11:07.687 UTC [38991][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=52977
2024-04-06 05:11:07.694 UTC [38993][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52978
2024-04-06 05:11:07.702 UTC [38993][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-04-06 05:11:07.702 UTC [38993][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-04-06 05:11:07.703 UTC [38993][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_G1Xe/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$
2024-04-06 05:11:07.703 UTC [38993][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=52978
2024-04-06 05:11:07.710 UTC [38995][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52979
2024-04-06 05:11:07.717 UTC [38995][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-04-06 05:11:07.717 UTC [38995][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-04-06 05:11:07.718 UTC [38995][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_G1Xe/client-encrypted-der.key sslpassword='dUmmyP^#+'$$
2024-04-06 05:11:07.718 UTC [38995][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=52979
2024-04-06 05:11:07.726 UTC [38997][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52980
2024-04-06 05:11:07.734 UTC [38997][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-04-06 05:11:07.734 UTC [38997][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-04-06 05:11:07.735 UTC [38997][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_G1Xe/client.key$$
2024-04-06 05:11:07.735 UTC [38997][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=52980
2024-04-06 05:11:07.743 UTC [38999][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52981
2024-04-06 05:11:07.750 UTC [38999][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-04-06 05:11:07.750 UTC [38999][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-04-06 05:11:07.751 UTC [38999][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_G1Xe/client.key$$
2024-04-06 05:11:07.751 UTC [38999][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=52981
2024-04-06 05:11:07.759 UTC [39001][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52982
2024-04-06 05:11:07.764 UTC [39001][client backend] [[unknown]][10/1:0] FATAL:  connection requires a valid client certificate
2024-04-06 05:11:07.772 UTC [39003][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52983
2024-04-06 05:11:07.774 UTC [39003][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-04-06 05:11:07.781 UTC [39005][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52984
2024-04-06 05:11:07.789 UTC [39005][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-04-06 05:11:07.789 UTC [39005][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-04-06 05:11:07.793 UTC [39005][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_G1Xe/client-dn.key$$
2024-04-06 05:11:07.794 UTC [39005][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb_dn host=localhost port=52984
2024-04-06 05:11:07.802 UTC [39007][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52985
2024-04-06 05:11:07.810 UTC [39007][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-04-06 05:11:07.810 UTC [39007][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-04-06 05:11:07.814 UTC [39007][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_G1Xe/client-dn.key$$
2024-04-06 05:11:07.815 UTC [39007][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb_dn_re host=localhost port=52985
2024-04-06 05:11:07.823 UTC [39009][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52986
2024-04-06 05:11:07.830 UTC [39009][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-04-06 05:11:07.830 UTC [39009][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-04-06 05:11:07.835 UTC [39009][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_G1Xe/client-dn.key$$
2024-04-06 05:11:07.836 UTC [39009][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=certdb_cn host=localhost port=52986
2024-04-06 05:11:07.864 UTC [39012][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52987
2024-04-06 05:11:07.871 UTC [39012][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-04-06 05:11:07.871 UTC [39012][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-04-06 05:11:07.872 UTC [39012][client backend] [001_ssltests.pl][14/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-04-06 05:11:07.874 UTC [39012][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=52987
2024-04-06 05:11:07.883 UTC [39014][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52988
2024-04-06 05:11:07.885 UTC [39014][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-04-06 05:11:07.893 UTC [39016][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52989
2024-04-06 05:11:07.900 UTC [39016][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-04-06 05:11:07.900 UTC [39016][client backend] [[unknown]][15/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-04-06 05:11:07.900 UTC [39016][client backend] [[unknown]][15/1:0] FATAL:  certificate authentication failed for user "anotheruser"
2024-04-06 05:11:07.900 UTC [39016][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-04-06 05:11:07.908 UTC [39018][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52990
2024-04-06 05:11:07.914 UTC [39018][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-04-06 05:11:07.914 UTC [39018][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-04-06 05:11:07.922 UTC [39020][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52991
2024-04-06 05:11:07.929 UTC [39020][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-04-06 05:11:07.929 UTC [39020][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-04-06 05:11:07.933 UTC [39020][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_G1Xe/client.key$$
2024-04-06 05:11:07.934 UTC [39020][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=verifydb host=localhost port=52991
2024-04-06 05:11:07.941 UTC [39022][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52992
2024-04-06 05:11:07.948 UTC [39022][client backend] [[unknown]][17/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-04-06 05:11:07.948 UTC [39022][client backend] [[unknown]][17/1:0] LOG:  certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch
2024-04-06 05:11:07.948 UTC [39022][client backend] [[unknown]][17/1:0] FATAL:  "trust" authentication failed for user "anotheruser"
2024-04-06 05:11:07.948 UTC [39022][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-04-06 05:11:07.956 UTC [39024][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52993
2024-04-06 05:11:07.962 UTC [39024][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-04-06 05:11:07.962 UTC [39024][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-04-06 05:11:07.963 UTC [39024][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_G1Xe/client.key$$
2024-04-06 05:11:07.964 UTC [39024][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=yetanotheruser database=verifydb host=localhost port=52993
2024-04-06 05:11:07.967 UTC [38966][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:07.967 UTC [38966][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:07.969 UTC [38966][postmaster] LOG:  background worker "logical replication launcher" (PID 38972) exited with exit code 1
2024-04-06 05:11:07.969 UTC [38967][checkpointer] LOG:  shutting down
2024-04-06 05:11:07.969 UTC [38967][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:07.970 UTC [38967][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/4101A58, redo lsn=0/4101A58
2024-04-06 05:11:07.972 UTC [38966][postmaster] LOG:  database system is shut down
2024-04-06 05:11:08.086 UTC [39026][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:08.086 UTC [39026][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:08.086 UTC [39026][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:08.088 UTC [39029][startup] LOG:  database system was shut down at 2024-04-06 05:11:07 UTC
2024-04-06 05:11:08.092 UTC [39026][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:08.189 UTC [39034][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52994
2024-04-06 05:11:08.196 UTC [39034][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-04-06 05:11:08.196 UTC [39034][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-04-06 05:11:08.201 UTC [39034][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_G1Xe/client.key sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 host=localhost sslmode=require sslcert=ssl/client+client_ca.crt$$
2024-04-06 05:11:08.202 UTC [39034][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=certdb host=localhost port=52994
2024-04-06 05:11:08.211 UTC [39036][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52995
2024-04-06 05:11:08.218 UTC [39036][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-04-06 05:11:08.218 UTC [39036][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-04-06 05:11:08.227 UTC [39038][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52996
2024-04-06 05:11:08.236 UTC [39038][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-04-06 05:11:08.236 UTC [39038][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-04-06 05:11:08.242 UTC [39026][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:08.242 UTC [39026][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:08.244 UTC [39026][postmaster] LOG:  background worker "logical replication launcher" (PID 39032) exited with exit code 1
2024-04-06 05:11:08.244 UTC [39027][checkpointer] LOG:  shutting down
2024-04-06 05:11:08.244 UTC [39027][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:08.244 UTC [39027][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/4101AD0, redo lsn=0/4101AD0
2024-04-06 05:11:08.246 UTC [39026][postmaster] LOG:  database system is shut down
2024-04-06 05:11:08.361 UTC [39040][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:08.361 UTC [39040][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:08.361 UTC [39040][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:08.363 UTC [39043][startup] LOG:  database system was shut down at 2024-04-06 05:11:08 UTC
2024-04-06 05:11:08.367 UTC [39040][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:08.462 UTC [39048][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52997
2024-04-06 05:11:08.469 UTC [39048][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-04-06 05:11:08.469 UTC [39048][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-04-06 05:11:08.474 UTC [39040][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:08.474 UTC [39040][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:08.475 UTC [39040][postmaster] LOG:  background worker "logical replication launcher" (PID 39046) exited with exit code 1
2024-04-06 05:11:08.475 UTC [39041][checkpointer] LOG:  shutting down
2024-04-06 05:11:08.475 UTC [39041][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:08.476 UTC [39041][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/4101B48, redo lsn=0/4101B48
2024-04-06 05:11:08.477 UTC [39040][postmaster] LOG:  database system is shut down
2024-04-06 05:11:08.592 UTC [39050][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:08.592 UTC [39050][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:08.592 UTC [39050][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:08.594 UTC [39053][startup] LOG:  database system was shut down at 2024-04-06 05:11:08 UTC
2024-04-06 05:11:08.597 UTC [39050][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:08.694 UTC [39058][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52998
2024-04-06 05:11:08.701 UTC [39058][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-04-06 05:11:08.701 UTC [39058][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-04-06 05:11:08.710 UTC [39060][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=52999
2024-04-06 05:11:08.716 UTC [39060][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-04-06 05:11:08.716 UTC [39060][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-04-06 05:11:08.720 UTC [39050][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:08.720 UTC [39050][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:08.722 UTC [39050][postmaster] LOG:  background worker "logical replication launcher" (PID 39056) exited with exit code 1
2024-04-06 05:11:08.722 UTC [39051][checkpointer] LOG:  shutting down
2024-04-06 05:11:08.722 UTC [39051][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:08.722 UTC [39051][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/4101BC0, redo lsn=0/4101BC0
2024-04-06 05:11:08.724 UTC [39050][postmaster] LOG:  database system is shut down
2024-04-06 05:11:08.839 UTC [39062][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:08.839 UTC [39062][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:08.839 UTC [39062][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:08.841 UTC [39065][startup] LOG:  database system was shut down at 2024-04-06 05:11:08 UTC
2024-04-06 05:11:08.845 UTC [39062][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:08.941 UTC [39070][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=53000
2024-04-06 05:11:08.943 UTC [39070][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-06 05:11:08.943 UTC [39070][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-04-06 05:11:08.954 UTC [39072][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=53001
2024-04-06 05:11:08.959 UTC [39072][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-04-06 05:11:08.959 UTC [39072][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-04-06 05:11:08.964 UTC [39072][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-04-06 05:11:08.965 UTC [39072][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=53001
2024-04-06 05:11:08.969 UTC [39062][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:08.969 UTC [39062][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:08.970 UTC [39062][postmaster] LOG:  background worker "logical replication launcher" (PID 39068) exited with exit code 1
2024-04-06 05:11:08.970 UTC [39063][checkpointer] LOG:  shutting down
2024-04-06 05:11:08.970 UTC [39063][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:08.971 UTC [39063][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/4101C38, redo lsn=0/4101C38
2024-04-06 05:11:08.973 UTC [39062][postmaster] LOG:  database system is shut down
2024-04-06 05:11:09.087 UTC [39074][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:09.087 UTC [39074][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:09.087 UTC [39074][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:09.089 UTC [39077][startup] LOG:  database system was shut down at 2024-04-06 05:11:08 UTC
2024-04-06 05:11:09.092 UTC [39074][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:09.186 UTC [39082][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=53002
2024-04-06 05:11:09.187 UTC [39082][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-06 05:11:09.188 UTC [39082][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-04-06 05:11:09.197 UTC [39084][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=53003
2024-04-06 05:11:09.202 UTC [39084][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-04-06 05:11:09.202 UTC [39084][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-04-06 05:11:09.207 UTC [39084][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-04-06 05:11:09.207 UTC [39084][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=53003
2024-04-06 05:11:09.211 UTC [39074][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:09.211 UTC [39074][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:09.213 UTC [39074][postmaster] LOG:  background worker "logical replication launcher" (PID 39080) exited with exit code 1
2024-04-06 05:11:09.213 UTC [39075][checkpointer] LOG:  shutting down
2024-04-06 05:11:09.213 UTC [39075][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:09.213 UTC [39075][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/4101CB0, redo lsn=0/4101CB0
2024-04-06 05:11:09.215 UTC [39074][postmaster] LOG:  database system is shut down
2024-04-06 05:11:09.330 UTC [39086][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:09.330 UTC [39086][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:09.330 UTC [39086][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:09.332 UTC [39089][startup] LOG:  database system was shut down at 2024-04-06 05:11:09 UTC
2024-04-06 05:11:09.336 UTC [39086][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:09.431 UTC [39094][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=53004
2024-04-06 05:11:09.433 UTC [39094][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-06 05:11:09.433 UTC [39094][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-04-06 05:11:09.441 UTC [39096][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=53005
2024-04-06 05:11:09.446 UTC [39096][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-04-06 05:11:09.446 UTC [39096][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-04-06 05:11:09.451 UTC [39096][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-04-06 05:11:09.451 UTC [39096][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=53005
2024-04-06 05:11:09.455 UTC [39086][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:09.455 UTC [39086][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:09.456 UTC [39086][postmaster] LOG:  background worker "logical replication launcher" (PID 39092) exited with exit code 1
2024-04-06 05:11:09.456 UTC [39087][checkpointer] LOG:  shutting down
2024-04-06 05:11:09.456 UTC [39087][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:09.457 UTC [39087][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/4101D28, redo lsn=0/4101D28
2024-04-06 05:11:09.458 UTC [39086][postmaster] LOG:  database system is shut down
2024-04-06 05:11:09.573 UTC [39098][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:09.573 UTC [39098][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:09.574 UTC [39098][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:09.575 UTC [39101][startup] LOG:  database system was shut down at 2024-04-06 05:11:09 UTC
2024-04-06 05:11:09.579 UTC [39098][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:09.675 UTC [39106][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=53006
2024-04-06 05:11:09.677 UTC [39106][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-06 05:11:09.677 UTC [39106][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-04-06 05:11:09.685 UTC [39108][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=53007
2024-04-06 05:11:09.691 UTC [39108][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-04-06 05:11:09.691 UTC [39108][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-04-06 05:11:09.696 UTC [39108][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-04-06 05:11:09.696 UTC [39108][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=53007
2024-04-06 05:11:09.700 UTC [39098][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:09.700 UTC [39098][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:09.701 UTC [39098][postmaster] LOG:  background worker "logical replication launcher" (PID 39104) exited with exit code 1
2024-04-06 05:11:09.701 UTC [39099][checkpointer] LOG:  shutting down
2024-04-06 05:11:09.701 UTC [39099][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:09.702 UTC [39099][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/4101DA0, redo lsn=0/4101DA0
2024-04-06 05:11:09.703 UTC [39098][postmaster] LOG:  database system is shut down
2024-04-06 05:11:09.818 UTC [39110][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:09.818 UTC [39110][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:09.818 UTC [39110][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:09.820 UTC [39113][startup] LOG:  database system was shut down at 2024-04-06 05:11:09 UTC
2024-04-06 05:11:09.823 UTC [39110][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:09.921 UTC [39118][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=53008
2024-04-06 05:11:09.923 UTC [39118][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-06 05:11:09.923 UTC [39118][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-04-06 05:11:09.935 UTC [39120][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=53009
2024-04-06 05:11:09.940 UTC [39120][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-04-06 05:11:09.940 UTC [39120][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-04-06 05:11:09.945 UTC [39120][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-04-06 05:11:09.946 UTC [39120][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=53009
2024-04-06 05:11:09.950 UTC [39110][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:09.950 UTC [39110][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:09.952 UTC [39110][postmaster] LOG:  background worker "logical replication launcher" (PID 39116) exited with exit code 1
2024-04-06 05:11:09.952 UTC [39111][checkpointer] LOG:  shutting down
2024-04-06 05:11:09.952 UTC [39111][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:09.952 UTC [39111][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/4101E18, redo lsn=0/4101E18
2024-04-06 05:11:09.954 UTC [39110][postmaster] LOG:  database system is shut down
2024-04-06 05:11:10.069 UTC [39122][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:10.069 UTC [39122][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:10.069 UTC [39122][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:10.071 UTC [39125][startup] LOG:  database system was shut down at 2024-04-06 05:11:09 UTC
2024-04-06 05:11:10.075 UTC [39122][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:10.171 UTC [39130][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=53010
2024-04-06 05:11:10.173 UTC [39130][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-06 05:11:10.173 UTC [39130][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-04-06 05:11:10.183 UTC [39132][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=53011
2024-04-06 05:11:10.189 UTC [39132][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-04-06 05:11:10.189 UTC [39132][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-04-06 05:11:10.193 UTC [39132][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-04-06 05:11:10.194 UTC [39132][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=53011
2024-04-06 05:11:10.198 UTC [39122][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:10.198 UTC [39122][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:10.200 UTC [39122][postmaster] LOG:  background worker "logical replication launcher" (PID 39128) exited with exit code 1
2024-04-06 05:11:10.200 UTC [39123][checkpointer] LOG:  shutting down
2024-04-06 05:11:10.200 UTC [39123][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:10.201 UTC [39123][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/4101E90, redo lsn=0/4101E90
2024-04-06 05:11:10.203 UTC [39122][postmaster] LOG:  database system is shut down
2024-04-06 05:11:10.317 UTC [39134][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:10.317 UTC [39134][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:10.317 UTC [39134][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:10.319 UTC [39137][startup] LOG:  database system was shut down at 2024-04-06 05:11:10 UTC
2024-04-06 05:11:10.323 UTC [39134][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:10.419 UTC [39142][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=53012
2024-04-06 05:11:10.421 UTC [39142][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-06 05:11:10.421 UTC [39142][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-04-06 05:11:10.430 UTC [39144][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=53013
2024-04-06 05:11:10.435 UTC [39144][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-04-06 05:11:10.435 UTC [39144][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-04-06 05:11:10.439 UTC [39144][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-04-06 05:11:10.440 UTC [39144][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=53013
2024-04-06 05:11:10.444 UTC [39134][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:10.444 UTC [39134][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:10.445 UTC [39134][postmaster] LOG:  background worker "logical replication launcher" (PID 39140) exited with exit code 1
2024-04-06 05:11:10.445 UTC [39135][checkpointer] LOG:  shutting down
2024-04-06 05:11:10.446 UTC [39135][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:10.446 UTC [39135][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/4101F08, redo lsn=0/4101F08
2024-04-06 05:11:10.448 UTC [39134][postmaster] LOG:  database system is shut down
2024-04-06 05:11:10.562 UTC [39146][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:10.562 UTC [39146][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:10.562 UTC [39146][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:10.565 UTC [39149][startup] LOG:  database system was shut down at 2024-04-06 05:11:10 UTC
2024-04-06 05:11:10.568 UTC [39146][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:10.664 UTC [39154][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=53014
2024-04-06 05:11:10.666 UTC [39154][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-06 05:11:10.666 UTC [39154][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-04-06 05:11:10.676 UTC [39156][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=53015
2024-04-06 05:11:10.681 UTC [39156][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-04-06 05:11:10.681 UTC [39156][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-04-06 05:11:10.686 UTC [39156][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-04-06 05:11:10.687 UTC [39156][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=53015
2024-04-06 05:11:10.691 UTC [39146][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:10.691 UTC [39146][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:10.693 UTC [39146][postmaster] LOG:  background worker "logical replication launcher" (PID 39152) exited with exit code 1
2024-04-06 05:11:10.693 UTC [39147][checkpointer] LOG:  shutting down
2024-04-06 05:11:10.693 UTC [39147][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:10.693 UTC [39147][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/4101F80, redo lsn=0/4101F80
2024-04-06 05:11:10.695 UTC [39146][postmaster] LOG:  database system is shut down
2024-04-06 05:11:10.810 UTC [39158][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-04-06 05:11:10.810 UTC [39158][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 57504
2024-04-06 05:11:10.810 UTC [39158][postmaster] LOG:  listening on Unix socket "/tmp/O9pBkcq9_9/.s.PGSQL.57504"
2024-04-06 05:11:10.812 UTC [39161][startup] LOG:  database system was shut down at 2024-04-06 05:11:10 UTC
2024-04-06 05:11:10.816 UTC [39158][postmaster] LOG:  database system is ready to accept connections
2024-04-06 05:11:10.912 UTC [39166][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=53016
2024-04-06 05:11:10.915 UTC [39166][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-06 05:11:10.915 UTC [39166][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-04-06 05:11:10.924 UTC [39168][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=53017
2024-04-06 05:11:10.930 UTC [39168][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-04-06 05:11:10.930 UTC [39168][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-04-06 05:11:10.935 UTC [39168][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-04-06 05:11:10.935 UTC [39168][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=53017
2024-04-06 05:11:10.939 UTC [39158][postmaster] LOG:  received fast shutdown request
2024-04-06 05:11:10.939 UTC [39158][postmaster] LOG:  aborting any active transactions
2024-04-06 05:11:10.941 UTC [39158][postmaster] LOG:  background worker "logical replication launcher" (PID 39164) exited with exit code 1
2024-04-06 05:11:10.941 UTC [39159][checkpointer] LOG:  shutting down
2024-04-06 05:11:10.941 UTC [39159][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-06 05:11:10.941 UTC [39159][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/4101FF8, redo lsn=0/4101FF8
2024-04-06 05:11:10.943 UTC [39158][postmaster] LOG:  database system is shut down
2024-04-06 05:11:11.054 UTC [39170][postmaster] FATAL:  could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory
2024-04-06 05:11:11.054 UTC [39170][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[05:11:11.153](0.217s) Bail out!  pg_ctl restart failed
# No postmaster PID for node "primary"