[05:08:28.360](0.028s) # setting up data directory
# Checking port 64747
# Found port 64747
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=64747 host=/tmp/SRwA3suIyB
Log file: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log
[05:08:28.364](0.004s) # 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 37541
[05:08:28.536](0.172s) 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 37767
[05:08:29.336](0.800s) # testing password-protected keys
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... stopped waiting
pg_ctl: could not start server
Examine the log output.
# pg_ctl restart failed; logfile:
2024-03-28 05:08:28.417 UTC [37541][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:28.417 UTC [37541][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:28.419 UTC [37545][startup] LOG:  database system was shut down at 2024-03-28 05:06:11 UTC
2024-03-28 05:08:28.421 UTC [37541][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:28.528 UTC [37577][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.528 UTC [37577][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.528 UTC [37577][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.533 UTC [37577][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-28 05:08:28.534 UTC [37577][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.557 UTC [37582][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.558 UTC [37582][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.558 UTC [37582][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.558 UTC [37582][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-28 05:08:28.558 UTC [37582][client backend] [001_ssltests.pl][1/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-28 05:08:28.559 UTC [37582][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.590 UTC [37597][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.590 UTC [37597][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.590 UTC [37597][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.591 UTC [37597][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-28 05:08:28.591 UTC [37597][client backend] [001_ssltests.pl][2/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-28 05:08:28.594 UTC [37597][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.637 UTC [37609][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.637 UTC [37609][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.637 UTC [37609][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.639 UTC [37609][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-28 05:08:28.639 UTC [37609][client backend] [001_ssltests.pl][3/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-28 05:08:28.640 UTC [37609][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.689 UTC [37635][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.689 UTC [37635][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.689 UTC [37635][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.690 UTC [37635][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-28 05:08:28.690 UTC [37635][client backend] [001_ssltests.pl][4/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-28 05:08:28.690 UTC [37635][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.735 UTC [37656][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.735 UTC [37656][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.735 UTC [37656][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.736 UTC [37656][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-28 05:08:28.736 UTC [37656][client backend] [001_ssltests.pl][5/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 05:08:28.756 UTC [37656][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.784 UTC [37670][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.785 UTC [37670][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.785 UTC [37670][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.786 UTC [37670][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-28 05:08:28.786 UTC [37670][client backend] [001_ssltests.pl][6/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 05:08:28.815 UTC [37670][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.832 UTC [37681][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.832 UTC [37681][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.832 UTC [37681][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.833 UTC [37681][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-28 05:08:28.833 UTC [37681][client backend] [001_ssltests.pl][7/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 05:08:28.853 UTC [37681][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.874 UTC [37698][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.875 UTC [37698][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.875 UTC [37698][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.875 UTC [37698][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-28 05:08:28.875 UTC [37698][client backend] [001_ssltests.pl][8/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 05:08:28.898 UTC [37698][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.918 UTC [37710][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.918 UTC [37710][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.918 UTC [37710][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.919 UTC [37710][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-28 05:08:28.919 UTC [37710][client backend] [001_ssltests.pl][9/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 05:08:28.951 UTC [37710][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.033 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.984 UTC [37730][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.984 UTC [37730][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.984 UTC [37730][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.985 UTC [37730][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-28 05:08:28.985 UTC [37730][client backend] [001_ssltests.pl][10/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 05:08:29.078 UTC [37730][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.094 user=postgres database=postgres host=[local]
2024-03-28 05:08:29.116 UTC [37541][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:29.116 UTC [37541][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:29.125 UTC [37541][postmaster] LOG:  background worker "logical replication launcher" (PID 37549) exited with exit code 1
2024-03-28 05:08:29.126 UTC [37543][checkpointer] LOG:  shutting down
2024-03-28 05:08:29.127 UTC [37543][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:29.173 UTC [37543][checkpointer] LOG:  checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.046 s, sync=0.001 s, total=0.046 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4100A50, redo lsn=0/4100A50
2024-03-28 05:08:29.177 UTC [37541][postmaster] LOG:  database system is shut down
2024-03-28 05:08:29.236 UTC [37767][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:29.236 UTC [37767][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:29.236 UTC [37767][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:29.238 UTC [37774][startup] LOG:  database system was shut down at 2024-03-28 05:08:29 UTC
2024-03-28 05:08:29.240 UTC [37767][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:29.339 UTC [37767][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:29.339 UTC [37767][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:29.341 UTC [37767][postmaster] LOG:  background worker "logical replication launcher" (PID 37777) exited with exit code 1
2024-03-28 05:08:29.341 UTC [37772][checkpointer] LOG:  shutting down
2024-03-28 05:08:29.342 UTC [37772][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:29.343 UTC [37772][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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4100AC8, redo lsn=0/4100AC8
2024-03-28 05:08:29.351 UTC [37767][postmaster] LOG:  database system is shut down
2024-03-28 05:08:29.451 UTC [37816][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-28 05:08:29.451 UTC [37816][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[05:08:29.543](0.207s) 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 37844
[05:08:29.647](0.104s) ok 3 - restart succeeds with password-protected key file
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... stopped waiting
pg_ctl: could not start server
Examine the log output.
# pg_ctl restart failed; logfile:
2024-03-28 05:08:28.417 UTC [37541][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:28.417 UTC [37541][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:28.419 UTC [37545][startup] LOG:  database system was shut down at 2024-03-28 05:06:11 UTC
2024-03-28 05:08:28.421 UTC [37541][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:28.528 UTC [37577][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.528 UTC [37577][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.528 UTC [37577][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.533 UTC [37577][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-28 05:08:28.534 UTC [37577][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.557 UTC [37582][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.558 UTC [37582][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.558 UTC [37582][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.558 UTC [37582][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-28 05:08:28.558 UTC [37582][client backend] [001_ssltests.pl][1/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-28 05:08:28.559 UTC [37582][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.590 UTC [37597][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.590 UTC [37597][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.590 UTC [37597][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.591 UTC [37597][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-28 05:08:28.591 UTC [37597][client backend] [001_ssltests.pl][2/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-28 05:08:28.594 UTC [37597][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.637 UTC [37609][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.637 UTC [37609][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.637 UTC [37609][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.639 UTC [37609][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-28 05:08:28.639 UTC [37609][client backend] [001_ssltests.pl][3/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-28 05:08:28.640 UTC [37609][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.689 UTC [37635][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.689 UTC [37635][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.689 UTC [37635][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.690 UTC [37635][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-28 05:08:28.690 UTC [37635][client backend] [001_ssltests.pl][4/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-28 05:08:28.690 UTC [37635][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.735 UTC [37656][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.735 UTC [37656][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.735 UTC [37656][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.736 UTC [37656][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-28 05:08:28.736 UTC [37656][client backend] [001_ssltests.pl][5/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 05:08:28.756 UTC [37656][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.784 UTC [37670][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.785 UTC [37670][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.785 UTC [37670][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.786 UTC [37670][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-28 05:08:28.786 UTC [37670][client backend] [001_ssltests.pl][6/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 05:08:28.815 UTC [37670][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.832 UTC [37681][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.832 UTC [37681][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.832 UTC [37681][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.833 UTC [37681][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-28 05:08:28.833 UTC [37681][client backend] [001_ssltests.pl][7/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 05:08:28.853 UTC [37681][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.874 UTC [37698][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.875 UTC [37698][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.875 UTC [37698][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.875 UTC [37698][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-28 05:08:28.875 UTC [37698][client backend] [001_ssltests.pl][8/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 05:08:28.898 UTC [37698][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.918 UTC [37710][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.918 UTC [37710][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.918 UTC [37710][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.919 UTC [37710][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-28 05:08:28.919 UTC [37710][client backend] [001_ssltests.pl][9/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 05:08:28.951 UTC [37710][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.033 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.984 UTC [37730][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.984 UTC [37730][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.984 UTC [37730][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.985 UTC [37730][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-28 05:08:28.985 UTC [37730][client backend] [001_ssltests.pl][10/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 05:08:29.078 UTC [37730][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.094 user=postgres database=postgres host=[local]
2024-03-28 05:08:29.116 UTC [37541][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:29.116 UTC [37541][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:29.125 UTC [37541][postmaster] LOG:  background worker "logical replication launcher" (PID 37549) exited with exit code 1
2024-03-28 05:08:29.126 UTC [37543][checkpointer] LOG:  shutting down
2024-03-28 05:08:29.127 UTC [37543][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:29.173 UTC [37543][checkpointer] LOG:  checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.046 s, sync=0.001 s, total=0.046 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4100A50, redo lsn=0/4100A50
2024-03-28 05:08:29.177 UTC [37541][postmaster] LOG:  database system is shut down
2024-03-28 05:08:29.236 UTC [37767][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:29.236 UTC [37767][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:29.236 UTC [37767][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:29.238 UTC [37774][startup] LOG:  database system was shut down at 2024-03-28 05:08:29 UTC
2024-03-28 05:08:29.240 UTC [37767][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:29.339 UTC [37767][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:29.339 UTC [37767][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:29.341 UTC [37767][postmaster] LOG:  background worker "logical replication launcher" (PID 37777) exited with exit code 1
2024-03-28 05:08:29.341 UTC [37772][checkpointer] LOG:  shutting down
2024-03-28 05:08:29.342 UTC [37772][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:29.343 UTC [37772][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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4100AC8, redo lsn=0/4100AC8
2024-03-28 05:08:29.351 UTC [37767][postmaster] LOG:  database system is shut down
2024-03-28 05:08:29.451 UTC [37816][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-28 05:08:29.451 UTC [37816][postmaster] LOG:  database system is shut down
2024-03-28 05:08:29.559 UTC [37844][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:29.559 UTC [37844][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:29.559 UTC [37844][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:29.561 UTC [37850][startup] LOG:  database system was shut down at 2024-03-28 05:08:29 UTC
2024-03-28 05:08:29.563 UTC [37844][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:29.649 UTC [37844][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:29.649 UTC [37844][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:29.651 UTC [37844][postmaster] LOG:  background worker "logical replication launcher" (PID 37853) exited with exit code 1
2024-03-28 05:08:29.651 UTC [37848][checkpointer] LOG:  shutting down
2024-03-28 05:08:29.651 UTC [37848][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:29.652 UTC [37848][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/4100B40, redo lsn=0/4100B40
2024-03-28 05:08:29.653 UTC [37844][postmaster] LOG:  database system is shut down
2024-03-28 05:08:29.763 UTC [37889][postmaster] FATAL:  could not set SSL protocol version range
2024-03-28 05:08:29.763 UTC [37889][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-28 05:08:29.763 UTC [37889][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[05:08:29.858](0.212s) ok 4 - restart fails with incorrect SSL protocol bounds
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart
pg_ctl: PID file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/postmaster.pid" does not exist
Is server running?
trying to start server anyway
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 37913
[05:08:29.964](0.105s) ok 5 - restart succeeds with correct SSL protocol bounds
[05:08:29.964](0.000s) # running client tests
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 37965
[05:08:30.184](0.220s) ok 6 - server doesn't accept non-SSL connections
[05:08:30.189](0.005s) ok 7 - server doesn't accept non-SSL connections: matches
[05:08:30.207](0.018s) ok 8 - connect without server root cert sslmode=require
[05:08:30.208](0.001s) ok 9 - connect without server root cert sslmode=require: no stderr
[05:08:30.220](0.012s) ok 10 - connect without server root cert sslmode=verify-ca
[05:08:30.221](0.001s) ok 11 - connect without server root cert sslmode=verify-ca: matches
[05:08:30.230](0.010s) ok 12 - connect without server root cert sslmode=verify-full
[05:08:30.231](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches
[05:08:30.243](0.012s) ok 14 - connect with wrong server root cert sslmode=require
[05:08:30.243](0.001s) ok 15 - connect with wrong server root cert sslmode=require: matches
[05:08:30.255](0.012s) ok 16 - connect with wrong server root cert sslmode=verify-ca
[05:08:30.255](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches
[05:08:30.265](0.010s) ok 18 - connect with wrong server root cert sslmode=verify-full
[05:08:30.265](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches
[05:08:30.276](0.010s) ok 20 - connect with server CA cert, without root CA
[05:08:30.276](0.000s) ok 21 - connect with server CA cert, without root CA: matches
[05:08:30.290](0.014s) ok 22 - connect with correct server CA cert file sslmode=require
[05:08:30.290](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr
[05:08:30.305](0.015s) ok 24 - connect with correct server CA cert file sslmode=verify-ca
[05:08:30.305](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr
[05:08:30.318](0.012s) ok 26 - connect with correct server CA cert file sslmode=verify-full
[05:08:30.318](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr
[05:08:30.331](0.013s) ok 28 - cert root file that contains two certificates, order 1
[05:08:30.331](0.001s) ok 29 - cert root file that contains two certificates, order 1: no stderr
[05:08:30.344](0.013s) ok 30 - cert root file that contains two certificates, order 2
[05:08:30.345](0.001s) ok 31 - cert root file that contains two certificates, order 2: no stderr
[05:08:30.359](0.015s) ok 32 - connect with sslcertmode=disable
[05:08:30.360](0.001s) ok 33 - connect with sslcertmode=disable: no stderr
[05:08:30.379](0.019s) ok 34 - connect with sslcertmode=allow
[05:08:30.379](0.000s) ok 35 - connect with sslcertmode=allow: no stderr
[05:08:30.400](0.021s) ok 36 - connect with sslcertmode=require fails without a client certificate
[05:08:30.400](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches
[05:08:30.413](0.013s) ok 38 - sslcrl option with invalid file name
[05:08:30.413](0.000s) ok 39 - sslcrl option with invalid file name: no stderr
[05:08:30.425](0.011s) ok 40 - CRL belonging to a different CA
[05:08:30.425](0.000s) ok 41 - CRL belonging to a different CA: matches
[05:08:30.436](0.011s) ok 42 - directory CRL belonging to a different CA
[05:08:30.436](0.000s) ok 43 - directory CRL belonging to a different CA: matches
[05:08:30.448](0.012s) ok 44 - CRL with a non-revoked cert
[05:08:30.448](0.000s) ok 45 - CRL with a non-revoked cert: no stderr
[05:08:30.460](0.012s) ok 46 - directory CRL with a non-revoked cert
[05:08:30.460](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr
[05:08:30.472](0.012s) ok 48 - mismatch between host name and server certificate sslmode=require
[05:08:30.473](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr
[05:08:30.485](0.012s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca
[05:08:30.485](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr
[05:08:30.499](0.014s) ok 52 - mismatch between host name and server certificate sslmode=verify-full
[05:08:30.499](0.001s) 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 38140
[05:08:30.726](0.226s) ok 54 - IP address in the Common Name
[05:08:30.726](0.000s) ok 55 - IP address in the Common Name: no stderr
[05:08:30.735](0.009s) ok 56 - mismatch between host name and server certificate IP address
[05:08:30.736](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 38178
[05:08:30.958](0.222s) ok 58 - IP address in a dNSName
[05:08:30.958](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 38200
[05:08:31.188](0.230s) ok 60 - host name matching with X.509 Subject Alternative Names 1
[05:08:31.189](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr
[05:08:31.201](0.012s) ok 62 - host name matching with X.509 Subject Alternative Names 2
[05:08:31.201](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr
[05:08:31.214](0.013s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard
[05:08:31.214](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr
[05:08:31.226](0.012s) ok 66 - host name not matching with X.509 Subject Alternative Names
[05:08:31.227](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches
[05:08:31.237](0.010s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard
[05:08:31.237](0.000s) ok 69 - host name not matching with X.509 Subject Alternative Names wildcard: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 38238
[05:08:31.469](0.232s) ok 70 - host name matching with a single X.509 Subject Alternative Name
[05:08:31.470](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr
[05:08:31.480](0.011s) ok 72 - host name not matching with a single X.509 Subject Alternative Name
[05:08:31.481](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches
[05:08:31.490](0.010s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard
[05:08:31.491](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 38265
[05:08:31.719](0.228s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1)
[05:08:31.719](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr
[05:08:31.731](0.012s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1)
[05:08:31.731](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr
[05:08:31.742](0.010s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1)
[05:08:31.742](0.001s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches
[05:08:31.754](0.012s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2)
[05:08:31.754](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr
[05:08:31.766](0.012s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2)
[05:08:31.766](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr
[05:08:31.778](0.012s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2)
[05:08:31.778](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr
[05:08:31.788](0.010s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2)
[05:08:31.788](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches
[05:08:31.798](0.010s) ok 90 - IPv6 host with CIDR mask does not match
[05:08:31.798](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 38310
[05:08:32.030](0.232s) ok 92 - certificate with both a CN and SANs 1
[05:08:32.030](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr
[05:08:32.043](0.012s) ok 94 - certificate with both a CN and SANs 2
[05:08:32.043](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr
[05:08:32.053](0.010s) ok 96 - certificate with both a CN and SANs ignores CN
[05:08:32.053](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 38332
[05:08:32.281](0.227s) ok 98 - certificate with both a CN and IP SANs matches CN
[05:08:32.281](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr
[05:08:32.294](0.013s) ok 100 - certificate with both a CN and IP SANs matches SAN 1
[05:08:32.294](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr
[05:08:32.306](0.012s) ok 102 - certificate with both a CN and IP SANs matches SAN 2
[05:08:32.306](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 38348
[05:08:32.539](0.233s) ok 104 - certificate with both an IP CN and IP SANs 1
[05:08:32.539](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr
[05:08:32.551](0.012s) ok 106 - certificate with both an IP CN and IP SANs 2
[05:08:32.551](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr
[05:08:32.563](0.012s) ok 108 - certificate with both an IP CN and IP SANs ignores CN
[05:08:32.563](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 38366
[05:08:32.795](0.231s) ok 110 - certificate with both an IP CN and DNS SANs matches CN
[05:08:32.795](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr
[05:08:32.808](0.013s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1
[05:08:32.808](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr
[05:08:32.820](0.012s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2
[05:08:32.820](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 38381
[05:08:33.054](0.233s) ok 116 - server certificate without CN or SANs sslmode=verify-ca
[05:08:33.054](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr
[05:08:33.064](0.010s) ok 118 - server certificate without CN or SANs sslmode=verify-full
[05:08:33.064](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 38393
[05:08:33.292](0.228s) ok 120 - sslrootcert=system does not connect with private CA
[05:08:33.292](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches
[05:08:33.298](0.005s) ok 122 - sslrootcert=system only accepts sslmode=verify-full
[05:08:33.298](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches
[05:08:33.315](0.017s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE
[05:08:33.315](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr
[05:08:33.332](0.016s) ok 126 - sslrootcert=system defaults to sslmode=verify-full
[05:08:33.332](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 38408
[05:08:33.571](0.239s) ok 128 - connects without client-side CRL
[05:08:33.571](0.000s) ok 129 - connects without client-side CRL: no stderr
[05:08:33.587](0.017s) ok 130 - does not connect with client-side CRL file
[05:08:33.588](0.000s) ok 131 - does not connect with client-side CRL file: matches
[05:08:33.603](0.015s) ok 132 - does not connect with client-side CRL directory
[05:08:33.603](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:08:33.621](0.018s) ok 134 - pg_stat_ssl view without client certificate: exit code 0
[05:08:33.621](0.001s) ok 135 - pg_stat_ssl view without client certificate: no stderr
[05:08:33.622](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches
[05:08:33.640](0.019s) ok 137 - connection success with correct range of TLS protocol versions
[05:08:33.641](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr
[05:08:33.650](0.009s) ok 139 - connection failure with incorrect range of TLS protocol versions
[05:08:33.650](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches
[05:08:33.658](0.008s) ok 141 - connection failure with an incorrect SSL protocol minimum bound
[05:08:33.659](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches
[05:08:33.667](0.009s) ok 143 - connection failure with an incorrect SSL protocol maximum bound
[05:08:33.668](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches
[05:08:33.668](0.000s) # running server tests
[05:08:33.685](0.017s) ok 145 - certificate authorization fails without client cert
[05:08:33.685](0.000s) ok 146 - certificate authorization fails without client cert: matches
[05:08:33.709](0.024s) ok 147 - certificate authorization succeeds with correct client cert in PEM format
[05:08:33.709](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr
[05:08:33.730](0.021s) ok 149 - certificate authorization succeeds with correct client cert in DER format
[05:08:33.731](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr
[05:08:33.751](0.020s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format
[05:08:33.751](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr
[05:08:33.769](0.018s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format
[05:08:33.769](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr
[05:08:33.784](0.014s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require
[05:08:33.784](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr
[05:08:33.797](0.013s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow
[05:08:33.797](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr
[05:08:33.808](0.011s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable
[05:08:33.808](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches
[05:08:33.819](0.011s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format
[05:08:33.819](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches
[05:08:33.840](0.021s) ok 163 - certificate authorization succeeds with DN mapping
[05:08:33.840](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr
[05:08:33.841](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches
[05:08:33.861](0.020s) ok 166 - certificate authorization succeeds with DN regex mapping
[05:08:33.861](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr
[05:08:33.883](0.022s) ok 168 - certificate authorization succeeds with CN mapping
[05:08:33.883](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr
[05:08:33.883](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches
[05:08:33.884](0.000s) not ok 171 # TODO & SKIP Need Pty support
[05:08:33.884](0.000s) not ok 172 # TODO & SKIP Need Pty support
[05:08:33.884](0.000s) not ok 173 # TODO & SKIP Need Pty support
[05:08:33.884](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_xFTm/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
[05:08:33.918](0.034s) ok 175 - pg_stat_ssl with client certificate: exit code 0
[05:08:33.919](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr
[05:08:33.919](0.000s) ok 177 - pg_stat_ssl with client certificate: matches
[05:08:33.929](0.010s) ok 178 - certificate authorization fails because of file permissions
[05:08:33.929](0.000s) ok 179 - certificate authorization fails because of file permissions: matches
[05:08:33.944](0.014s) ok 180 - certificate authorization fails with client cert belonging to another user
[05:08:33.944](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches
[05:08:33.959](0.015s) ok 182 - certificate authorization fails with revoked client cert
[05:08:33.960](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches
[05:08:33.960](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match
[05:08:33.980](0.020s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name
[05:08:33.981](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr
[05:08:33.981](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches
[05:08:33.996](0.015s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name
[05:08:33.996](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches
[05:08:33.996](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match
[05:08:34.011](0.015s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name
[05:08:34.011](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr
[05:08:34.012](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 38468
[05:08:34.250](0.239s) ok 194 - intermediate client certificate is provided by client
[05:08:34.251](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr
[05:08:34.264](0.014s) ok 196 - intermediate client certificate is missing
[05:08:34.264](0.000s) ok 197 - intermediate client certificate is missing: matches
[05:08:34.278](0.014s) ok 198 - logged client certificate Subjects are truncated if they're too long
[05:08:34.279](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 38482
[05:08:34.509](0.230s) ok 200 - intermediate client certificate is untrusted
[05:08:34.509](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 38492
[05:08:34.740](0.231s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory
[05:08:34.740](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches
[05:08:34.756](0.016s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory
[05:08:34.757](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 38504
[05:08:34.984](0.227s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1
[05:08:34.984](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:08:34.984](0.000s) #          got: '2'
#     expected: '0'
[05:08:34.985](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[05:08:34.985](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:08:34.985](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 64747 failed: SSL SYSCALL error: EOF detected'
#     expected: ''
[05:08:35.005](0.020s) ok 208 - connect without requesting ocsp response when sslocspstapling=0
[05:08:35.005](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 38516
[05:08:35.235](0.230s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1
[05:08:35.255](0.021s) ok 211 - connect without requesting ocsp response when sslocspstapling=0
[05:08:35.256](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 38528
[05:08:35.483](0.227s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1
[05:08:35.501](0.018s) ok 214 - connect without requesting ocsp response when sslocspstapling=0
[05:08:35.501](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 38540
[05:08:35.727](0.226s) ok 216 - failed with an expired ocsp response when sslocspstapling=1
[05:08:35.743](0.017s) ok 217 - connect without requesting ocsp response when sslocspstapling=0
[05:08:35.744](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 38552
[05:08:35.971](0.228s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1
[05:08:35.972](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:08:35.972](0.000s) #          got: '2'
#     expected: '0'
[05:08:35.972](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[05:08:35.972](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:08:35.972](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 64747 failed: SSL SYSCALL error: EOF detected'
#     expected: ''
[05:08:35.988](0.016s) ok 221 - connect without requesting ocsp response when sslocspstapling=0
[05:08:35.988](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 38564
[05:08:36.214](0.226s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1
[05:08:36.230](0.016s) ok 224 - connect without requesting ocsp response when sslocspstapling=0
[05:08:36.230](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 38576
[05:08:36.459](0.229s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1
[05:08:36.477](0.018s) ok 227 - connect without requesting ocsp response when sslocspstapling=0
[05:08:36.477](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 38588
[05:08:36.706](0.228s) ok 229 - failed with an expired ocsp response when sslocspstapling=1
[05:08:36.728](0.022s) ok 230 - connect without requesting ocsp response when sslocspstapling=0
[05:08:36.728](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 38600
[05:08:36.956](0.228s) ok 232 - failed with an expired ocsp response when sslocspstapling=1
[05:08:36.977](0.021s) ok 233 - connect without requesting ocsp response when sslocspstapling=0
[05:08:36.977](0.000s) ok 234 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... stopped waiting
pg_ctl: could not start server
Examine the log output.
# pg_ctl restart failed; logfile:
2024-03-28 05:08:28.417 UTC [37541][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:28.417 UTC [37541][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:28.419 UTC [37545][startup] LOG:  database system was shut down at 2024-03-28 05:06:11 UTC
2024-03-28 05:08:28.421 UTC [37541][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:28.528 UTC [37577][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.528 UTC [37577][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.528 UTC [37577][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.533 UTC [37577][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-28 05:08:28.534 UTC [37577][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.557 UTC [37582][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.558 UTC [37582][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.558 UTC [37582][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.558 UTC [37582][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-28 05:08:28.558 UTC [37582][client backend] [001_ssltests.pl][1/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-28 05:08:28.559 UTC [37582][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.590 UTC [37597][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.590 UTC [37597][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.590 UTC [37597][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.591 UTC [37597][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-28 05:08:28.591 UTC [37597][client backend] [001_ssltests.pl][2/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-28 05:08:28.594 UTC [37597][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.637 UTC [37609][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.637 UTC [37609][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.637 UTC [37609][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.639 UTC [37609][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-28 05:08:28.639 UTC [37609][client backend] [001_ssltests.pl][3/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-28 05:08:28.640 UTC [37609][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.689 UTC [37635][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.689 UTC [37635][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.689 UTC [37635][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.690 UTC [37635][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-28 05:08:28.690 UTC [37635][client backend] [001_ssltests.pl][4/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-28 05:08:28.690 UTC [37635][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.735 UTC [37656][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.735 UTC [37656][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.735 UTC [37656][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.736 UTC [37656][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-28 05:08:28.736 UTC [37656][client backend] [001_ssltests.pl][5/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 05:08:28.756 UTC [37656][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.784 UTC [37670][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.785 UTC [37670][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.785 UTC [37670][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.786 UTC [37670][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-28 05:08:28.786 UTC [37670][client backend] [001_ssltests.pl][6/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 05:08:28.815 UTC [37670][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.832 UTC [37681][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.832 UTC [37681][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.832 UTC [37681][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.833 UTC [37681][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-28 05:08:28.833 UTC [37681][client backend] [001_ssltests.pl][7/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 05:08:28.853 UTC [37681][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.874 UTC [37698][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.875 UTC [37698][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.875 UTC [37698][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.875 UTC [37698][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-28 05:08:28.875 UTC [37698][client backend] [001_ssltests.pl][8/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 05:08:28.898 UTC [37698][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.918 UTC [37710][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.918 UTC [37710][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.918 UTC [37710][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.919 UTC [37710][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-28 05:08:28.919 UTC [37710][client backend] [001_ssltests.pl][9/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 05:08:28.951 UTC [37710][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.033 user=postgres database=postgres host=[local]
2024-03-28 05:08:28.984 UTC [37730][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 05:08:28.984 UTC [37730][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 05:08:28.984 UTC [37730][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 05:08:28.985 UTC [37730][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-28 05:08:28.985 UTC [37730][client backend] [001_ssltests.pl][10/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 05:08:29.078 UTC [37730][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.094 user=postgres database=postgres host=[local]
2024-03-28 05:08:29.116 UTC [37541][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:29.116 UTC [37541][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:29.125 UTC [37541][postmaster] LOG:  background worker "logical replication launcher" (PID 37549) exited with exit code 1
2024-03-28 05:08:29.126 UTC [37543][checkpointer] LOG:  shutting down
2024-03-28 05:08:29.127 UTC [37543][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:29.173 UTC [37543][checkpointer] LOG:  checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.046 s, sync=0.001 s, total=0.046 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4100A50, redo lsn=0/4100A50
2024-03-28 05:08:29.177 UTC [37541][postmaster] LOG:  database system is shut down
2024-03-28 05:08:29.236 UTC [37767][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:29.236 UTC [37767][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:29.236 UTC [37767][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:29.238 UTC [37774][startup] LOG:  database system was shut down at 2024-03-28 05:08:29 UTC
2024-03-28 05:08:29.240 UTC [37767][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:29.339 UTC [37767][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:29.339 UTC [37767][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:29.341 UTC [37767][postmaster] LOG:  background worker "logical replication launcher" (PID 37777) exited with exit code 1
2024-03-28 05:08:29.341 UTC [37772][checkpointer] LOG:  shutting down
2024-03-28 05:08:29.342 UTC [37772][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:29.343 UTC [37772][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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4100AC8, redo lsn=0/4100AC8
2024-03-28 05:08:29.351 UTC [37767][postmaster] LOG:  database system is shut down
2024-03-28 05:08:29.451 UTC [37816][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-28 05:08:29.451 UTC [37816][postmaster] LOG:  database system is shut down
2024-03-28 05:08:29.559 UTC [37844][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:29.559 UTC [37844][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:29.559 UTC [37844][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:29.561 UTC [37850][startup] LOG:  database system was shut down at 2024-03-28 05:08:29 UTC
2024-03-28 05:08:29.563 UTC [37844][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:29.649 UTC [37844][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:29.649 UTC [37844][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:29.651 UTC [37844][postmaster] LOG:  background worker "logical replication launcher" (PID 37853) exited with exit code 1
2024-03-28 05:08:29.651 UTC [37848][checkpointer] LOG:  shutting down
2024-03-28 05:08:29.651 UTC [37848][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:29.652 UTC [37848][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/4100B40, redo lsn=0/4100B40
2024-03-28 05:08:29.653 UTC [37844][postmaster] LOG:  database system is shut down
2024-03-28 05:08:29.763 UTC [37889][postmaster] FATAL:  could not set SSL protocol version range
2024-03-28 05:08:29.763 UTC [37889][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-28 05:08:29.763 UTC [37889][postmaster] LOG:  database system is shut down
2024-03-28 05:08:29.877 UTC [37913][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:29.877 UTC [37913][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:29.877 UTC [37913][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:29.878 UTC [37920][startup] LOG:  database system was shut down at 2024-03-28 05:08:29 UTC
2024-03-28 05:08:29.880 UTC [37913][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:29.966 UTC [37913][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:29.966 UTC [37913][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:29.968 UTC [37913][postmaster] LOG:  background worker "logical replication launcher" (PID 37923) exited with exit code 1
2024-03-28 05:08:29.968 UTC [37918][checkpointer] LOG:  shutting down
2024-03-28 05:08:29.968 UTC [37918][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:29.968 UTC [37918][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4100BB8, redo lsn=0/4100BB8
2024-03-28 05:08:29.969 UTC [37913][postmaster] LOG:  database system is shut down
2024-03-28 05:08:30.084 UTC [37965][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:30.084 UTC [37965][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:30.084 UTC [37965][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:30.086 UTC [37969][startup] LOG:  database system was shut down at 2024-03-28 05:08:29 UTC
2024-03-28 05:08:30.088 UTC [37965][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:30.181 UTC [38003][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24574
2024-03-28 05:08:30.181 UTC [38003][client backend] [[unknown]][0/1:0] FATAL:  no pg_hba.conf entry for host "127.0.0.1", user "ssltestuser", database "trustdb", no encryption
2024-03-28 05:08:30.181 UTC [38003][client backend] [[unknown]][0/1:0] DETAIL:  Client IP address resolved to "localhost", forward lookup not checked.
2024-03-28 05:08:30.197 UTC [38007][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24575
2024-03-28 05:08:30.201 UTC [38007][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:30.201 UTC [38007][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:30.205 UTC [38007][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid sslmode=require$$
2024-03-28 05:08:30.207 UTC [38007][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=24575
2024-03-28 05:08:30.214 UTC [38012][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24577
2024-03-28 05:08:30.219 UTC [38012][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-28 05:08:30.228 UTC [38018][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24580
2024-03-28 05:08:30.229 UTC [38018][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-28 05:08:30.237 UTC [38022][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24581
2024-03-28 05:08:30.246 UTC [38022][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-28 05:08:30.250 UTC [38033][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24583
2024-03-28 05:08:30.254 UTC [38033][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-28 05:08:30.260 UTC [38043][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24585
2024-03-28 05:08:30.264 UTC [38043][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-28 05:08:30.271 UTC [38046][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24586
2024-03-28 05:08:30.275 UTC [38046][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-28 05:08:30.282 UTC [38050][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24588
2024-03-28 05:08:30.287 UTC [38050][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:30.287 UTC [38050][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:30.288 UTC [38050][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require$$
2024-03-28 05:08:30.289 UTC [38050][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=24588
2024-03-28 05:08:30.298 UTC [38055][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24590
2024-03-28 05:08:30.303 UTC [38055][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:30.303 UTC [38055][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:30.303 UTC [38055][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$
2024-03-28 05:08:30.305 UTC [38055][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=24590
2024-03-28 05:08:30.311 UTC [38059][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24591
2024-03-28 05:08:30.315 UTC [38059][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:30.315 UTC [38059][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:30.316 UTC [38059][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-full$$
2024-03-28 05:08:30.317 UTC [38059][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=24591
2024-03-28 05:08:30.324 UTC [38063][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24593
2024-03-28 05:08:30.328 UTC [38063][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:30.328 UTC [38063][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:30.329 UTC [38063][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-1.crt sslmode=verify-ca$$
2024-03-28 05:08:30.330 UTC [38063][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=24593
2024-03-28 05:08:30.338 UTC [38068][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24595
2024-03-28 05:08:30.342 UTC [38068][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:30.342 UTC [38068][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:30.343 UTC [38068][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-2.crt sslmode=verify-ca$$
2024-03-28 05:08:30.344 UTC [38068][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=24595
2024-03-28 05:08:30.351 UTC [38073][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24596
2024-03-28 05:08:30.356 UTC [38073][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:30.356 UTC [38073][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:30.357 UTC [38073][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=disable$$
2024-03-28 05:08:30.359 UTC [38073][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=24596
2024-03-28 05:08:30.367 UTC [38078][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24598
2024-03-28 05:08:30.375 UTC [38078][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:30.375 UTC [38078][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:30.376 UTC [38078][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=allow$$
2024-03-28 05:08:30.378 UTC [38078][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=24598
2024-03-28 05:08:30.387 UTC [38085][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24600
2024-03-28 05:08:30.393 UTC [38085][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:30.393 UTC [38085][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:30.400 UTC [38085][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=24600
2024-03-28 05:08:30.406 UTC [38090][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24603
2024-03-28 05:08:30.411 UTC [38090][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:30.411 UTC [38090][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:30.412 UTC [38090][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=invalid$$
2024-03-28 05:08:30.412 UTC [38090][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=24603
2024-03-28 05:08:30.419 UTC [38094][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24605
2024-03-28 05:08:30.423 UTC [38094][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-28 05:08:30.431 UTC [38097][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24606
2024-03-28 05:08:30.435 UTC [38097][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-28 05:08:30.441 UTC [38100][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24608
2024-03-28 05:08:30.446 UTC [38100][client backend] [[unknown]][11/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:30.446 UTC [38100][client backend] [[unknown]][11/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:30.446 UTC [38100][client backend] [001_ssltests.pl][11/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=ssl/root+server.crl$$
2024-03-28 05:08:30.447 UTC [38100][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=24608
2024-03-28 05:08:30.453 UTC [38104][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24610
2024-03-28 05:08:30.458 UTC [38104][client backend] [[unknown]][12/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:30.458 UTC [38104][client backend] [[unknown]][12/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:30.458 UTC [38104][client backend] [001_ssltests.pl][12/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrldir=ssl/root+server-crldir$$
2024-03-28 05:08:30.459 UTC [38104][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=24610
2024-03-28 05:08:30.466 UTC [38110][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24612
2024-03-28 05:08:30.470 UTC [38110][client backend] [[unknown]][13/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:30.470 UTC [38110][client backend] [[unknown]][13/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:30.471 UTC [38110][client backend] [001_ssltests.pl][13/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=require host=wronghost.test$$
2024-03-28 05:08:30.471 UTC [38110][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=24612
2024-03-28 05:08:30.478 UTC [38114][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24614
2024-03-28 05:08:30.482 UTC [38114][client backend] [[unknown]][14/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:30.482 UTC [38114][client backend] [[unknown]][14/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:30.483 UTC [38114][client backend] [001_ssltests.pl][14/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=wronghost.test$$
2024-03-28 05:08:30.486 UTC [38114][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=24614
2024-03-28 05:08:30.492 UTC [38121][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24616
2024-03-28 05:08:30.502 UTC [37965][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:30.502 UTC [37965][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:30.505 UTC [37965][postmaster] LOG:  background worker "logical replication launcher" (PID 37972) exited with exit code 1
2024-03-28 05:08:30.505 UTC [37967][checkpointer] LOG:  shutting down
2024-03-28 05:08:30.505 UTC [37967][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:30.506 UTC [37967][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/4100C30, redo lsn=0/4100C30
2024-03-28 05:08:30.507 UTC [37965][postmaster] LOG:  database system is shut down
2024-03-28 05:08:30.619 UTC [38140][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:30.619 UTC [38140][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:30.619 UTC [38140][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:30.621 UTC [38145][startup] LOG:  database system was shut down at 2024-03-28 05:08:30 UTC
2024-03-28 05:08:30.624 UTC [38140][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:30.716 UTC [38162][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24621
2024-03-28 05:08:30.720 UTC [38162][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:30.720 UTC [38162][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:30.724 UTC [38162][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-28 05:08:30.725 UTC [38162][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=24621
2024-03-28 05:08:30.731 UTC [38164][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24622
2024-03-28 05:08:30.737 UTC [38140][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:30.737 UTC [38140][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:30.738 UTC [38140][postmaster] LOG:  background worker "logical replication launcher" (PID 38149) exited with exit code 1
2024-03-28 05:08:30.738 UTC [38143][checkpointer] LOG:  shutting down
2024-03-28 05:08:30.738 UTC [38143][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:30.739 UTC [38143][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/4100CA8, redo lsn=0/4100CA8
2024-03-28 05:08:30.740 UTC [38140][postmaster] LOG:  database system is shut down
2024-03-28 05:08:30.849 UTC [38178][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:30.849 UTC [38178][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:30.849 UTC [38178][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:30.851 UTC [38183][startup] LOG:  database system was shut down at 2024-03-28 05:08:30 UTC
2024-03-28 05:08:30.853 UTC [38178][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:30.947 UTC [38189][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24625
2024-03-28 05:08:30.951 UTC [38189][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:30.951 UTC [38189][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:30.956 UTC [38189][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-28 05:08:30.956 UTC [38189][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=24625
2024-03-28 05:08:30.960 UTC [38178][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:30.961 UTC [38178][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:30.964 UTC [38178][postmaster] LOG:  background worker "logical replication launcher" (PID 38186) exited with exit code 1
2024-03-28 05:08:30.964 UTC [38181][checkpointer] LOG:  shutting down
2024-03-28 05:08:30.964 UTC [38181][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:30.964 UTC [38181][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/4100D20, redo lsn=0/4100D20
2024-03-28 05:08:30.966 UTC [38178][postmaster] LOG:  database system is shut down
2024-03-28 05:08:31.076 UTC [38200][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:31.076 UTC [38200][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:31.076 UTC [38200][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:31.078 UTC [38206][startup] LOG:  database system was shut down at 2024-03-28 05:08:30 UTC
2024-03-28 05:08:31.080 UTC [38200][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:31.177 UTC [38214][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24627
2024-03-28 05:08:31.182 UTC [38214][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:31.182 UTC [38214][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:31.186 UTC [38214][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$
2024-03-28 05:08:31.187 UTC [38214][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=24627
2024-03-28 05:08:31.194 UTC [38222][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24628
2024-03-28 05:08:31.198 UTC [38222][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:31.198 UTC [38222][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:31.199 UTC [38222][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$
2024-03-28 05:08:31.200 UTC [38222][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=24628
2024-03-28 05:08:31.207 UTC [38224][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24629
2024-03-28 05:08:31.212 UTC [38224][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:31.212 UTC [38224][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:31.212 UTC [38224][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=foo.wildcard.pg-ssltest.test$$
2024-03-28 05:08:31.213 UTC [38224][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=24629
2024-03-28 05:08:31.221 UTC [38226][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24630
2024-03-28 05:08:31.232 UTC [38228][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24631
2024-03-28 05:08:31.239 UTC [38200][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:31.239 UTC [38200][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:31.240 UTC [38200][postmaster] LOG:  background worker "logical replication launcher" (PID 38209) exited with exit code 1
2024-03-28 05:08:31.240 UTC [38204][checkpointer] LOG:  shutting down
2024-03-28 05:08:31.240 UTC [38204][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:31.241 UTC [38204][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/4100D98, redo lsn=0/4100D98
2024-03-28 05:08:31.242 UTC [38200][postmaster] LOG:  database system is shut down
2024-03-28 05:08:31.357 UTC [38238][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:31.357 UTC [38238][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:31.357 UTC [38238][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:31.358 UTC [38241][startup] LOG:  database system was shut down at 2024-03-28 05:08:31 UTC
2024-03-28 05:08:31.361 UTC [38238][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:31.459 UTC [38253][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24633
2024-03-28 05:08:31.463 UTC [38253][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:31.463 UTC [38253][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:31.468 UTC [38253][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=single.alt-name.pg-ssltest.test$$
2024-03-28 05:08:31.468 UTC [38253][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=24633
2024-03-28 05:08:31.475 UTC [38255][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24634
2024-03-28 05:08:31.485 UTC [38258][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24635
2024-03-28 05:08:31.495 UTC [38238][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:31.495 UTC [38238][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:31.496 UTC [38238][postmaster] LOG:  background worker "logical replication launcher" (PID 38244) exited with exit code 1
2024-03-28 05:08:31.497 UTC [38239][checkpointer] LOG:  shutting down
2024-03-28 05:08:31.497 UTC [38239][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:31.497 UTC [38239][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4100E10, redo lsn=0/4100E10
2024-03-28 05:08:31.498 UTC [38238][postmaster] LOG:  database system is shut down
2024-03-28 05:08:31.613 UTC [38265][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:31.613 UTC [38265][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:31.613 UTC [38265][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:31.615 UTC [38268][startup] LOG:  database system was shut down at 2024-03-28 05:08:31 UTC
2024-03-28 05:08:31.617 UTC [38265][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:31.709 UTC [38282][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24637
2024-03-28 05:08:31.713 UTC [38282][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:31.713 UTC [38282][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:31.718 UTC [38282][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-28 05:08:31.718 UTC [38282][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=24637
2024-03-28 05:08:31.725 UTC [38284][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24638
2024-03-28 05:08:31.729 UTC [38284][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:31.729 UTC [38284][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:31.730 UTC [38284][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.000.002.001$$
2024-03-28 05:08:31.730 UTC [38284][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=24638
2024-03-28 05:08:31.737 UTC [38287][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24639
2024-03-28 05:08:31.747 UTC [38290][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24641
2024-03-28 05:08:31.752 UTC [38290][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:31.752 UTC [38290][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:31.753 UTC [38290][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:DB8::1$$
2024-03-28 05:08:31.753 UTC [38290][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=24641
2024-03-28 05:08:31.759 UTC [38293][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24642
2024-03-28 05:08:31.764 UTC [38293][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:31.764 UTC [38293][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:31.765 UTC [38293][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8:0:0:0:0:0:1$$
2024-03-28 05:08:31.765 UTC [38293][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=24642
2024-03-28 05:08:31.771 UTC [38295][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24643
2024-03-28 05:08:31.776 UTC [38295][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:31.776 UTC [38295][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:31.777 UTC [38295][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::0.0.0.1$$
2024-03-28 05:08:31.777 UTC [38295][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=24643
2024-03-28 05:08:31.783 UTC [38297][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24644
2024-03-28 05:08:31.793 UTC [38299][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24645
2024-03-28 05:08:31.800 UTC [38265][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:31.800 UTC [38265][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:31.801 UTC [38265][postmaster] LOG:  background worker "logical replication launcher" (PID 38271) exited with exit code 1
2024-03-28 05:08:31.801 UTC [38266][checkpointer] LOG:  shutting down
2024-03-28 05:08:31.801 UTC [38266][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:31.802 UTC [38266][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/4100E88, redo lsn=0/4100E88
2024-03-28 05:08:31.803 UTC [38265][postmaster] LOG:  database system is shut down
2024-03-28 05:08:31.917 UTC [38310][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:31.917 UTC [38310][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:31.917 UTC [38310][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:31.919 UTC [38313][startup] LOG:  database system was shut down at 2024-03-28 05:08:31 UTC
2024-03-28 05:08:31.921 UTC [38310][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:32.019 UTC [38323][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24647
2024-03-28 05:08:32.024 UTC [38323][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:32.024 UTC [38323][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:32.028 UTC [38323][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$
2024-03-28 05:08:32.029 UTC [38323][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=24647
2024-03-28 05:08:32.036 UTC [38325][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24648
2024-03-28 05:08:32.040 UTC [38325][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:32.040 UTC [38325][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:32.041 UTC [38325][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$
2024-03-28 05:08:32.041 UTC [38325][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=24648
2024-03-28 05:08:32.048 UTC [38327][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24649
2024-03-28 05:08:32.058 UTC [38310][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:32.058 UTC [38310][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:32.059 UTC [38310][postmaster] LOG:  background worker "logical replication launcher" (PID 38316) exited with exit code 1
2024-03-28 05:08:32.059 UTC [38311][checkpointer] LOG:  shutting down
2024-03-28 05:08:32.059 UTC [38311][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:32.060 UTC [38311][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/4100F00, redo lsn=0/4100F00
2024-03-28 05:08:32.061 UTC [38310][postmaster] LOG:  database system is shut down
2024-03-28 05:08:32.169 UTC [38332][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:32.169 UTC [38332][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:32.169 UTC [38332][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:32.171 UTC [38335][startup] LOG:  database system was shut down at 2024-03-28 05:08:32 UTC
2024-03-28 05:08:32.173 UTC [38332][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:32.270 UTC [38340][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24650
2024-03-28 05:08:32.275 UTC [38340][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:32.275 UTC [38340][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:32.279 UTC [38340][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$
2024-03-28 05:08:32.280 UTC [38340][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=24650
2024-03-28 05:08:32.287 UTC [38342][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24651
2024-03-28 05:08:32.292 UTC [38342][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:32.292 UTC [38342][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:32.292 UTC [38342][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-28 05:08:32.293 UTC [38342][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=24651
2024-03-28 05:08:32.299 UTC [38346][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24652
2024-03-28 05:08:32.304 UTC [38346][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:32.304 UTC [38346][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:32.304 UTC [38346][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$
2024-03-28 05:08:32.305 UTC [38346][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=24652
2024-03-28 05:08:32.307 UTC [38332][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:32.307 UTC [38332][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:32.309 UTC [38332][postmaster] LOG:  background worker "logical replication launcher" (PID 38338) exited with exit code 1
2024-03-28 05:08:32.309 UTC [38333][checkpointer] LOG:  shutting down
2024-03-28 05:08:32.309 UTC [38333][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:32.309 UTC [38333][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/4100F78, redo lsn=0/4100F78
2024-03-28 05:08:32.310 UTC [38332][postmaster] LOG:  database system is shut down
2024-03-28 05:08:32.426 UTC [38348][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:32.426 UTC [38348][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:32.426 UTC [38348][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:32.428 UTC [38351][startup] LOG:  database system was shut down at 2024-03-28 05:08:32 UTC
2024-03-28 05:08:32.431 UTC [38348][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:32.527 UTC [38358][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24653
2024-03-28 05:08:32.532 UTC [38358][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:32.532 UTC [38358][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:32.537 UTC [38358][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.2$$
2024-03-28 05:08:32.538 UTC [38358][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=24653
2024-03-28 05:08:32.544 UTC [38360][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24654
2024-03-28 05:08:32.549 UTC [38360][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:32.549 UTC [38360][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:32.549 UTC [38360][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$
2024-03-28 05:08:32.550 UTC [38360][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=24654
2024-03-28 05:08:32.558 UTC [38362][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24655
2024-03-28 05:08:32.565 UTC [38348][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:32.566 UTC [38348][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:32.566 UTC [38348][postmaster] LOG:  background worker "logical replication launcher" (PID 38354) exited with exit code 1
2024-03-28 05:08:32.567 UTC [38349][checkpointer] LOG:  shutting down
2024-03-28 05:08:32.567 UTC [38349][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:32.567 UTC [38349][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/4100FF0, redo lsn=0/4100FF0
2024-03-28 05:08:32.569 UTC [38348][postmaster] LOG:  database system is shut down
2024-03-28 05:08:32.682 UTC [38366][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:32.682 UTC [38366][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:32.682 UTC [38366][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:32.684 UTC [38369][startup] LOG:  database system was shut down at 2024-03-28 05:08:32 UTC
2024-03-28 05:08:32.686 UTC [38366][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:32.784 UTC [38374][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24656
2024-03-28 05:08:32.788 UTC [38374][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:32.788 UTC [38374][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:32.793 UTC [38374][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-28 05:08:32.793 UTC [38374][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=24656
2024-03-28 05:08:32.800 UTC [38377][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24657
2024-03-28 05:08:32.805 UTC [38377][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:32.805 UTC [38377][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:32.806 UTC [38377][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$
2024-03-28 05:08:32.806 UTC [38377][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=24657
2024-03-28 05:08:32.813 UTC [38379][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24658
2024-03-28 05:08:32.818 UTC [38379][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:32.818 UTC [38379][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:32.819 UTC [38379][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$
2024-03-28 05:08:32.819 UTC [38379][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=24658
2024-03-28 05:08:32.822 UTC [38366][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:32.822 UTC [38366][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:32.823 UTC [38366][postmaster] LOG:  background worker "logical replication launcher" (PID 38372) exited with exit code 1
2024-03-28 05:08:32.823 UTC [38367][checkpointer] LOG:  shutting down
2024-03-28 05:08:32.823 UTC [38367][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:32.824 UTC [38367][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/4101068, redo lsn=0/4101068
2024-03-28 05:08:32.825 UTC [38366][postmaster] LOG:  database system is shut down
2024-03-28 05:08:32.939 UTC [38381][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:32.939 UTC [38381][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:32.939 UTC [38381][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:32.941 UTC [38384][startup] LOG:  database system was shut down at 2024-03-28 05:08:32 UTC
2024-03-28 05:08:32.943 UTC [38381][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:33.042 UTC [38389][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24659
2024-03-28 05:08:33.047 UTC [38389][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:33.047 UTC [38389][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:33.052 UTC [38389][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=common-name.pg-ssltest.test$$
2024-03-28 05:08:33.052 UTC [38389][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=24659
2024-03-28 05:08:33.059 UTC [38391][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24660
2024-03-28 05:08:33.066 UTC [38381][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:33.066 UTC [38381][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:33.067 UTC [38381][postmaster] LOG:  background worker "logical replication launcher" (PID 38387) exited with exit code 1
2024-03-28 05:08:33.067 UTC [38382][checkpointer] LOG:  shutting down
2024-03-28 05:08:33.067 UTC [38382][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:33.067 UTC [38382][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/41010E0, redo lsn=0/41010E0
2024-03-28 05:08:33.069 UTC [38381][postmaster] LOG:  database system is shut down
2024-03-28 05:08:33.183 UTC [38393][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:33.183 UTC [38393][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:33.183 UTC [38393][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:33.185 UTC [38396][startup] LOG:  database system was shut down at 2024-03-28 05:08:33 UTC
2024-03-28 05:08:33.187 UTC [38393][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:33.286 UTC [38401][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24661
2024-03-28 05:08:33.291 UTC [38401][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-28 05:08:33.304 UTC [38404][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24662
2024-03-28 05:08:33.309 UTC [38404][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:33.309 UTC [38404][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:33.314 UTC [38404][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=system hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$
2024-03-28 05:08:33.314 UTC [38404][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=24662
2024-03-28 05:08:33.325 UTC [38406][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24663
2024-03-28 05:08:33.334 UTC [38393][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:33.334 UTC [38393][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:33.335 UTC [38393][postmaster] LOG:  background worker "logical replication launcher" (PID 38399) exited with exit code 1
2024-03-28 05:08:33.335 UTC [38394][checkpointer] LOG:  shutting down
2024-03-28 05:08:33.335 UTC [38394][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:33.335 UTC [38394][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4101158, redo lsn=0/4101158
2024-03-28 05:08:33.337 UTC [38393][postmaster] LOG:  database system is shut down
2024-03-28 05:08:33.453 UTC [38408][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:33.453 UTC [38408][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:33.453 UTC [38408][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:33.454 UTC [38411][startup] LOG:  database system was shut down at 2024-03-28 05:08:33 UTC
2024-03-28 05:08:33.457 UTC [38408][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:33.556 UTC [38416][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24664
2024-03-28 05:08:33.562 UTC [38416][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:33.563 UTC [38416][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:33.568 UTC [38416][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$
2024-03-28 05:08:33.569 UTC [38416][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=24664
2024-03-28 05:08:33.580 UTC [38418][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24665
2024-03-28 05:08:33.586 UTC [38418][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-28 05:08:33.596 UTC [38420][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24666
2024-03-28 05:08:33.601 UTC [38420][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-28 05:08:33.611 UTC [38422][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24667
2024-03-28 05:08:33.616 UTC [38422][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:33.616 UTC [38422][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:33.617 UTC [38422][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-28 05:08:33.619 UTC [38422][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=24667
2024-03-28 05:08:33.631 UTC [38424][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24668
2024-03-28 05:08:33.637 UTC [38424][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:33.637 UTC [38424][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.2, cipher=ECDHE-RSA-AES256-GCM-SHA384, bits=256)
2024-03-28 05:08:33.638 UTC [38424][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require ssl_min_protocol_version=TLSv1.2 ssl_max_protocol_version=TLSv1.2$$
2024-03-28 05:08:33.639 UTC [38424][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=24668
2024-03-28 05:08:33.677 UTC [38429][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24669
2024-03-28 05:08:33.683 UTC [38429][client backend] [[unknown]][3/1:0] FATAL:  connection requires a valid client certificate
2024-03-28 05:08:33.694 UTC [38431][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24670
2024-03-28 05:08:33.702 UTC [38431][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-28 05:08:33.702 UTC [38431][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:33.707 UTC [38431][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_xFTm/client.key$$
2024-03-28 05:08:33.707 UTC [38431][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=certdb host=localhost port=24670
2024-03-28 05:08:33.718 UTC [38433][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24671
2024-03-28 05:08:33.727 UTC [38433][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-28 05:08:33.727 UTC [38433][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:33.728 UTC [38433][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_xFTm/client-der.key$$
2024-03-28 05:08:33.728 UTC [38433][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=24671
2024-03-28 05:08:33.740 UTC [38435][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24672
2024-03-28 05:08:33.747 UTC [38435][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-28 05:08:33.747 UTC [38435][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:33.748 UTC [38435][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_xFTm/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$
2024-03-28 05:08:33.749 UTC [38435][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=24672
2024-03-28 05:08:33.759 UTC [38437][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24673
2024-03-28 05:08:33.766 UTC [38437][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-28 05:08:33.766 UTC [38437][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:33.767 UTC [38437][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_xFTm/client-encrypted-der.key sslpassword='dUmmyP^#+'$$
2024-03-28 05:08:33.767 UTC [38437][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=24673
2024-03-28 05:08:33.775 UTC [38439][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24674
2024-03-28 05:08:33.782 UTC [38439][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-28 05:08:33.782 UTC [38439][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:33.782 UTC [38439][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_xFTm/client.key$$
2024-03-28 05:08:33.783 UTC [38439][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=24674
2024-03-28 05:08:33.789 UTC [38441][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24675
2024-03-28 05:08:33.795 UTC [38441][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-28 05:08:33.795 UTC [38441][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:33.795 UTC [38441][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_xFTm/client.key$$
2024-03-28 05:08:33.796 UTC [38441][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=24675
2024-03-28 05:08:33.802 UTC [38443][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24676
2024-03-28 05:08:33.806 UTC [38443][client backend] [[unknown]][10/1:0] FATAL:  connection requires a valid client certificate
2024-03-28 05:08:33.815 UTC [38445][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24677
2024-03-28 05:08:33.818 UTC [38445][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-28 05:08:33.825 UTC [38447][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24678
2024-03-28 05:08:33.833 UTC [38447][client backend] [[unknown]][11/1:0] LOG:  connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:8)
2024-03-28 05:08:33.833 UTC [38447][client backend] [[unknown]][11/1:0] LOG:  connection authorized: user=ssltestuser database=certdb_dn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:33.838 UTC [38447][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_xFTm/client-dn.key$$
2024-03-28 05:08:33.839 UTC [38447][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=certdb_dn host=localhost port=24678
2024-03-28 05:08:33.847 UTC [38449][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24679
2024-03-28 05:08:33.854 UTC [38449][client backend] [[unknown]][12/1:0] LOG:  connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:9)
2024-03-28 05:08:33.854 UTC [38449][client backend] [[unknown]][12/1:0] LOG:  connection authorized: user=ssltestuser database=certdb_dn_re application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:33.859 UTC [38449][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_xFTm/client-dn.key$$
2024-03-28 05:08:33.859 UTC [38449][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb_dn_re host=localhost port=24679
2024-03-28 05:08:33.868 UTC [38451][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24680
2024-03-28 05:08:33.876 UTC [38451][client backend] [[unknown]][13/1:0] LOG:  connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:10)
2024-03-28 05:08:33.876 UTC [38451][client backend] [[unknown]][13/1:0] LOG:  connection authorized: user=ssltestuser database=certdb_cn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:33.881 UTC [38451][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_xFTm/client-dn.key$$
2024-03-28 05:08:33.882 UTC [38451][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=certdb_cn host=localhost port=24680
2024-03-28 05:08:33.907 UTC [38454][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24681
2024-03-28 05:08:33.915 UTC [38454][client backend] [[unknown]][14/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-28 05:08:33.915 UTC [38454][client backend] [[unknown]][14/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:33.916 UTC [38454][client backend] [001_ssltests.pl][14/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-28 05:08:33.918 UTC [38454][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=24681
2024-03-28 05:08:33.926 UTC [38456][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24682
2024-03-28 05:08:33.928 UTC [38456][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-28 05:08:33.935 UTC [38458][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24683
2024-03-28 05:08:33.943 UTC [38458][client backend] [[unknown]][15/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-28 05:08:33.943 UTC [38458][client backend] [[unknown]][15/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-28 05:08:33.943 UTC [38458][client backend] [[unknown]][15/1:0] FATAL:  certificate authentication failed for user "anotheruser"
2024-03-28 05:08:33.943 UTC [38458][client backend] [[unknown]][15/1:0] DETAIL:  Connection matched file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf" line 7: "hostssl certdb          all             127.0.0.1/32            cert"
2024-03-28 05:08:33.951 UTC [38460][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24684
2024-03-28 05:08:33.958 UTC [38460][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-28 05:08:33.958 UTC [38460][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: certificate revoked.
	Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-28 05:08:33.966 UTC [38462][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24685
2024-03-28 05:08:33.974 UTC [38462][client backend] [[unknown]][16/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:4)
2024-03-28 05:08:33.974 UTC [38462][client backend] [[unknown]][16/1:0] LOG:  connection authorized: user=ssltestuser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:33.979 UTC [38462][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_xFTm/client.key$$
2024-03-28 05:08:33.979 UTC [38462][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=verifydb host=localhost port=24685
2024-03-28 05:08:33.987 UTC [38464][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24686
2024-03-28 05:08:33.994 UTC [38464][client backend] [[unknown]][17/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-28 05:08:33.994 UTC [38464][client backend] [[unknown]][17/1:0] LOG:  certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch
2024-03-28 05:08:33.994 UTC [38464][client backend] [[unknown]][17/1:0] FATAL:  "trust" authentication failed for user "anotheruser"
2024-03-28 05:08:33.994 UTC [38464][client backend] [[unknown]][17/1:0] DETAIL:  Connection matched file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf" line 5: "hostssl verifydb        anotheruser     127.0.0.1/32            trust        clientcert=verify-full"
2024-03-28 05:08:34.002 UTC [38466][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24687
2024-03-28 05:08:34.009 UTC [38466][client backend] [[unknown]][18/1:0] LOG:  connection authenticated: user="yetanotheruser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:6)
2024-03-28 05:08:34.009 UTC [38466][client backend] [[unknown]][18/1:0] LOG:  connection authorized: user=yetanotheruser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:34.010 UTC [38466][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_xFTm/client.key$$
2024-03-28 05:08:34.010 UTC [38466][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=yetanotheruser database=verifydb host=localhost port=24687
2024-03-28 05:08:34.014 UTC [38408][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:34.014 UTC [38408][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:34.015 UTC [38408][postmaster] LOG:  background worker "logical replication launcher" (PID 38414) exited with exit code 1
2024-03-28 05:08:34.015 UTC [38409][checkpointer] LOG:  shutting down
2024-03-28 05:08:34.015 UTC [38409][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:34.016 UTC [38409][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/41011D0, redo lsn=0/41011D0
2024-03-28 05:08:34.017 UTC [38408][postmaster] LOG:  database system is shut down
2024-03-28 05:08:34.133 UTC [38468][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:34.133 UTC [38468][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:34.133 UTC [38468][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:34.135 UTC [38471][startup] LOG:  database system was shut down at 2024-03-28 05:08:34 UTC
2024-03-28 05:08:34.139 UTC [38468][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:34.235 UTC [38476][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24688
2024-03-28 05:08:34.243 UTC [38476][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-28 05:08:34.243 UTC [38476][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:34.249 UTC [38476][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_xFTm/client.key sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 host=localhost sslmode=require sslcert=ssl/client+client_ca.crt$$
2024-03-28 05:08:34.249 UTC [38476][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=certdb host=localhost port=24688
2024-03-28 05:08:34.256 UTC [38478][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24689
2024-03-28 05:08:34.263 UTC [38478][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-28 05:08:34.263 UTC [38478][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: unable to get local issuer certificate.
	Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921344, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-28 05:08:34.271 UTC [38480][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24690
2024-03-28 05:08:34.277 UTC [38480][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-28 05:08:34.277 UTC [38480][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: unable to get local issuer certificate.
	Failed certificate data (unverified): subject ".../CN=ssl-123456789012345678901234567890123456789012345678901234567890", serial number 2315702411956921348, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-28 05:08:34.281 UTC [38468][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:34.281 UTC [38468][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:34.282 UTC [38468][postmaster] LOG:  background worker "logical replication launcher" (PID 38474) exited with exit code 1
2024-03-28 05:08:34.282 UTC [38469][checkpointer] LOG:  shutting down
2024-03-28 05:08:34.282 UTC [38469][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:34.282 UTC [38469][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/4101248, redo lsn=0/4101248
2024-03-28 05:08:34.284 UTC [38468][postmaster] LOG:  database system is shut down
2024-03-28 05:08:34.400 UTC [38482][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:34.400 UTC [38482][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:34.400 UTC [38482][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:34.402 UTC [38485][startup] LOG:  database system was shut down at 2024-03-28 05:08:34 UTC
2024-03-28 05:08:34.406 UTC [38482][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:34.501 UTC [38490][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24691
2024-03-28 05:08:34.508 UTC [38490][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-28 05:08:34.508 UTC [38490][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 1: unable to get local issuer certificate.
	Failed certificate data (unverified): subject "/CN=Test CA for PostgreSQL SSL regression test client certs", serial number 2315702411956921089, issuer "/CN=Test root CA for PostgreSQL SSL regression test suite".
2024-03-28 05:08:34.512 UTC [38482][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:34.512 UTC [38482][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:34.513 UTC [38482][postmaster] LOG:  background worker "logical replication launcher" (PID 38488) exited with exit code 1
2024-03-28 05:08:34.513 UTC [38483][checkpointer] LOG:  shutting down
2024-03-28 05:08:34.513 UTC [38483][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:34.514 UTC [38483][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/41012C0, redo lsn=0/41012C0
2024-03-28 05:08:34.515 UTC [38482][postmaster] LOG:  database system is shut down
2024-03-28 05:08:34.629 UTC [38492][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:34.629 UTC [38492][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:34.629 UTC [38492][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:34.630 UTC [38495][startup] LOG:  database system was shut down at 2024-03-28 05:08:34 UTC
2024-03-28 05:08:34.632 UTC [38492][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:34.732 UTC [38500][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24692
2024-03-28 05:08:34.739 UTC [38500][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-28 05:08:34.739 UTC [38500][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: certificate revoked.
	Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-28 05:08:34.748 UTC [38502][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24693
2024-03-28 05:08:34.755 UTC [38502][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-28 05:08:34.755 UTC [38502][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: certificate revoked.
	Failed certificate data (unverified): subject "/CN=\xce\x9f\xce\xb4\xcf\x85\xcf\x83\xcf\x83\xce\xad\xce\xb1\xcf\x82", serial number 2315702411956921349, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-28 05:08:34.759 UTC [38492][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:34.759 UTC [38492][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:34.760 UTC [38492][postmaster] LOG:  background worker "logical replication launcher" (PID 38498) exited with exit code 1
2024-03-28 05:08:34.760 UTC [38493][checkpointer] LOG:  shutting down
2024-03-28 05:08:34.760 UTC [38493][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:34.761 UTC [38493][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/4101338, redo lsn=0/4101338
2024-03-28 05:08:34.763 UTC [38492][postmaster] LOG:  database system is shut down
2024-03-28 05:08:34.876 UTC [38504][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:34.876 UTC [38504][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:34.876 UTC [38504][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:34.878 UTC [38507][startup] LOG:  database system was shut down at 2024-03-28 05:08:34 UTC
2024-03-28 05:08:34.881 UTC [38504][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:34.979 UTC [38512][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24694
2024-03-28 05:08:34.982 UTC [38512][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-28 05:08:34.982 UTC [38512][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-28 05:08:34.992 UTC [38514][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24695
2024-03-28 05:08:34.998 UTC [38514][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:34.998 UTC [38514][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:35.003 UTC [38514][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-28 05:08:35.003 UTC [38514][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=24695
2024-03-28 05:08:35.008 UTC [38504][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:35.008 UTC [38504][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:35.009 UTC [38504][postmaster] LOG:  background worker "logical replication launcher" (PID 38510) exited with exit code 1
2024-03-28 05:08:35.009 UTC [38505][checkpointer] LOG:  shutting down
2024-03-28 05:08:35.009 UTC [38505][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:35.010 UTC [38505][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/41013B0, redo lsn=0/41013B0
2024-03-28 05:08:35.011 UTC [38504][postmaster] LOG:  database system is shut down
2024-03-28 05:08:35.127 UTC [38516][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:35.127 UTC [38516][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:35.127 UTC [38516][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:35.129 UTC [38519][startup] LOG:  database system was shut down at 2024-03-28 05:08:35 UTC
2024-03-28 05:08:35.132 UTC [38516][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:35.229 UTC [38524][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24696
2024-03-28 05:08:35.232 UTC [38524][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-28 05:08:35.232 UTC [38524][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-28 05:08:35.242 UTC [38526][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24697
2024-03-28 05:08:35.248 UTC [38526][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:35.248 UTC [38526][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:35.253 UTC [38526][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-28 05:08:35.253 UTC [38526][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=24697
2024-03-28 05:08:35.258 UTC [38516][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:35.259 UTC [38516][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:35.260 UTC [38516][postmaster] LOG:  background worker "logical replication launcher" (PID 38522) exited with exit code 1
2024-03-28 05:08:35.260 UTC [38517][checkpointer] LOG:  shutting down
2024-03-28 05:08:35.260 UTC [38517][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:35.261 UTC [38517][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/4101428, redo lsn=0/4101428
2024-03-28 05:08:35.262 UTC [38516][postmaster] LOG:  database system is shut down
2024-03-28 05:08:35.379 UTC [38528][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:35.379 UTC [38528][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:35.379 UTC [38528][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:35.381 UTC [38531][startup] LOG:  database system was shut down at 2024-03-28 05:08:35 UTC
2024-03-28 05:08:35.385 UTC [38528][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:35.479 UTC [38536][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24698
2024-03-28 05:08:35.481 UTC [38536][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-28 05:08:35.481 UTC [38536][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-28 05:08:35.489 UTC [38538][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24699
2024-03-28 05:08:35.494 UTC [38538][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:35.494 UTC [38538][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:35.499 UTC [38538][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-28 05:08:35.499 UTC [38538][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=24699
2024-03-28 05:08:35.503 UTC [38528][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:35.503 UTC [38528][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:35.505 UTC [38528][postmaster] LOG:  background worker "logical replication launcher" (PID 38534) exited with exit code 1
2024-03-28 05:08:35.505 UTC [38529][checkpointer] LOG:  shutting down
2024-03-28 05:08:35.505 UTC [38529][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:35.506 UTC [38529][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/41014A0, redo lsn=0/41014A0
2024-03-28 05:08:35.508 UTC [38528][postmaster] LOG:  database system is shut down
2024-03-28 05:08:35.621 UTC [38540][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:35.621 UTC [38540][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:35.621 UTC [38540][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:35.623 UTC [38543][startup] LOG:  database system was shut down at 2024-03-28 05:08:35 UTC
2024-03-28 05:08:35.626 UTC [38540][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:35.723 UTC [38548][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24700
2024-03-28 05:08:35.725 UTC [38548][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-28 05:08:35.725 UTC [38548][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-28 05:08:35.734 UTC [38550][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24701
2024-03-28 05:08:35.738 UTC [38550][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:35.738 UTC [38550][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:35.742 UTC [38550][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-28 05:08:35.742 UTC [38550][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=24701
2024-03-28 05:08:35.746 UTC [38540][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:35.746 UTC [38540][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:35.748 UTC [38540][postmaster] LOG:  background worker "logical replication launcher" (PID 38546) exited with exit code 1
2024-03-28 05:08:35.748 UTC [38541][checkpointer] LOG:  shutting down
2024-03-28 05:08:35.748 UTC [38541][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:35.748 UTC [38541][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/4101518, redo lsn=0/4101518
2024-03-28 05:08:35.750 UTC [38540][postmaster] LOG:  database system is shut down
2024-03-28 05:08:35.863 UTC [38552][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:35.863 UTC [38552][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:35.863 UTC [38552][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:35.865 UTC [38555][startup] LOG:  database system was shut down at 2024-03-28 05:08:35 UTC
2024-03-28 05:08:35.869 UTC [38552][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:35.966 UTC [38560][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24702
2024-03-28 05:08:35.969 UTC [38560][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-28 05:08:35.970 UTC [38560][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-28 05:08:35.978 UTC [38562][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24703
2024-03-28 05:08:35.983 UTC [38562][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:35.983 UTC [38562][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:35.987 UTC [38562][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-28 05:08:35.987 UTC [38562][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=24703
2024-03-28 05:08:35.990 UTC [38552][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:35.990 UTC [38552][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:35.992 UTC [38552][postmaster] LOG:  background worker "logical replication launcher" (PID 38558) exited with exit code 1
2024-03-28 05:08:35.992 UTC [38553][checkpointer] LOG:  shutting down
2024-03-28 05:08:35.992 UTC [38553][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:35.992 UTC [38553][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/4101590, redo lsn=0/4101590
2024-03-28 05:08:35.994 UTC [38552][postmaster] LOG:  database system is shut down
2024-03-28 05:08:36.106 UTC [38564][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:36.107 UTC [38564][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:36.107 UTC [38564][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:36.108 UTC [38567][startup] LOG:  database system was shut down at 2024-03-28 05:08:35 UTC
2024-03-28 05:08:36.111 UTC [38564][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:36.210 UTC [38572][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24704
2024-03-28 05:08:36.212 UTC [38572][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-28 05:08:36.212 UTC [38572][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-28 05:08:36.220 UTC [38574][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24705
2024-03-28 05:08:36.224 UTC [38574][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:36.224 UTC [38574][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:36.229 UTC [38574][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-28 05:08:36.229 UTC [38574][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=24705
2024-03-28 05:08:36.233 UTC [38564][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:36.233 UTC [38564][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:36.234 UTC [38564][postmaster] LOG:  background worker "logical replication launcher" (PID 38570) exited with exit code 1
2024-03-28 05:08:36.234 UTC [38565][checkpointer] LOG:  shutting down
2024-03-28 05:08:36.234 UTC [38565][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:36.235 UTC [38565][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/4101608, redo lsn=0/4101608
2024-03-28 05:08:36.236 UTC [38564][postmaster] LOG:  database system is shut down
2024-03-28 05:08:36.351 UTC [38576][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:36.351 UTC [38576][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:36.351 UTC [38576][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:36.354 UTC [38579][startup] LOG:  database system was shut down at 2024-03-28 05:08:36 UTC
2024-03-28 05:08:36.358 UTC [38576][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:36.454 UTC [38584][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24706
2024-03-28 05:08:36.456 UTC [38584][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-28 05:08:36.456 UTC [38584][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-28 05:08:36.466 UTC [38586][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24707
2024-03-28 05:08:36.471 UTC [38586][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:36.471 UTC [38586][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:36.475 UTC [38586][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-28 05:08:36.476 UTC [38586][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=24707
2024-03-28 05:08:36.480 UTC [38576][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:36.480 UTC [38576][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:36.481 UTC [38576][postmaster] LOG:  background worker "logical replication launcher" (PID 38582) exited with exit code 1
2024-03-28 05:08:36.482 UTC [38577][checkpointer] LOG:  shutting down
2024-03-28 05:08:36.482 UTC [38577][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:36.482 UTC [38577][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/4101680, redo lsn=0/4101680
2024-03-28 05:08:36.484 UTC [38576][postmaster] LOG:  database system is shut down
2024-03-28 05:08:36.599 UTC [38588][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:36.599 UTC [38588][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:36.599 UTC [38588][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:36.602 UTC [38591][startup] LOG:  database system was shut down at 2024-03-28 05:08:36 UTC
2024-03-28 05:08:36.606 UTC [38588][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:36.702 UTC [38596][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24708
2024-03-28 05:08:36.704 UTC [38596][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-28 05:08:36.704 UTC [38596][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-28 05:08:36.712 UTC [38598][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24709
2024-03-28 05:08:36.718 UTC [38598][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:36.718 UTC [38598][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:36.725 UTC [38598][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-28 05:08:36.726 UTC [38598][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=24709
2024-03-28 05:08:36.731 UTC [38588][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:36.731 UTC [38588][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:36.732 UTC [38588][postmaster] LOG:  background worker "logical replication launcher" (PID 38594) exited with exit code 1
2024-03-28 05:08:36.733 UTC [38589][checkpointer] LOG:  shutting down
2024-03-28 05:08:36.733 UTC [38589][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:36.734 UTC [38589][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/41016F8, redo lsn=0/41016F8
2024-03-28 05:08:36.736 UTC [38588][postmaster] LOG:  database system is shut down
2024-03-28 05:08:36.848 UTC [38600][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 05:08:36.848 UTC [38600][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 64747
2024-03-28 05:08:36.848 UTC [38600][postmaster] LOG:  listening on Unix socket "/tmp/SRwA3suIyB/.s.PGSQL.64747"
2024-03-28 05:08:36.850 UTC [38603][startup] LOG:  database system was shut down at 2024-03-28 05:08:36 UTC
2024-03-28 05:08:36.853 UTC [38600][postmaster] LOG:  database system is ready to accept connections
2024-03-28 05:08:36.952 UTC [38608][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24710
2024-03-28 05:08:36.954 UTC [38608][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-28 05:08:36.954 UTC [38608][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-28 05:08:36.963 UTC [38610][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=24711
2024-03-28 05:08:36.969 UTC [38610][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-28 05:08:36.969 UTC [38610][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-28 05:08:36.975 UTC [38610][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-28 05:08:36.976 UTC [38610][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=24711
2024-03-28 05:08:36.980 UTC [38600][postmaster] LOG:  received fast shutdown request
2024-03-28 05:08:36.980 UTC [38600][postmaster] LOG:  aborting any active transactions
2024-03-28 05:08:36.981 UTC [38600][postmaster] LOG:  background worker "logical replication launcher" (PID 38606) exited with exit code 1
2024-03-28 05:08:36.981 UTC [38601][checkpointer] LOG:  shutting down
2024-03-28 05:08:36.982 UTC [38601][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 05:08:36.982 UTC [38601][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/4101770, redo lsn=0/4101770
2024-03-28 05:08:36.984 UTC [38600][postmaster] LOG:  database system is shut down
2024-03-28 05:08:37.094 UTC [38612][postmaster] FATAL:  could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory
2024-03-28 05:08:37.094 UTC [38612][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[05:08:37.193](0.216s) Bail out!  pg_ctl restart failed
# No postmaster PID for node "primary"