[07:40:20.671](0.023s) # setting up data directory
# Checking port 63452
# Found port 63452
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=63452 host=/tmp/VsJP7z3J2v
Log file: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log
[07:40:20.674](0.003s) # initializing database system by copying initdb template
# Running: cp -RPp /tmp/cirrus-ci-build/build/tmp_install/initdb-template /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata
# Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata
### Starting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log -o --cluster-name=primary start
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 36774
[07:40:20.832](0.158s) 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 36885
[07:40:21.694](0.862s) # 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-12 07:40:20.727 UTC [36774][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:20.727 UTC [36774][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:20.728 UTC [36777][startup] LOG:  database system was shut down at 2024-03-12 07:36:04 UTC
2024-03-12 07:40:20.730 UTC [36774][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:20.826 UTC [36796][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:20.826 UTC [36796][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-12 07:40:20.826 UTC [36796][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:20.830 UTC [36796][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-12 07:40:20.830 UTC [36796][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-12 07:40:20.838 UTC [36802][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:20.838 UTC [36802][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-12 07:40:20.838 UTC [36802][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:20.839 UTC [36802][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-12 07:40:20.839 UTC [36802][client backend] [001_ssltests.pl][1/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-12 07:40:20.839 UTC [36802][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-12 07:40:20.881 UTC [36809][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:20.882 UTC [36809][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-12 07:40:20.882 UTC [36809][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:20.882 UTC [36809][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-12 07:40:20.883 UTC [36809][client backend] [001_ssltests.pl][2/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-12 07:40:20.883 UTC [36809][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-12 07:40:20.928 UTC [36815][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:20.928 UTC [36815][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-12 07:40:20.928 UTC [36815][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:20.929 UTC [36815][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-12 07:40:20.929 UTC [36815][client backend] [001_ssltests.pl][3/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-12 07:40:20.929 UTC [36815][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-12 07:40:20.971 UTC [36830][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:20.971 UTC [36830][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-12 07:40:20.971 UTC [36830][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:20.972 UTC [36830][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-12 07:40:20.972 UTC [36830][client backend] [001_ssltests.pl][4/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-12 07:40:20.972 UTC [36830][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-12 07:40:21.017 UTC [36836][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:21.018 UTC [36836][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-12 07:40:21.018 UTC [36836][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:21.018 UTC [36836][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-12 07:40:21.018 UTC [36836][client backend] [001_ssltests.pl][5/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-12 07:40:21.037 UTC [36836][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.019 user=postgres database=postgres host=[local]
2024-03-12 07:40:21.063 UTC [36840][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:21.063 UTC [36840][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-12 07:40:21.063 UTC [36840][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:21.064 UTC [36840][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-12 07:40:21.064 UTC [36840][client backend] [001_ssltests.pl][6/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-12 07:40:21.087 UTC [36840][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=postgres database=postgres host=[local]
2024-03-12 07:40:21.108 UTC [36843][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:21.108 UTC [36843][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-12 07:40:21.108 UTC [36843][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:21.109 UTC [36843][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-12 07:40:21.109 UTC [36843][client backend] [001_ssltests.pl][7/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-12 07:40:21.125 UTC [36843][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.016 user=postgres database=postgres host=[local]
2024-03-12 07:40:21.151 UTC [36847][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:21.151 UTC [36847][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-12 07:40:21.151 UTC [36847][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:21.152 UTC [36847][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-12 07:40:21.152 UTC [36847][client backend] [001_ssltests.pl][8/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-12 07:40:21.175 UTC [36847][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=postgres database=postgres host=[local]
2024-03-12 07:40:21.196 UTC [36850][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:21.196 UTC [36850][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-12 07:40:21.196 UTC [36850][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:21.197 UTC [36850][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-12 07:40:21.197 UTC [36850][client backend] [001_ssltests.pl][9/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-12 07:40:21.212 UTC [36850][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=postgres database=postgres host=[local]
2024-03-12 07:40:21.241 UTC [36857][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:21.241 UTC [36857][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-12 07:40:21.241 UTC [36857][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:21.242 UTC [36857][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-12 07:40:21.242 UTC [36857][client backend] [001_ssltests.pl][10/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-12 07:40:21.265 UTC [36857][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=postgres database=postgres host=[local]
2024-03-12 07:40:21.287 UTC [36774][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:21.287 UTC [36774][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:21.288 UTC [36774][postmaster] LOG:  background worker "logical replication launcher" (PID 36780) exited with exit code 1
2024-03-12 07:40:21.288 UTC [36775][checkpointer] LOG:  shutting down
2024-03-12 07:40:21.288 UTC [36775][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:21.561 UTC [36775][checkpointer] LOG:  checkpoint complete: wrote 5614 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.263 s, sync=0.001 s, total=0.273 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45190 kB, estimate=45190 kB; lsn=0/413E658, redo lsn=0/413E658
2024-03-12 07:40:21.565 UTC [36774][postmaster] LOG:  database system is shut down
2024-03-12 07:40:21.601 UTC [36885][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:21.602 UTC [36885][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:21.602 UTC [36885][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:21.603 UTC [36889][startup] LOG:  database system was shut down at 2024-03-12 07:40:21 UTC
2024-03-12 07:40:21.605 UTC [36885][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:21.696 UTC [36885][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:21.696 UTC [36885][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:21.698 UTC [36885][postmaster] LOG:  background worker "logical replication launcher" (PID 36892) exited with exit code 1
2024-03-12 07:40:21.698 UTC [36887][checkpointer] LOG:  shutting down
2024-03-12 07:40:21.698 UTC [36887][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:21.698 UTC [36887][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/413E6D0, redo lsn=0/413E6D0
2024-03-12 07:40:21.699 UTC [36885][postmaster] LOG:  database system is shut down
2024-03-12 07:40:21.810 UTC [36904][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-12 07:40:21.810 UTC [36904][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[07:40:21.903](0.209s) 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 36914
[07:40:22.007](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-12 07:40:20.727 UTC [36774][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:20.727 UTC [36774][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:20.728 UTC [36777][startup] LOG:  database system was shut down at 2024-03-12 07:36:04 UTC
2024-03-12 07:40:20.730 UTC [36774][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:20.826 UTC [36796][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:20.826 UTC [36796][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-12 07:40:20.826 UTC [36796][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:20.830 UTC [36796][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-12 07:40:20.830 UTC [36796][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-12 07:40:20.838 UTC [36802][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:20.838 UTC [36802][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-12 07:40:20.838 UTC [36802][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:20.839 UTC [36802][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-12 07:40:20.839 UTC [36802][client backend] [001_ssltests.pl][1/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-12 07:40:20.839 UTC [36802][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-12 07:40:20.881 UTC [36809][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:20.882 UTC [36809][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-12 07:40:20.882 UTC [36809][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:20.882 UTC [36809][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-12 07:40:20.883 UTC [36809][client backend] [001_ssltests.pl][2/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-12 07:40:20.883 UTC [36809][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-12 07:40:20.928 UTC [36815][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:20.928 UTC [36815][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-12 07:40:20.928 UTC [36815][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:20.929 UTC [36815][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-12 07:40:20.929 UTC [36815][client backend] [001_ssltests.pl][3/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-12 07:40:20.929 UTC [36815][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-12 07:40:20.971 UTC [36830][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:20.971 UTC [36830][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-12 07:40:20.971 UTC [36830][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:20.972 UTC [36830][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-12 07:40:20.972 UTC [36830][client backend] [001_ssltests.pl][4/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-12 07:40:20.972 UTC [36830][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-12 07:40:21.017 UTC [36836][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:21.018 UTC [36836][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-12 07:40:21.018 UTC [36836][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:21.018 UTC [36836][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-12 07:40:21.018 UTC [36836][client backend] [001_ssltests.pl][5/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-12 07:40:21.037 UTC [36836][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.019 user=postgres database=postgres host=[local]
2024-03-12 07:40:21.063 UTC [36840][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:21.063 UTC [36840][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-12 07:40:21.063 UTC [36840][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:21.064 UTC [36840][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-12 07:40:21.064 UTC [36840][client backend] [001_ssltests.pl][6/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-12 07:40:21.087 UTC [36840][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=postgres database=postgres host=[local]
2024-03-12 07:40:21.108 UTC [36843][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:21.108 UTC [36843][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-12 07:40:21.108 UTC [36843][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:21.109 UTC [36843][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-12 07:40:21.109 UTC [36843][client backend] [001_ssltests.pl][7/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-12 07:40:21.125 UTC [36843][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.016 user=postgres database=postgres host=[local]
2024-03-12 07:40:21.151 UTC [36847][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:21.151 UTC [36847][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-12 07:40:21.151 UTC [36847][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:21.152 UTC [36847][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-12 07:40:21.152 UTC [36847][client backend] [001_ssltests.pl][8/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-12 07:40:21.175 UTC [36847][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=postgres database=postgres host=[local]
2024-03-12 07:40:21.196 UTC [36850][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:21.196 UTC [36850][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-12 07:40:21.196 UTC [36850][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:21.197 UTC [36850][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-12 07:40:21.197 UTC [36850][client backend] [001_ssltests.pl][9/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-12 07:40:21.212 UTC [36850][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=postgres database=postgres host=[local]
2024-03-12 07:40:21.241 UTC [36857][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:21.241 UTC [36857][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-12 07:40:21.241 UTC [36857][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:21.242 UTC [36857][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-12 07:40:21.242 UTC [36857][client backend] [001_ssltests.pl][10/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-12 07:40:21.265 UTC [36857][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=postgres database=postgres host=[local]
2024-03-12 07:40:21.287 UTC [36774][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:21.287 UTC [36774][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:21.288 UTC [36774][postmaster] LOG:  background worker "logical replication launcher" (PID 36780) exited with exit code 1
2024-03-12 07:40:21.288 UTC [36775][checkpointer] LOG:  shutting down
2024-03-12 07:40:21.288 UTC [36775][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:21.561 UTC [36775][checkpointer] LOG:  checkpoint complete: wrote 5614 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.263 s, sync=0.001 s, total=0.273 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45190 kB, estimate=45190 kB; lsn=0/413E658, redo lsn=0/413E658
2024-03-12 07:40:21.565 UTC [36774][postmaster] LOG:  database system is shut down
2024-03-12 07:40:21.601 UTC [36885][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:21.602 UTC [36885][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:21.602 UTC [36885][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:21.603 UTC [36889][startup] LOG:  database system was shut down at 2024-03-12 07:40:21 UTC
2024-03-12 07:40:21.605 UTC [36885][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:21.696 UTC [36885][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:21.696 UTC [36885][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:21.698 UTC [36885][postmaster] LOG:  background worker "logical replication launcher" (PID 36892) exited with exit code 1
2024-03-12 07:40:21.698 UTC [36887][checkpointer] LOG:  shutting down
2024-03-12 07:40:21.698 UTC [36887][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:21.698 UTC [36887][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/413E6D0, redo lsn=0/413E6D0
2024-03-12 07:40:21.699 UTC [36885][postmaster] LOG:  database system is shut down
2024-03-12 07:40:21.810 UTC [36904][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-12 07:40:21.810 UTC [36904][postmaster] LOG:  database system is shut down
2024-03-12 07:40:21.918 UTC [36914][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:21.918 UTC [36914][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:21.918 UTC [36914][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:21.919 UTC [36919][startup] LOG:  database system was shut down at 2024-03-12 07:40:21 UTC
2024-03-12 07:40:21.922 UTC [36914][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:22.010 UTC [36914][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:22.010 UTC [36914][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:22.011 UTC [36914][postmaster] LOG:  background worker "logical replication launcher" (PID 36922) exited with exit code 1
2024-03-12 07:40:22.012 UTC [36917][checkpointer] LOG:  shutting down
2024-03-12 07:40:22.012 UTC [36917][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:22.012 UTC [36917][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/413E748, redo lsn=0/413E748
2024-03-12 07:40:22.014 UTC [36914][postmaster] LOG:  database system is shut down
2024-03-12 07:40:22.121 UTC [36933][postmaster] FATAL:  could not set SSL protocol version range
2024-03-12 07:40:22.121 UTC [36933][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-12 07:40:22.121 UTC [36933][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[07:40:22.212](0.205s) 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 36948
[07:40:22.322](0.109s) ok 5 - restart succeeds with correct SSL protocol bounds
[07:40:22.322](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 36967
[07:40:22.537](0.215s) ok 6 - server doesn't accept non-SSL connections
[07:40:22.538](0.000s) ok 7 - server doesn't accept non-SSL connections: matches
[07:40:22.552](0.014s) ok 8 - connect without server root cert sslmode=require
[07:40:22.552](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr
[07:40:22.560](0.008s) ok 10 - connect without server root cert sslmode=verify-ca
[07:40:22.560](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches
[07:40:22.568](0.008s) ok 12 - connect without server root cert sslmode=verify-full
[07:40:22.568](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches
[07:40:22.578](0.010s) ok 14 - connect with wrong server root cert sslmode=require
[07:40:22.579](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches
[07:40:22.589](0.010s) ok 16 - connect with wrong server root cert sslmode=verify-ca
[07:40:22.589](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches
[07:40:22.598](0.009s) ok 18 - connect with wrong server root cert sslmode=verify-full
[07:40:22.598](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches
[07:40:22.608](0.009s) ok 20 - connect with server CA cert, without root CA
[07:40:22.608](0.000s) ok 21 - connect with server CA cert, without root CA: matches
[07:40:22.619](0.011s) ok 22 - connect with correct server CA cert file sslmode=require
[07:40:22.619](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr
[07:40:22.631](0.012s) ok 24 - connect with correct server CA cert file sslmode=verify-ca
[07:40:22.631](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr
[07:40:22.642](0.011s) ok 26 - connect with correct server CA cert file sslmode=verify-full
[07:40:22.643](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr
[07:40:22.654](0.011s) ok 28 - cert root file that contains two certificates, order 1
[07:40:22.654](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr
[07:40:22.665](0.011s) ok 30 - cert root file that contains two certificates, order 2
[07:40:22.665](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr
[07:40:22.676](0.010s) ok 32 - connect with sslcertmode=disable
[07:40:22.676](0.000s) ok 33 - connect with sslcertmode=disable: no stderr
[07:40:22.686](0.011s) ok 34 - connect with sslcertmode=allow
[07:40:22.687](0.000s) ok 35 - connect with sslcertmode=allow: no stderr
[07:40:22.696](0.010s) ok 36 - connect with sslcertmode=require fails without a client certificate
[07:40:22.696](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches
[07:40:22.706](0.010s) ok 38 - sslcrl option with invalid file name
[07:40:22.707](0.000s) ok 39 - sslcrl option with invalid file name: no stderr
[07:40:22.715](0.009s) ok 40 - CRL belonging to a different CA
[07:40:22.716](0.000s) ok 41 - CRL belonging to a different CA: matches
[07:40:22.725](0.009s) ok 42 - directory CRL belonging to a different CA
[07:40:22.725](0.000s) ok 43 - directory CRL belonging to a different CA: matches
[07:40:22.735](0.010s) ok 44 - CRL with a non-revoked cert
[07:40:22.735](0.000s) ok 45 - CRL with a non-revoked cert: no stderr
[07:40:22.745](0.010s) ok 46 - directory CRL with a non-revoked cert
[07:40:22.745](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr
[07:40:22.755](0.010s) ok 48 - mismatch between host name and server certificate sslmode=require
[07:40:22.755](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr
[07:40:22.765](0.010s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca
[07:40:22.766](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr
[07:40:22.774](0.009s) ok 52 - mismatch between host name and server certificate sslmode=verify-full
[07:40:22.775](0.000s) ok 53 - mismatch between host name and server certificate sslmode=verify-full: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 37059
[07:40:22.993](0.219s) ok 54 - IP address in the Common Name
[07:40:22.993](0.000s) ok 55 - IP address in the Common Name: no stderr
[07:40:23.003](0.010s) ok 56 - mismatch between host name and server certificate IP address
[07:40:23.004](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 37075
[07:40:23.231](0.227s) ok 58 - IP address in a dNSName
[07:40:23.231](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 37104
[07:40:23.455](0.224s) ok 60 - host name matching with X.509 Subject Alternative Names 1
[07:40:23.455](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr
[07:40:23.467](0.012s) ok 62 - host name matching with X.509 Subject Alternative Names 2
[07:40:23.467](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr
[07:40:23.480](0.013s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard
[07:40:23.481](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr
[07:40:23.491](0.011s) ok 66 - host name not matching with X.509 Subject Alternative Names
[07:40:23.492](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches
[07:40:23.503](0.012s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard
[07:40:23.504](0.001s) ok 69 - host name not matching with X.509 Subject Alternative Names wildcard: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 37136
[07:40:23.733](0.229s) ok 70 - host name matching with a single X.509 Subject Alternative Name
[07:40:23.733](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr
[07:40:23.745](0.012s) ok 72 - host name not matching with a single X.509 Subject Alternative Name
[07:40:23.745](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches
[07:40:23.757](0.012s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard
[07:40:23.758](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 37166
[07:40:23.993](0.235s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1)
[07:40:23.993](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr
[07:40:24.007](0.013s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1)
[07:40:24.007](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr
[07:40:24.018](0.011s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1)
[07:40:24.019](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches
[07:40:24.033](0.014s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2)
[07:40:24.033](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr
[07:40:24.048](0.015s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2)
[07:40:24.048](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr
[07:40:24.063](0.015s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2)
[07:40:24.064](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr
[07:40:24.076](0.013s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2)
[07:40:24.076](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches
[07:40:24.090](0.014s) ok 90 - IPv6 host with CIDR mask does not match
[07:40:24.090](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 37216
[07:40:24.323](0.233s) ok 92 - certificate with both a CN and SANs 1
[07:40:24.323](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr
[07:40:24.336](0.013s) ok 94 - certificate with both a CN and SANs 2
[07:40:24.337](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr
[07:40:24.348](0.012s) ok 96 - certificate with both a CN and SANs ignores CN
[07:40:24.349](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 37265
[07:40:24.576](0.227s) ok 98 - certificate with both a CN and IP SANs matches CN
[07:40:24.576](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr
[07:40:24.588](0.013s) ok 100 - certificate with both a CN and IP SANs matches SAN 1
[07:40:24.589](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr
[07:40:24.601](0.013s) ok 102 - certificate with both a CN and IP SANs matches SAN 2
[07:40:24.602](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 37328
[07:40:24.829](0.227s) ok 104 - certificate with both an IP CN and IP SANs 1
[07:40:24.829](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr
[07:40:24.841](0.012s) ok 106 - certificate with both an IP CN and IP SANs 2
[07:40:24.841](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr
[07:40:24.850](0.009s) ok 108 - certificate with both an IP CN and IP SANs ignores CN
[07:40:24.850](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 37362
[07:40:25.076](0.226s) ok 110 - certificate with both an IP CN and DNS SANs matches CN
[07:40:25.077](0.001s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr
[07:40:25.094](0.017s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1
[07:40:25.094](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr
[07:40:25.108](0.014s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2
[07:40:25.108](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 37409
[07:40:25.335](0.227s) ok 116 - server certificate without CN or SANs sslmode=verify-ca
[07:40:25.335](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr
[07:40:25.345](0.009s) ok 118 - server certificate without CN or SANs sslmode=verify-full
[07:40:25.345](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 37447
[07:40:25.569](0.224s) ok 120 - sslrootcert=system does not connect with private CA
[07:40:25.569](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches
[07:40:25.574](0.004s) ok 122 - sslrootcert=system only accepts sslmode=verify-full
[07:40:25.574](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches
[07:40:25.589](0.015s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE
[07:40:25.589](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr
[07:40:25.598](0.010s) ok 126 - sslrootcert=system defaults to sslmode=verify-full
[07:40:25.599](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 37484
[07:40:25.828](0.229s) ok 128 - connects without client-side CRL
[07:40:25.828](0.000s) ok 129 - connects without client-side CRL: no stderr
[07:40:25.838](0.010s) ok 130 - does not connect with client-side CRL file
[07:40:25.838](0.000s) ok 131 - does not connect with client-side CRL file: matches
[07:40:25.848](0.010s) ok 132 - does not connect with client-side CRL directory
[07:40:25.848](0.000s) ok 133 - does not connect with client-side CRL directory: matches
# Running: psql -X -A -F , -P null=_null_ -d sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
[07:40:25.859](0.011s) ok 134 - pg_stat_ssl view without client certificate: exit code 0
[07:40:25.860](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr
[07:40:25.860](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches
[07:40:25.871](0.011s) ok 137 - connection success with correct range of TLS protocol versions
[07:40:25.871](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr
[07:40:25.875](0.004s) ok 139 - connection failure with incorrect range of TLS protocol versions
[07:40:25.875](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches
[07:40:25.881](0.005s) ok 141 - connection failure with an incorrect SSL protocol minimum bound
[07:40:25.881](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches
[07:40:25.886](0.004s) ok 143 - connection failure with an incorrect SSL protocol maximum bound
[07:40:25.886](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches
[07:40:25.886](0.000s) # running server tests
[07:40:25.896](0.010s) ok 145 - certificate authorization fails without client cert
[07:40:25.896](0.000s) ok 146 - certificate authorization fails without client cert: matches
[07:40:25.913](0.017s) ok 147 - certificate authorization succeeds with correct client cert in PEM format
[07:40:25.913](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr
[07:40:25.927](0.014s) ok 149 - certificate authorization succeeds with correct client cert in DER format
[07:40:25.928](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr
[07:40:25.942](0.014s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format
[07:40:25.942](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr
[07:40:25.955](0.013s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format
[07:40:25.955](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr
[07:40:25.969](0.013s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require
[07:40:25.969](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr
[07:40:25.982](0.013s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow
[07:40:25.982](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr
[07:40:25.993](0.010s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable
[07:40:25.993](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches
[07:40:26.001](0.008s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format
[07:40:26.001](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches
[07:40:26.017](0.016s) ok 163 - certificate authorization succeeds with DN mapping
[07:40:26.017](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr
[07:40:26.017](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches
[07:40:26.034](0.017s) ok 166 - certificate authorization succeeds with DN regex mapping
[07:40:26.034](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr
[07:40:26.055](0.020s) ok 168 - certificate authorization succeeds with CN mapping
[07:40:26.055](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr
[07:40:26.055](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches
[07:40:26.056](0.000s) not ok 171 # TODO & SKIP Need Pty support
[07:40:26.056](0.000s) not ok 172 # TODO & SKIP Need Pty support
[07:40:26.056](0.000s) not ok 173 # TODO & SKIP Need Pty support
[07:40:26.056](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_TJQr/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
[07:40:26.084](0.028s) ok 175 - pg_stat_ssl with client certificate: exit code 0
[07:40:26.085](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr
[07:40:26.085](0.000s) ok 177 - pg_stat_ssl with client certificate: matches
[07:40:26.092](0.007s) ok 178 - certificate authorization fails because of file permissions
[07:40:26.092](0.000s) ok 179 - certificate authorization fails because of file permissions: matches
[07:40:26.103](0.011s) ok 180 - certificate authorization fails with client cert belonging to another user
[07:40:26.103](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches
[07:40:26.114](0.011s) ok 182 - certificate authorization fails with revoked client cert
[07:40:26.114](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches
[07:40:26.115](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match
[07:40:26.130](0.016s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name
[07:40:26.130](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr
[07:40:26.131](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches
[07:40:26.142](0.011s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name
[07:40:26.142](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches
[07:40:26.142](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match
[07:40:26.154](0.012s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name
[07:40:26.154](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr
[07:40:26.155](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 37596
[07:40:26.374](0.220s) ok 194 - intermediate client certificate is provided by client
[07:40:26.375](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr
[07:40:26.385](0.011s) ok 196 - intermediate client certificate is missing
[07:40:26.385](0.000s) ok 197 - intermediate client certificate is missing: matches
[07:40:26.397](0.011s) ok 198 - logged client certificate Subjects are truncated if they're too long
[07:40:26.397](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 37629
[07:40:26.624](0.227s) ok 200 - intermediate client certificate is untrusted
[07:40:26.624](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 37649
[07:40:26.852](0.227s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory
[07:40:26.852](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches
[07:40:26.863](0.012s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory
[07:40:26.864](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 37671
[07:40:27.088](0.224s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1
[07:40:27.088](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.
[07:40:27.088](0.000s) #          got: '2'
#     expected: '0'
[07:40:27.088](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[07:40:27.089](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.
[07:40:27.089](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 63452 failed: SSL SYSCALL error: EOF detected'
#     expected: ''
[07:40:27.102](0.014s) ok 208 - connect without requesting ocsp response when sslocspstapling=0
[07:40:27.103](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 37686
[07:40:27.327](0.224s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1
[07:40:27.343](0.016s) ok 211 - connect without requesting ocsp response when sslocspstapling=0
[07:40:27.343](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 37698
[07:40:27.568](0.225s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1
[07:40:27.583](0.015s) ok 214 - connect without requesting ocsp response when sslocspstapling=0
[07:40:27.583](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 37710
[07:40:27.803](0.220s) ok 216 - failed with an expired ocsp response when sslocspstapling=1
[07:40:27.820](0.017s) ok 217 - connect without requesting ocsp response when sslocspstapling=0
[07:40:27.820](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 37722
[07:40:28.039](0.220s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1
[07:40:28.040](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.
[07:40:28.040](0.000s) #          got: '2'
#     expected: '0'
[07:40:28.040](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[07:40:28.040](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.
[07:40:28.040](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 63452 failed: SSL SYSCALL error: EOF detected'
#     expected: ''
[07:40:28.056](0.016s) ok 221 - connect without requesting ocsp response when sslocspstapling=0
[07:40:28.056](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 37734
[07:40:28.283](0.226s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1
[07:40:28.299](0.016s) ok 224 - connect without requesting ocsp response when sslocspstapling=0
[07:40:28.299](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 37746
[07:40:28.523](0.224s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1
[07:40:28.537](0.014s) ok 227 - connect without requesting ocsp response when sslocspstapling=0
[07:40:28.537](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 37758
[07:40:28.761](0.225s) ok 229 - failed with an expired ocsp response when sslocspstapling=1
[07:40:28.777](0.016s) ok 230 - connect without requesting ocsp response when sslocspstapling=0
[07:40:28.778](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 37770
[07:40:29.001](0.224s) ok 232 - failed with an expired ocsp response when sslocspstapling=1
[07:40:29.015](0.014s) ok 233 - connect without requesting ocsp response when sslocspstapling=0
[07:40:29.016](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-12 07:40:20.727 UTC [36774][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:20.727 UTC [36774][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:20.728 UTC [36777][startup] LOG:  database system was shut down at 2024-03-12 07:36:04 UTC
2024-03-12 07:40:20.730 UTC [36774][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:20.826 UTC [36796][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:20.826 UTC [36796][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-12 07:40:20.826 UTC [36796][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:20.830 UTC [36796][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-12 07:40:20.830 UTC [36796][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-12 07:40:20.838 UTC [36802][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:20.838 UTC [36802][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-12 07:40:20.838 UTC [36802][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:20.839 UTC [36802][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-12 07:40:20.839 UTC [36802][client backend] [001_ssltests.pl][1/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-12 07:40:20.839 UTC [36802][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-12 07:40:20.881 UTC [36809][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:20.882 UTC [36809][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-12 07:40:20.882 UTC [36809][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:20.882 UTC [36809][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-12 07:40:20.883 UTC [36809][client backend] [001_ssltests.pl][2/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-12 07:40:20.883 UTC [36809][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-12 07:40:20.928 UTC [36815][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:20.928 UTC [36815][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-12 07:40:20.928 UTC [36815][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:20.929 UTC [36815][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-12 07:40:20.929 UTC [36815][client backend] [001_ssltests.pl][3/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-12 07:40:20.929 UTC [36815][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-12 07:40:20.971 UTC [36830][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:20.971 UTC [36830][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-12 07:40:20.971 UTC [36830][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:20.972 UTC [36830][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-12 07:40:20.972 UTC [36830][client backend] [001_ssltests.pl][4/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-12 07:40:20.972 UTC [36830][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-12 07:40:21.017 UTC [36836][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:21.018 UTC [36836][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-12 07:40:21.018 UTC [36836][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:21.018 UTC [36836][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-12 07:40:21.018 UTC [36836][client backend] [001_ssltests.pl][5/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-12 07:40:21.037 UTC [36836][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.019 user=postgres database=postgres host=[local]
2024-03-12 07:40:21.063 UTC [36840][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:21.063 UTC [36840][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-12 07:40:21.063 UTC [36840][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:21.064 UTC [36840][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-12 07:40:21.064 UTC [36840][client backend] [001_ssltests.pl][6/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-12 07:40:21.087 UTC [36840][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=postgres database=postgres host=[local]
2024-03-12 07:40:21.108 UTC [36843][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:21.108 UTC [36843][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-12 07:40:21.108 UTC [36843][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:21.109 UTC [36843][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-12 07:40:21.109 UTC [36843][client backend] [001_ssltests.pl][7/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-12 07:40:21.125 UTC [36843][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.016 user=postgres database=postgres host=[local]
2024-03-12 07:40:21.151 UTC [36847][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:21.151 UTC [36847][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-12 07:40:21.151 UTC [36847][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:21.152 UTC [36847][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-12 07:40:21.152 UTC [36847][client backend] [001_ssltests.pl][8/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-12 07:40:21.175 UTC [36847][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=postgres database=postgres host=[local]
2024-03-12 07:40:21.196 UTC [36850][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:21.196 UTC [36850][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-12 07:40:21.196 UTC [36850][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:21.197 UTC [36850][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-12 07:40:21.197 UTC [36850][client backend] [001_ssltests.pl][9/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-12 07:40:21.212 UTC [36850][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=postgres database=postgres host=[local]
2024-03-12 07:40:21.241 UTC [36857][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-12 07:40:21.241 UTC [36857][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-12 07:40:21.241 UTC [36857][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-12 07:40:21.242 UTC [36857][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-12 07:40:21.242 UTC [36857][client backend] [001_ssltests.pl][10/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-12 07:40:21.265 UTC [36857][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=postgres database=postgres host=[local]
2024-03-12 07:40:21.287 UTC [36774][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:21.287 UTC [36774][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:21.288 UTC [36774][postmaster] LOG:  background worker "logical replication launcher" (PID 36780) exited with exit code 1
2024-03-12 07:40:21.288 UTC [36775][checkpointer] LOG:  shutting down
2024-03-12 07:40:21.288 UTC [36775][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:21.561 UTC [36775][checkpointer] LOG:  checkpoint complete: wrote 5614 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.263 s, sync=0.001 s, total=0.273 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45190 kB, estimate=45190 kB; lsn=0/413E658, redo lsn=0/413E658
2024-03-12 07:40:21.565 UTC [36774][postmaster] LOG:  database system is shut down
2024-03-12 07:40:21.601 UTC [36885][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:21.602 UTC [36885][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:21.602 UTC [36885][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:21.603 UTC [36889][startup] LOG:  database system was shut down at 2024-03-12 07:40:21 UTC
2024-03-12 07:40:21.605 UTC [36885][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:21.696 UTC [36885][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:21.696 UTC [36885][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:21.698 UTC [36885][postmaster] LOG:  background worker "logical replication launcher" (PID 36892) exited with exit code 1
2024-03-12 07:40:21.698 UTC [36887][checkpointer] LOG:  shutting down
2024-03-12 07:40:21.698 UTC [36887][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:21.698 UTC [36887][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/413E6D0, redo lsn=0/413E6D0
2024-03-12 07:40:21.699 UTC [36885][postmaster] LOG:  database system is shut down
2024-03-12 07:40:21.810 UTC [36904][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-12 07:40:21.810 UTC [36904][postmaster] LOG:  database system is shut down
2024-03-12 07:40:21.918 UTC [36914][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:21.918 UTC [36914][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:21.918 UTC [36914][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:21.919 UTC [36919][startup] LOG:  database system was shut down at 2024-03-12 07:40:21 UTC
2024-03-12 07:40:21.922 UTC [36914][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:22.010 UTC [36914][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:22.010 UTC [36914][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:22.011 UTC [36914][postmaster] LOG:  background worker "logical replication launcher" (PID 36922) exited with exit code 1
2024-03-12 07:40:22.012 UTC [36917][checkpointer] LOG:  shutting down
2024-03-12 07:40:22.012 UTC [36917][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:22.012 UTC [36917][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/413E748, redo lsn=0/413E748
2024-03-12 07:40:22.014 UTC [36914][postmaster] LOG:  database system is shut down
2024-03-12 07:40:22.121 UTC [36933][postmaster] FATAL:  could not set SSL protocol version range
2024-03-12 07:40:22.121 UTC [36933][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-12 07:40:22.121 UTC [36933][postmaster] LOG:  database system is shut down
2024-03-12 07:40:22.227 UTC [36948][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:22.227 UTC [36948][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:22.227 UTC [36948][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:22.228 UTC [36952][startup] LOG:  database system was shut down at 2024-03-12 07:40:22 UTC
2024-03-12 07:40:22.231 UTC [36948][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:22.324 UTC [36948][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:22.324 UTC [36948][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:22.325 UTC [36948][postmaster] LOG:  background worker "logical replication launcher" (PID 36955) exited with exit code 1
2024-03-12 07:40:22.325 UTC [36950][checkpointer] LOG:  shutting down
2024-03-12 07:40:22.325 UTC [36950][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:22.326 UTC [36950][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/413E7C0, redo lsn=0/413E7C0
2024-03-12 07:40:22.327 UTC [36948][postmaster] LOG:  database system is shut down
2024-03-12 07:40:22.441 UTC [36967][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:22.441 UTC [36967][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:22.441 UTC [36967][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:22.442 UTC [36970][startup] LOG:  database system was shut down at 2024-03-12 07:40:22 UTC
2024-03-12 07:40:22.445 UTC [36967][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:22.536 UTC [36982][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39558
2024-03-12 07:40:22.536 UTC [36982][client backend] [[unknown]][0/1:0] FATAL:  no pg_hba.conf entry for host "127.0.0.1", user "ssltestuser", database "trustdb", no encryption
2024-03-12 07:40:22.536 UTC [36982][client backend] [[unknown]][0/1:0] DETAIL:  Client IP address resolved to "localhost", forward lookup not checked.
2024-03-12 07:40:22.542 UTC [36984][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39559
2024-03-12 07:40:22.546 UTC [36984][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-12 07:40:22.546 UTC [36984][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:22.550 UTC [36984][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid sslmode=require$$
2024-03-12 07:40:22.550 UTC [36984][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=39559
2024-03-12 07:40:22.558 UTC [36988][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39561
2024-03-12 07:40:22.559 UTC [36988][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-12 07:40:22.566 UTC [36992][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39563
2024-03-12 07:40:22.567 UTC [36992][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-12 07:40:22.574 UTC [36995][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39564
2024-03-12 07:40:22.577 UTC [36995][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-12 07:40:22.584 UTC [36998][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39566
2024-03-12 07:40:22.587 UTC [36998][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-12 07:40:22.594 UTC [37002][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39568
2024-03-12 07:40:22.597 UTC [37002][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-12 07:40:22.603 UTC [37004][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39569
2024-03-12 07:40:22.607 UTC [37004][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-12 07:40:22.612 UTC [37008][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39571
2024-03-12 07:40:22.616 UTC [37008][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-12 07:40:22.616 UTC [37008][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:22.617 UTC [37008][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require$$
2024-03-12 07:40:22.617 UTC [37008][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=39571
2024-03-12 07:40:22.624 UTC [37012][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39573
2024-03-12 07:40:22.628 UTC [37012][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-12 07:40:22.628 UTC [37012][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:22.629 UTC [37012][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$
2024-03-12 07:40:22.629 UTC [37012][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=39573
2024-03-12 07:40:22.636 UTC [37016][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39575
2024-03-12 07:40:22.640 UTC [37016][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-12 07:40:22.640 UTC [37016][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:22.640 UTC [37016][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-full$$
2024-03-12 07:40:22.641 UTC [37016][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=39575
2024-03-12 07:40:22.648 UTC [37019][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39576
2024-03-12 07:40:22.652 UTC [37019][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-12 07:40:22.652 UTC [37019][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:22.652 UTC [37019][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-1.crt sslmode=verify-ca$$
2024-03-12 07:40:22.653 UTC [37019][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=39576
2024-03-12 07:40:22.659 UTC [37023][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39577
2024-03-12 07:40:22.663 UTC [37023][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-12 07:40:22.663 UTC [37023][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:22.664 UTC [37023][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-2.crt sslmode=verify-ca$$
2024-03-12 07:40:22.664 UTC [37023][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=39577
2024-03-12 07:40:22.670 UTC [37025][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39578
2024-03-12 07:40:22.674 UTC [37025][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-12 07:40:22.674 UTC [37025][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:22.674 UTC [37025][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=disable$$
2024-03-12 07:40:22.675 UTC [37025][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=39578
2024-03-12 07:40:22.680 UTC [37027][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39579
2024-03-12 07:40:22.685 UTC [37027][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-12 07:40:22.685 UTC [37027][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:22.685 UTC [37027][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=allow$$
2024-03-12 07:40:22.686 UTC [37027][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=39579
2024-03-12 07:40:22.691 UTC [37029][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39580
2024-03-12 07:40:22.695 UTC [37029][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-12 07:40:22.695 UTC [37029][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:22.695 UTC [37029][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=39580
2024-03-12 07:40:22.701 UTC [37031][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39581
2024-03-12 07:40:22.705 UTC [37031][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-12 07:40:22.705 UTC [37031][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:22.705 UTC [37031][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=invalid$$
2024-03-12 07:40:22.706 UTC [37031][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=39581
2024-03-12 07:40:22.711 UTC [37033][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39582
2024-03-12 07:40:22.714 UTC [37033][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-12 07:40:22.720 UTC [37035][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39583
2024-03-12 07:40:22.724 UTC [37035][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-12 07:40:22.729 UTC [37037][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39584
2024-03-12 07:40:22.733 UTC [37037][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-12 07:40:22.733 UTC [37037][client backend] [[unknown]][11/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:22.734 UTC [37037][client backend] [001_ssltests.pl][11/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=ssl/root+server.crl$$
2024-03-12 07:40:22.734 UTC [37037][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=39584
2024-03-12 07:40:22.739 UTC [37039][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39585
2024-03-12 07:40:22.743 UTC [37039][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-12 07:40:22.743 UTC [37039][client backend] [[unknown]][12/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:22.744 UTC [37039][client backend] [001_ssltests.pl][12/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrldir=ssl/root+server-crldir$$
2024-03-12 07:40:22.744 UTC [37039][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=39585
2024-03-12 07:40:22.749 UTC [37042][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39586
2024-03-12 07:40:22.753 UTC [37042][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-12 07:40:22.753 UTC [37042][client backend] [[unknown]][13/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:22.754 UTC [37042][client backend] [001_ssltests.pl][13/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=require host=wronghost.test$$
2024-03-12 07:40:22.754 UTC [37042][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=39586
2024-03-12 07:40:22.760 UTC [37050][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39587
2024-03-12 07:40:22.764 UTC [37050][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-12 07:40:22.764 UTC [37050][client backend] [[unknown]][14/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:22.764 UTC [37050][client backend] [001_ssltests.pl][14/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=wronghost.test$$
2024-03-12 07:40:22.765 UTC [37050][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=39587
2024-03-12 07:40:22.770 UTC [37052][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39588
2024-03-12 07:40:22.776 UTC [36967][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:22.776 UTC [36967][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:22.777 UTC [36967][postmaster] LOG:  background worker "logical replication launcher" (PID 36973) exited with exit code 1
2024-03-12 07:40:22.777 UTC [36968][checkpointer] LOG:  shutting down
2024-03-12 07:40:22.777 UTC [36968][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:22.777 UTC [36968][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/413E838, redo lsn=0/413E838
2024-03-12 07:40:22.778 UTC [36967][postmaster] LOG:  database system is shut down
2024-03-12 07:40:22.887 UTC [37059][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:22.887 UTC [37059][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:22.887 UTC [37059][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:22.888 UTC [37062][startup] LOG:  database system was shut down at 2024-03-12 07:40:22 UTC
2024-03-12 07:40:22.890 UTC [37059][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:22.983 UTC [37069][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39590
2024-03-12 07:40:22.987 UTC [37069][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-12 07:40:22.987 UTC [37069][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:22.991 UTC [37069][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-12 07:40:22.992 UTC [37069][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=39590
2024-03-12 07:40:22.998 UTC [37071][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39591
2024-03-12 07:40:23.005 UTC [37059][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:23.005 UTC [37059][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:23.006 UTC [37059][postmaster] LOG:  background worker "logical replication launcher" (PID 37065) exited with exit code 1
2024-03-12 07:40:23.007 UTC [37060][checkpointer] LOG:  shutting down
2024-03-12 07:40:23.007 UTC [37060][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:23.007 UTC [37060][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/413E8B0, redo lsn=0/413E8B0
2024-03-12 07:40:23.008 UTC [37059][postmaster] LOG:  database system is shut down
2024-03-12 07:40:23.122 UTC [37075][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:23.122 UTC [37075][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:23.122 UTC [37075][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:23.123 UTC [37078][startup] LOG:  database system was shut down at 2024-03-12 07:40:23 UTC
2024-03-12 07:40:23.126 UTC [37075][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:23.219 UTC [37098][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39592
2024-03-12 07:40:23.224 UTC [37098][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-12 07:40:23.224 UTC [37098][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:23.229 UTC [37098][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-12 07:40:23.229 UTC [37098][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=39592
2024-03-12 07:40:23.233 UTC [37075][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:23.233 UTC [37075][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:23.234 UTC [37075][postmaster] LOG:  background worker "logical replication launcher" (PID 37081) exited with exit code 1
2024-03-12 07:40:23.234 UTC [37076][checkpointer] LOG:  shutting down
2024-03-12 07:40:23.234 UTC [37076][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:23.235 UTC [37076][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/413E928, redo lsn=0/413E928
2024-03-12 07:40:23.236 UTC [37075][postmaster] LOG:  database system is shut down
2024-03-12 07:40:23.350 UTC [37104][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:23.350 UTC [37104][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:23.350 UTC [37104][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:23.351 UTC [37108][startup] LOG:  database system was shut down at 2024-03-12 07:40:23 UTC
2024-03-12 07:40:23.354 UTC [37104][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:23.445 UTC [37117][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39593
2024-03-12 07:40:23.450 UTC [37117][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-12 07:40:23.450 UTC [37117][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:23.454 UTC [37117][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$
2024-03-12 07:40:23.454 UTC [37117][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=39593
2024-03-12 07:40:23.461 UTC [37120][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39594
2024-03-12 07:40:23.465 UTC [37120][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-12 07:40:23.465 UTC [37120][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:23.466 UTC [37120][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$
2024-03-12 07:40:23.466 UTC [37120][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=39594
2024-03-12 07:40:23.473 UTC [37123][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39595
2024-03-12 07:40:23.478 UTC [37123][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-12 07:40:23.478 UTC [37123][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:23.479 UTC [37123][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=foo.wildcard.pg-ssltest.test$$
2024-03-12 07:40:23.479 UTC [37123][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=39595
2024-03-12 07:40:23.486 UTC [37126][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39596
2024-03-12 07:40:23.498 UTC [37130][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39597
2024-03-12 07:40:23.506 UTC [37104][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:23.506 UTC [37104][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:23.508 UTC [37104][postmaster] LOG:  background worker "logical replication launcher" (PID 37111) exited with exit code 1
2024-03-12 07:40:23.508 UTC [37105][checkpointer] LOG:  shutting down
2024-03-12 07:40:23.508 UTC [37105][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:23.509 UTC [37105][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/413E9A0, redo lsn=0/413E9A0
2024-03-12 07:40:23.510 UTC [37104][postmaster] LOG:  database system is shut down
2024-03-12 07:40:23.624 UTC [37136][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:23.624 UTC [37136][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:23.624 UTC [37136][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:23.626 UTC [37141][startup] LOG:  database system was shut down at 2024-03-12 07:40:23 UTC
2024-03-12 07:40:23.629 UTC [37136][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:23.721 UTC [37151][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39598
2024-03-12 07:40:23.726 UTC [37151][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-12 07:40:23.726 UTC [37151][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:23.730 UTC [37151][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=single.alt-name.pg-ssltest.test$$
2024-03-12 07:40:23.731 UTC [37151][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=39598
2024-03-12 07:40:23.740 UTC [37153][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39599
2024-03-12 07:40:23.751 UTC [37155][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39600
2024-03-12 07:40:23.763 UTC [37136][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:23.763 UTC [37136][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:23.765 UTC [37136][postmaster] LOG:  background worker "logical replication launcher" (PID 37144) exited with exit code 1
2024-03-12 07:40:23.766 UTC [37138][checkpointer] LOG:  shutting down
2024-03-12 07:40:23.766 UTC [37138][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:23.766 UTC [37138][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/413EA18, redo lsn=0/413EA18
2024-03-12 07:40:23.768 UTC [37136][postmaster] LOG:  database system is shut down
2024-03-12 07:40:23.880 UTC [37166][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:23.880 UTC [37166][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:23.880 UTC [37166][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:23.882 UTC [37169][startup] LOG:  database system was shut down at 2024-03-12 07:40:23 UTC
2024-03-12 07:40:23.884 UTC [37166][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:23.982 UTC [37181][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39601
2024-03-12 07:40:23.987 UTC [37181][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-12 07:40:23.987 UTC [37181][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:23.991 UTC [37181][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-12 07:40:23.992 UTC [37181][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=39601
2024-03-12 07:40:23.999 UTC [37184][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39602
2024-03-12 07:40:24.004 UTC [37184][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-12 07:40:24.004 UTC [37184][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:24.005 UTC [37184][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.000.002.001$$
2024-03-12 07:40:24.005 UTC [37184][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=39602
2024-03-12 07:40:24.013 UTC [37186][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39603
2024-03-12 07:40:24.024 UTC [37188][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39604
2024-03-12 07:40:24.030 UTC [37188][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-12 07:40:24.030 UTC [37188][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:24.030 UTC [37188][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:DB8::1$$
2024-03-12 07:40:24.031 UTC [37188][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=39604
2024-03-12 07:40:24.039 UTC [37193][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39606
2024-03-12 07:40:24.045 UTC [37193][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-12 07:40:24.045 UTC [37193][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:24.046 UTC [37193][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8:0:0:0:0:0:1$$
2024-03-12 07:40:24.046 UTC [37193][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=39606
2024-03-12 07:40:24.055 UTC [37198][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39610
2024-03-12 07:40:24.060 UTC [37198][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-12 07:40:24.060 UTC [37198][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:24.061 UTC [37198][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::0.0.0.1$$
2024-03-12 07:40:24.061 UTC [37198][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=39610
2024-03-12 07:40:24.070 UTC [37201][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39611
2024-03-12 07:40:24.084 UTC [37203][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39612
2024-03-12 07:40:24.093 UTC [37166][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:24.093 UTC [37166][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:24.094 UTC [37166][postmaster] LOG:  background worker "logical replication launcher" (PID 37172) exited with exit code 1
2024-03-12 07:40:24.094 UTC [37167][checkpointer] LOG:  shutting down
2024-03-12 07:40:24.095 UTC [37167][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:24.095 UTC [37167][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/413EA90, redo lsn=0/413EA90
2024-03-12 07:40:24.096 UTC [37166][postmaster] LOG:  database system is shut down
2024-03-12 07:40:24.210 UTC [37216][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:24.210 UTC [37216][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:24.211 UTC [37216][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:24.212 UTC [37225][startup] LOG:  database system was shut down at 2024-03-12 07:40:24 UTC
2024-03-12 07:40:24.216 UTC [37216][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:24.313 UTC [37238][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39613
2024-03-12 07:40:24.317 UTC [37238][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-12 07:40:24.317 UTC [37238][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:24.321 UTC [37238][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$
2024-03-12 07:40:24.322 UTC [37238][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=39613
2024-03-12 07:40:24.329 UTC [37240][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39614
2024-03-12 07:40:24.334 UTC [37240][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-12 07:40:24.334 UTC [37240][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:24.335 UTC [37240][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$
2024-03-12 07:40:24.335 UTC [37240][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=39614
2024-03-12 07:40:24.342 UTC [37244][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39615
2024-03-12 07:40:24.355 UTC [37216][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:24.355 UTC [37216][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:24.356 UTC [37216][postmaster] LOG:  background worker "logical replication launcher" (PID 37230) exited with exit code 1
2024-03-12 07:40:24.356 UTC [37221][checkpointer] LOG:  shutting down
2024-03-12 07:40:24.356 UTC [37221][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:24.357 UTC [37221][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/413EB08, redo lsn=0/413EB08
2024-03-12 07:40:24.358 UTC [37216][postmaster] LOG:  database system is shut down
2024-03-12 07:40:24.470 UTC [37265][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:24.470 UTC [37265][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:24.470 UTC [37265][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:24.472 UTC [37272][startup] LOG:  database system was shut down at 2024-03-12 07:40:24 UTC
2024-03-12 07:40:24.475 UTC [37265][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:24.566 UTC [37294][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39628
2024-03-12 07:40:24.570 UTC [37294][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-12 07:40:24.570 UTC [37294][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:24.574 UTC [37294][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$
2024-03-12 07:40:24.575 UTC [37294][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=39628
2024-03-12 07:40:24.582 UTC [37296][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39629
2024-03-12 07:40:24.586 UTC [37296][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-12 07:40:24.586 UTC [37296][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:24.587 UTC [37296][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-12 07:40:24.587 UTC [37296][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=39629
2024-03-12 07:40:24.595 UTC [37300][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39630
2024-03-12 07:40:24.599 UTC [37300][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-12 07:40:24.599 UTC [37300][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:24.600 UTC [37300][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$
2024-03-12 07:40:24.600 UTC [37300][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=39630
2024-03-12 07:40:24.604 UTC [37265][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:24.604 UTC [37265][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:24.606 UTC [37265][postmaster] LOG:  background worker "logical replication launcher" (PID 37276) exited with exit code 1
2024-03-12 07:40:24.606 UTC [37270][checkpointer] LOG:  shutting down
2024-03-12 07:40:24.606 UTC [37270][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:24.607 UTC [37270][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/413EB80, redo lsn=0/413EB80
2024-03-12 07:40:24.608 UTC [37265][postmaster] LOG:  database system is shut down
2024-03-12 07:40:24.716 UTC [37328][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:24.716 UTC [37328][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:24.716 UTC [37328][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:24.717 UTC [37335][startup] LOG:  database system was shut down at 2024-03-12 07:40:24 UTC
2024-03-12 07:40:24.719 UTC [37328][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:24.818 UTC [37341][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39643
2024-03-12 07:40:24.823 UTC [37341][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-12 07:40:24.823 UTC [37341][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:24.827 UTC [37341][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.2$$
2024-03-12 07:40:24.828 UTC [37341][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=39643
2024-03-12 07:40:24.834 UTC [37344][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39644
2024-03-12 07:40:24.838 UTC [37344][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-12 07:40:24.838 UTC [37344][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:24.839 UTC [37344][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$
2024-03-12 07:40:24.840 UTC [37344][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=39644
2024-03-12 07:40:24.846 UTC [37352][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39645
2024-03-12 07:40:24.852 UTC [37328][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:24.852 UTC [37328][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:24.853 UTC [37328][postmaster] LOG:  background worker "logical replication launcher" (PID 37338) exited with exit code 1
2024-03-12 07:40:24.853 UTC [37333][checkpointer] LOG:  shutting down
2024-03-12 07:40:24.853 UTC [37333][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:24.854 UTC [37333][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/413EBF8, redo lsn=0/413EBF8
2024-03-12 07:40:24.855 UTC [37328][postmaster] LOG:  database system is shut down
2024-03-12 07:40:24.966 UTC [37362][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:24.966 UTC [37362][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:24.966 UTC [37362][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:24.968 UTC [37366][startup] LOG:  database system was shut down at 2024-03-12 07:40:24 UTC
2024-03-12 07:40:24.972 UTC [37362][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:25.064 UTC [37373][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39649
2024-03-12 07:40:25.069 UTC [37373][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-12 07:40:25.069 UTC [37373][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:25.074 UTC [37373][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-12 07:40:25.074 UTC [37373][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=39649
2024-03-12 07:40:25.086 UTC [37382][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39650
2024-03-12 07:40:25.091 UTC [37382][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-12 07:40:25.091 UTC [37382][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:25.092 UTC [37382][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$
2024-03-12 07:40:25.093 UTC [37382][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=39650
2024-03-12 07:40:25.100 UTC [37384][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39651
2024-03-12 07:40:25.105 UTC [37384][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-12 07:40:25.105 UTC [37384][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:25.106 UTC [37384][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$
2024-03-12 07:40:25.106 UTC [37384][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=39651
2024-03-12 07:40:25.110 UTC [37362][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:25.110 UTC [37362][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:25.111 UTC [37362][postmaster] LOG:  background worker "logical replication launcher" (PID 37369) exited with exit code 1
2024-03-12 07:40:25.111 UTC [37364][checkpointer] LOG:  shutting down
2024-03-12 07:40:25.112 UTC [37364][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:25.112 UTC [37364][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/413EC70, redo lsn=0/413EC70
2024-03-12 07:40:25.113 UTC [37362][postmaster] LOG:  database system is shut down
2024-03-12 07:40:25.226 UTC [37409][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:25.226 UTC [37409][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:25.226 UTC [37409][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:25.228 UTC [37412][startup] LOG:  database system was shut down at 2024-03-12 07:40:25 UTC
2024-03-12 07:40:25.230 UTC [37409][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:25.325 UTC [37429][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39659
2024-03-12 07:40:25.330 UTC [37429][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-12 07:40:25.330 UTC [37429][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:25.334 UTC [37429][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=common-name.pg-ssltest.test$$
2024-03-12 07:40:25.334 UTC [37429][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=39659
2024-03-12 07:40:25.340 UTC [37433][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39660
2024-03-12 07:40:25.346 UTC [37409][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:25.346 UTC [37409][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:25.347 UTC [37409][postmaster] LOG:  background worker "logical replication launcher" (PID 37415) exited with exit code 1
2024-03-12 07:40:25.347 UTC [37410][checkpointer] LOG:  shutting down
2024-03-12 07:40:25.347 UTC [37410][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:25.348 UTC [37410][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/413ECE8, redo lsn=0/413ECE8
2024-03-12 07:40:25.349 UTC [37409][postmaster] LOG:  database system is shut down
2024-03-12 07:40:25.463 UTC [37447][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:25.463 UTC [37447][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:25.463 UTC [37447][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:25.465 UTC [37450][startup] LOG:  database system was shut down at 2024-03-12 07:40:25 UTC
2024-03-12 07:40:25.468 UTC [37447][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:25.565 UTC [37465][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39664
2024-03-12 07:40:25.568 UTC [37465][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-12 07:40:25.578 UTC [37468][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39665
2024-03-12 07:40:25.583 UTC [37468][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-12 07:40:25.583 UTC [37468][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:25.587 UTC [37468][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=system hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$
2024-03-12 07:40:25.587 UTC [37468][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=39665
2024-03-12 07:40:25.593 UTC [37470][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39666
2024-03-12 07:40:25.601 UTC [37447][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:25.601 UTC [37447][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:25.602 UTC [37447][postmaster] LOG:  background worker "logical replication launcher" (PID 37453) exited with exit code 1
2024-03-12 07:40:25.602 UTC [37448][checkpointer] LOG:  shutting down
2024-03-12 07:40:25.602 UTC [37448][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:25.602 UTC [37448][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/413ED60, redo lsn=0/413ED60
2024-03-12 07:40:25.604 UTC [37447][postmaster] LOG:  database system is shut down
2024-03-12 07:40:25.715 UTC [37484][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:25.715 UTC [37484][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:25.715 UTC [37484][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:25.717 UTC [37487][startup] LOG:  database system was shut down at 2024-03-12 07:40:25 UTC
2024-03-12 07:40:25.721 UTC [37484][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:25.818 UTC [37502][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39670
2024-03-12 07:40:25.822 UTC [37502][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-12 07:40:25.822 UTC [37502][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:25.826 UTC [37502][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$
2024-03-12 07:40:25.827 UTC [37502][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=39670
2024-03-12 07:40:25.833 UTC [37504][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39671
2024-03-12 07:40:25.837 UTC [37504][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-12 07:40:25.843 UTC [37506][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39672
2024-03-12 07:40:25.847 UTC [37506][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-12 07:40:25.853 UTC [37508][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39673
2024-03-12 07:40:25.856 UTC [37508][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-12 07:40:25.856 UTC [37508][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:25.857 UTC [37508][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-12 07:40:25.859 UTC [37508][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=39673
2024-03-12 07:40:25.865 UTC [37510][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39674
2024-03-12 07:40:25.868 UTC [37510][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-12 07:40:25.868 UTC [37510][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.2, cipher=ECDHE-RSA-AES256-GCM-SHA384, bits=256)
2024-03-12 07:40:25.869 UTC [37510][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require ssl_min_protocol_version=TLSv1.2 ssl_max_protocol_version=TLSv1.2$$
2024-03-12 07:40:25.870 UTC [37510][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=39674
2024-03-12 07:40:25.890 UTC [37518][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39676
2024-03-12 07:40:25.895 UTC [37518][client backend] [[unknown]][3/1:0] FATAL:  connection requires a valid client certificate
2024-03-12 07:40:25.901 UTC [37521][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39677
2024-03-12 07:40:25.907 UTC [37521][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-12 07:40:25.907 UTC [37521][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:25.911 UTC [37521][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_TJQr/client.key$$
2024-03-12 07:40:25.912 UTC [37521][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=39677
2024-03-12 07:40:25.919 UTC [37523][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39678
2024-03-12 07:40:25.925 UTC [37523][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-12 07:40:25.925 UTC [37523][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:25.926 UTC [37523][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_TJQr/client-der.key$$
2024-03-12 07:40:25.926 UTC [37523][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=39678
2024-03-12 07:40:25.933 UTC [37526][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39679
2024-03-12 07:40:25.939 UTC [37526][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-12 07:40:25.939 UTC [37526][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:25.940 UTC [37526][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_TJQr/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$
2024-03-12 07:40:25.940 UTC [37526][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=39679
2024-03-12 07:40:25.947 UTC [37528][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39680
2024-03-12 07:40:25.953 UTC [37528][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-12 07:40:25.953 UTC [37528][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:25.954 UTC [37528][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_TJQr/client-encrypted-der.key sslpassword='dUmmyP^#+'$$
2024-03-12 07:40:25.954 UTC [37528][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=39680
2024-03-12 07:40:25.961 UTC [37530][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39681
2024-03-12 07:40:25.966 UTC [37530][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-12 07:40:25.966 UTC [37530][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:25.967 UTC [37530][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_TJQr/client.key$$
2024-03-12 07:40:25.968 UTC [37530][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=39681
2024-03-12 07:40:25.974 UTC [37532][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39682
2024-03-12 07:40:25.980 UTC [37532][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-12 07:40:25.980 UTC [37532][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:25.981 UTC [37532][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_TJQr/client.key$$
2024-03-12 07:40:25.981 UTC [37532][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=39682
2024-03-12 07:40:25.987 UTC [37535][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39683
2024-03-12 07:40:25.991 UTC [37535][client backend] [[unknown]][10/1:0] FATAL:  connection requires a valid client certificate
2024-03-12 07:40:25.998 UTC [37540][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39684
2024-03-12 07:40:26.000 UTC [37540][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-12 07:40:26.006 UTC [37545][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39685
2024-03-12 07:40:26.011 UTC [37545][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-12 07:40:26.011 UTC [37545][client backend] [[unknown]][11/1:0] LOG:  connection authorized: user=ssltestuser database=certdb_dn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:26.015 UTC [37545][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_TJQr/client-dn.key$$
2024-03-12 07:40:26.016 UTC [37545][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_dn host=localhost port=39685
2024-03-12 07:40:26.022 UTC [37547][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39686
2024-03-12 07:40:26.028 UTC [37547][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-12 07:40:26.028 UTC [37547][client backend] [[unknown]][12/1:0] LOG:  connection authorized: user=ssltestuser database=certdb_dn_re application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:26.032 UTC [37547][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_TJQr/client-dn.key$$
2024-03-12 07:40:26.033 UTC [37547][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_dn_re host=localhost port=39686
2024-03-12 07:40:26.042 UTC [37551][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39687
2024-03-12 07:40:26.049 UTC [37551][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-12 07:40:26.049 UTC [37551][client backend] [[unknown]][13/1:0] LOG:  connection authorized: user=ssltestuser database=certdb_cn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:26.053 UTC [37551][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_TJQr/client-dn.key$$
2024-03-12 07:40:26.053 UTC [37551][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=certdb_cn host=localhost port=39687
2024-03-12 07:40:26.076 UTC [37559][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39688
2024-03-12 07:40:26.081 UTC [37559][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-12 07:40:26.081 UTC [37559][client backend] [[unknown]][14/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:26.082 UTC [37559][client backend] [001_ssltests.pl][14/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-12 07:40:26.083 UTC [37559][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=39688
2024-03-12 07:40:26.090 UTC [37566][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39689
2024-03-12 07:40:26.091 UTC [37566][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-12 07:40:26.096 UTC [37570][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39691
2024-03-12 07:40:26.102 UTC [37570][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-12 07:40:26.102 UTC [37570][client backend] [[unknown]][15/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-12 07:40:26.102 UTC [37570][client backend] [[unknown]][15/1:0] FATAL:  certificate authentication failed for user "anotheruser"
2024-03-12 07:40:26.102 UTC [37570][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-12 07:40:26.108 UTC [37573][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39692
2024-03-12 07:40:26.113 UTC [37573][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-12 07:40:26.113 UTC [37573][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: certificate revoked.
	Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-12 07:40:26.119 UTC [37575][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39693
2024-03-12 07:40:26.125 UTC [37575][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-12 07:40:26.125 UTC [37575][client backend] [[unknown]][16/1:0] LOG:  connection authorized: user=ssltestuser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:26.129 UTC [37575][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_TJQr/client.key$$
2024-03-12 07:40:26.129 UTC [37575][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=verifydb host=localhost port=39693
2024-03-12 07:40:26.135 UTC [37577][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39694
2024-03-12 07:40:26.141 UTC [37577][client backend] [[unknown]][17/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-12 07:40:26.141 UTC [37577][client backend] [[unknown]][17/1:0] LOG:  certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch
2024-03-12 07:40:26.141 UTC [37577][client backend] [[unknown]][17/1:0] FATAL:  "trust" authentication failed for user "anotheruser"
2024-03-12 07:40:26.141 UTC [37577][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-12 07:40:26.147 UTC [37579][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39695
2024-03-12 07:40:26.152 UTC [37579][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-12 07:40:26.152 UTC [37579][client backend] [[unknown]][18/1:0] LOG:  connection authorized: user=yetanotheruser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:26.153 UTC [37579][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_TJQr/client.key$$
2024-03-12 07:40:26.153 UTC [37579][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=yetanotheruser database=verifydb host=localhost port=39695
2024-03-12 07:40:26.156 UTC [37484][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:26.156 UTC [37484][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:26.157 UTC [37484][postmaster] LOG:  background worker "logical replication launcher" (PID 37490) exited with exit code 1
2024-03-12 07:40:26.157 UTC [37485][checkpointer] LOG:  shutting down
2024-03-12 07:40:26.157 UTC [37485][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:26.157 UTC [37485][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/413EDD8, redo lsn=0/413EDD8
2024-03-12 07:40:26.158 UTC [37484][postmaster] LOG:  database system is shut down
2024-03-12 07:40:26.267 UTC [37596][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:26.267 UTC [37596][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:26.267 UTC [37596][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:26.269 UTC [37599][startup] LOG:  database system was shut down at 2024-03-12 07:40:26 UTC
2024-03-12 07:40:26.271 UTC [37596][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:26.363 UTC [37615][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39697
2024-03-12 07:40:26.369 UTC [37615][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-12 07:40:26.369 UTC [37615][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:26.373 UTC [37615][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_TJQr/client.key sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 host=localhost sslmode=require sslcert=ssl/client+client_ca.crt$$
2024-03-12 07:40:26.373 UTC [37615][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=39697
2024-03-12 07:40:26.379 UTC [37617][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39698
2024-03-12 07:40:26.384 UTC [37617][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-12 07:40:26.384 UTC [37617][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: unable to get local issuer certificate.
	Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921344, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-12 07:40:26.390 UTC [37619][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39699
2024-03-12 07:40:26.395 UTC [37619][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-12 07:40:26.395 UTC [37619][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: unable to get local issuer certificate.
	Failed certificate data (unverified): subject ".../CN=ssl-123456789012345678901234567890123456789012345678901234567890", serial number 2315702411956921348, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-12 07:40:26.398 UTC [37596][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:26.398 UTC [37596][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:26.400 UTC [37596][postmaster] LOG:  background worker "logical replication launcher" (PID 37602) exited with exit code 1
2024-03-12 07:40:26.400 UTC [37597][checkpointer] LOG:  shutting down
2024-03-12 07:40:26.400 UTC [37597][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:26.400 UTC [37597][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/413EE50, redo lsn=0/413EE50
2024-03-12 07:40:26.401 UTC [37596][postmaster] LOG:  database system is shut down
2024-03-12 07:40:26.515 UTC [37629][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:26.515 UTC [37629][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:26.515 UTC [37629][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:26.516 UTC [37632][startup] LOG:  database system was shut down at 2024-03-12 07:40:26 UTC
2024-03-12 07:40:26.519 UTC [37629][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:26.617 UTC [37640][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39701
2024-03-12 07:40:26.622 UTC [37640][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-12 07:40:26.622 UTC [37640][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 1: unable to get local issuer certificate.
	Failed certificate data (unverified): subject "/CN=Test CA for PostgreSQL SSL regression test client certs", serial number 2315702411956921089, issuer "/CN=Test root CA for PostgreSQL SSL regression test suite".
2024-03-12 07:40:26.626 UTC [37629][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:26.626 UTC [37629][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:26.627 UTC [37629][postmaster] LOG:  background worker "logical replication launcher" (PID 37635) exited with exit code 1
2024-03-12 07:40:26.628 UTC [37630][checkpointer] LOG:  shutting down
2024-03-12 07:40:26.628 UTC [37630][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:26.628 UTC [37630][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/413EEC8, redo lsn=0/413EEC8
2024-03-12 07:40:26.630 UTC [37629][postmaster] LOG:  database system is shut down
2024-03-12 07:40:26.743 UTC [37649][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:26.743 UTC [37649][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:26.743 UTC [37649][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:26.745 UTC [37652][startup] LOG:  database system was shut down at 2024-03-12 07:40:26 UTC
2024-03-12 07:40:26.747 UTC [37649][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:26.845 UTC [37660][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39703
2024-03-12 07:40:26.850 UTC [37660][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-12 07:40:26.850 UTC [37660][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: certificate revoked.
	Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-12 07:40:26.856 UTC [37662][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39704
2024-03-12 07:40:26.862 UTC [37662][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-12 07:40:26.862 UTC [37662][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: certificate revoked.
	Failed certificate data (unverified): subject "/CN=\xce\x9f\xce\xb4\xcf\x85\xcf\x83\xcf\x83\xce\xad\xce\xb1\xcf\x82", serial number 2315702411956921349, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-12 07:40:26.866 UTC [37649][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:26.866 UTC [37649][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:26.867 UTC [37649][postmaster] LOG:  background worker "logical replication launcher" (PID 37655) exited with exit code 1
2024-03-12 07:40:26.867 UTC [37650][checkpointer] LOG:  shutting down
2024-03-12 07:40:26.867 UTC [37650][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:26.867 UTC [37650][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/413EF40, redo lsn=0/413EF40
2024-03-12 07:40:26.869 UTC [37649][postmaster] LOG:  database system is shut down
2024-03-12 07:40:26.983 UTC [37671][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:26.983 UTC [37671][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:26.983 UTC [37671][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:26.984 UTC [37674][startup] LOG:  database system was shut down at 2024-03-12 07:40:26 UTC
2024-03-12 07:40:26.986 UTC [37671][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:27.084 UTC [37682][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39706
2024-03-12 07:40:27.086 UTC [37682][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-12 07:40:27.086 UTC [37682][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-12 07:40:27.093 UTC [37684][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39707
2024-03-12 07:40:27.097 UTC [37684][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-12 07:40:27.097 UTC [37684][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:27.101 UTC [37684][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-12 07:40:27.102 UTC [37684][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=39707
2024-03-12 07:40:27.104 UTC [37671][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:27.104 UTC [37671][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:27.105 UTC [37671][postmaster] LOG:  background worker "logical replication launcher" (PID 37677) exited with exit code 1
2024-03-12 07:40:27.105 UTC [37672][checkpointer] LOG:  shutting down
2024-03-12 07:40:27.105 UTC [37672][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:27.105 UTC [37672][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/413EFB8, redo lsn=0/413EFB8
2024-03-12 07:40:27.106 UTC [37671][postmaster] LOG:  database system is shut down
2024-03-12 07:40:27.223 UTC [37686][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:27.223 UTC [37686][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:27.223 UTC [37686][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:27.225 UTC [37689][startup] LOG:  database system was shut down at 2024-03-12 07:40:27 UTC
2024-03-12 07:40:27.229 UTC [37686][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:27.324 UTC [37694][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39708
2024-03-12 07:40:27.325 UTC [37694][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-12 07:40:27.325 UTC [37694][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-12 07:40:27.333 UTC [37696][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39709
2024-03-12 07:40:27.337 UTC [37696][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-12 07:40:27.337 UTC [37696][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:27.342 UTC [37696][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-12 07:40:27.342 UTC [37696][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=39709
2024-03-12 07:40:27.345 UTC [37686][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:27.345 UTC [37686][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:27.346 UTC [37686][postmaster] LOG:  background worker "logical replication launcher" (PID 37692) exited with exit code 1
2024-03-12 07:40:27.347 UTC [37687][checkpointer] LOG:  shutting down
2024-03-12 07:40:27.347 UTC [37687][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:27.347 UTC [37687][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/413F030, redo lsn=0/413F030
2024-03-12 07:40:27.349 UTC [37686][postmaster] LOG:  database system is shut down
2024-03-12 07:40:27.463 UTC [37698][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:27.464 UTC [37698][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:27.464 UTC [37698][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:27.466 UTC [37701][startup] LOG:  database system was shut down at 2024-03-12 07:40:27 UTC
2024-03-12 07:40:27.469 UTC [37698][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:27.565 UTC [37706][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39710
2024-03-12 07:40:27.566 UTC [37706][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-12 07:40:27.566 UTC [37706][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-12 07:40:27.573 UTC [37708][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39711
2024-03-12 07:40:27.577 UTC [37708][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-12 07:40:27.577 UTC [37708][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:27.581 UTC [37708][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-12 07:40:27.582 UTC [37708][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=39711
2024-03-12 07:40:27.585 UTC [37698][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:27.585 UTC [37698][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:27.585 UTC [37698][postmaster] LOG:  background worker "logical replication launcher" (PID 37704) exited with exit code 1
2024-03-12 07:40:27.586 UTC [37699][checkpointer] LOG:  shutting down
2024-03-12 07:40:27.586 UTC [37699][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:27.586 UTC [37699][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/413F0A8, redo lsn=0/413F0A8
2024-03-12 07:40:27.588 UTC [37698][postmaster] LOG:  database system is shut down
2024-03-12 07:40:27.701 UTC [37710][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:27.702 UTC [37710][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:27.702 UTC [37710][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:27.703 UTC [37713][startup] LOG:  database system was shut down at 2024-03-12 07:40:27 UTC
2024-03-12 07:40:27.705 UTC [37710][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:27.799 UTC [37718][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39712
2024-03-12 07:40:27.801 UTC [37718][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-12 07:40:27.801 UTC [37718][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-12 07:40:27.809 UTC [37720][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39713
2024-03-12 07:40:27.813 UTC [37720][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-12 07:40:27.813 UTC [37720][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:27.818 UTC [37720][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-12 07:40:27.819 UTC [37720][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=39713
2024-03-12 07:40:27.821 UTC [37710][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:27.821 UTC [37710][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:27.822 UTC [37710][postmaster] LOG:  background worker "logical replication launcher" (PID 37716) exited with exit code 1
2024-03-12 07:40:27.823 UTC [37711][checkpointer] LOG:  shutting down
2024-03-12 07:40:27.823 UTC [37711][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:27.823 UTC [37711][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/413F120, redo lsn=0/413F120
2024-03-12 07:40:27.825 UTC [37710][postmaster] LOG:  database system is shut down
2024-03-12 07:40:27.935 UTC [37722][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:27.935 UTC [37722][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:27.935 UTC [37722][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:27.937 UTC [37725][startup] LOG:  database system was shut down at 2024-03-12 07:40:27 UTC
2024-03-12 07:40:27.940 UTC [37722][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:28.036 UTC [37730][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39714
2024-03-12 07:40:28.037 UTC [37730][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-12 07:40:28.037 UTC [37730][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-12 07:40:28.046 UTC [37732][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39715
2024-03-12 07:40:28.050 UTC [37732][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-12 07:40:28.050 UTC [37732][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:28.054 UTC [37732][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-12 07:40:28.055 UTC [37732][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=39715
2024-03-12 07:40:28.058 UTC [37722][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:28.058 UTC [37722][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:28.059 UTC [37722][postmaster] LOG:  background worker "logical replication launcher" (PID 37728) exited with exit code 1
2024-03-12 07:40:28.060 UTC [37723][checkpointer] LOG:  shutting down
2024-03-12 07:40:28.060 UTC [37723][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:28.060 UTC [37723][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/413F198, redo lsn=0/413F198
2024-03-12 07:40:28.062 UTC [37722][postmaster] LOG:  database system is shut down
2024-03-12 07:40:28.176 UTC [37734][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:28.176 UTC [37734][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:28.176 UTC [37734][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:28.178 UTC [37737][startup] LOG:  database system was shut down at 2024-03-12 07:40:28 UTC
2024-03-12 07:40:28.182 UTC [37734][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:28.278 UTC [37742][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39716
2024-03-12 07:40:28.280 UTC [37742][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-12 07:40:28.280 UTC [37742][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-12 07:40:28.289 UTC [37744][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39717
2024-03-12 07:40:28.293 UTC [37744][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-12 07:40:28.293 UTC [37744][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:28.297 UTC [37744][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-12 07:40:28.298 UTC [37744][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=39717
2024-03-12 07:40:28.301 UTC [37734][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:28.301 UTC [37734][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:28.302 UTC [37734][postmaster] LOG:  background worker "logical replication launcher" (PID 37740) exited with exit code 1
2024-03-12 07:40:28.302 UTC [37735][checkpointer] LOG:  shutting down
2024-03-12 07:40:28.302 UTC [37735][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:28.303 UTC [37735][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/413F210, redo lsn=0/413F210
2024-03-12 07:40:28.304 UTC [37734][postmaster] LOG:  database system is shut down
2024-03-12 07:40:28.418 UTC [37746][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:28.418 UTC [37746][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:28.418 UTC [37746][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:28.420 UTC [37749][startup] LOG:  database system was shut down at 2024-03-12 07:40:28 UTC
2024-03-12 07:40:28.422 UTC [37746][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:28.520 UTC [37754][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39718
2024-03-12 07:40:28.521 UTC [37754][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-12 07:40:28.521 UTC [37754][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-12 07:40:28.527 UTC [37756][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39719
2024-03-12 07:40:28.531 UTC [37756][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-12 07:40:28.531 UTC [37756][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:28.535 UTC [37756][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-12 07:40:28.536 UTC [37756][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=39719
2024-03-12 07:40:28.538 UTC [37746][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:28.538 UTC [37746][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:28.539 UTC [37746][postmaster] LOG:  background worker "logical replication launcher" (PID 37752) exited with exit code 1
2024-03-12 07:40:28.539 UTC [37747][checkpointer] LOG:  shutting down
2024-03-12 07:40:28.539 UTC [37747][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:28.540 UTC [37747][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/413F288, redo lsn=0/413F288
2024-03-12 07:40:28.541 UTC [37746][postmaster] LOG:  database system is shut down
2024-03-12 07:40:28.656 UTC [37758][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:28.656 UTC [37758][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:28.656 UTC [37758][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:28.658 UTC [37761][startup] LOG:  database system was shut down at 2024-03-12 07:40:28 UTC
2024-03-12 07:40:28.661 UTC [37758][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:28.758 UTC [37766][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39720
2024-03-12 07:40:28.760 UTC [37766][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-12 07:40:28.760 UTC [37766][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-12 07:40:28.767 UTC [37768][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39721
2024-03-12 07:40:28.771 UTC [37768][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-12 07:40:28.771 UTC [37768][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:28.776 UTC [37768][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-12 07:40:28.776 UTC [37768][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=39721
2024-03-12 07:40:28.779 UTC [37758][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:28.779 UTC [37758][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:28.780 UTC [37758][postmaster] LOG:  background worker "logical replication launcher" (PID 37764) exited with exit code 1
2024-03-12 07:40:28.780 UTC [37759][checkpointer] LOG:  shutting down
2024-03-12 07:40:28.780 UTC [37759][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:28.781 UTC [37759][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/413F300, redo lsn=0/413F300
2024-03-12 07:40:28.782 UTC [37758][postmaster] LOG:  database system is shut down
2024-03-12 07:40:28.896 UTC [37770][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-12 07:40:28.896 UTC [37770][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63452
2024-03-12 07:40:28.896 UTC [37770][postmaster] LOG:  listening on Unix socket "/tmp/VsJP7z3J2v/.s.PGSQL.63452"
2024-03-12 07:40:28.898 UTC [37773][startup] LOG:  database system was shut down at 2024-03-12 07:40:28 UTC
2024-03-12 07:40:28.900 UTC [37770][postmaster] LOG:  database system is ready to accept connections
2024-03-12 07:40:28.998 UTC [37778][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39722
2024-03-12 07:40:29.000 UTC [37778][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-12 07:40:29.000 UTC [37778][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-12 07:40:29.006 UTC [37780][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=39723
2024-03-12 07:40:29.010 UTC [37780][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-12 07:40:29.010 UTC [37780][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-12 07:40:29.014 UTC [37780][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-12 07:40:29.015 UTC [37780][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=39723
2024-03-12 07:40:29.017 UTC [37770][postmaster] LOG:  received fast shutdown request
2024-03-12 07:40:29.017 UTC [37770][postmaster] LOG:  aborting any active transactions
2024-03-12 07:40:29.018 UTC [37770][postmaster] LOG:  background worker "logical replication launcher" (PID 37776) exited with exit code 1
2024-03-12 07:40:29.018 UTC [37771][checkpointer] LOG:  shutting down
2024-03-12 07:40:29.018 UTC [37771][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-12 07:40:29.019 UTC [37771][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/413F378, redo lsn=0/413F378
2024-03-12 07:40:29.020 UTC [37770][postmaster] LOG:  database system is shut down
2024-03-12 07:40:29.130 UTC [37782][postmaster] FATAL:  could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory
2024-03-12 07:40:29.131 UTC [37782][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[07:40:29.231](0.215s) Bail out!  pg_ctl restart failed
# No postmaster PID for node "primary"