[13:48:32.853](0.034s) # setting up data directory
# Checking port 58026
# Found port 58026
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=58026 host=/tmp/SWfMzE1J9U
Log file: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log
[13:48:32.867](0.014s) # 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 37083
[13:48:33.045](0.178s) 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 37340
[13:48:33.854](0.810s) # 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-22 13:48:32.959 UTC [37083][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:32.959 UTC [37083][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:32.961 UTC [37101][startup] LOG:  database system was shut down at 2024-03-22 13:46:16 UTC
2024-03-22 13:48:32.963 UTC [37083][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:33.038 UTC [37130][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.038 UTC [37130][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-22 13:48:33.038 UTC [37130][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.043 UTC [37130][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-22 13:48:33.044 UTC [37130][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.061 UTC [37142][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.061 UTC [37142][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-22 13:48:33.061 UTC [37142][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.062 UTC [37142][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-22 13:48:33.062 UTC [37142][client backend] [001_ssltests.pl][1/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-22 13:48:33.063 UTC [37142][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.107 UTC [37172][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.107 UTC [37172][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-22 13:48:33.107 UTC [37172][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.108 UTC [37172][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-22 13:48:33.108 UTC [37172][client backend] [001_ssltests.pl][2/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-22 13:48:33.109 UTC [37172][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.148 UTC [37185][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.148 UTC [37185][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-22 13:48:33.148 UTC [37185][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.149 UTC [37185][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-22 13:48:33.149 UTC [37185][client backend] [001_ssltests.pl][3/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-22 13:48:33.149 UTC [37185][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.193 UTC [37197][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.194 UTC [37197][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-22 13:48:33.194 UTC [37197][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.194 UTC [37197][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-22 13:48:33.194 UTC [37197][client backend] [001_ssltests.pl][4/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-22 13:48:33.195 UTC [37197][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.242 UTC [37215][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.243 UTC [37215][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-22 13:48:33.243 UTC [37215][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.243 UTC [37215][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-22 13:48:33.243 UTC [37215][client backend] [001_ssltests.pl][5/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-22 13:48:33.271 UTC [37215][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.289 UTC [37226][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.289 UTC [37226][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-22 13:48:33.289 UTC [37226][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.290 UTC [37226][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-22 13:48:33.290 UTC [37226][client backend] [001_ssltests.pl][6/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-22 13:48:33.315 UTC [37226][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.339 UTC [37241][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.339 UTC [37241][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-22 13:48:33.339 UTC [37241][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.340 UTC [37241][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-22 13:48:33.340 UTC [37241][client backend] [001_ssltests.pl][7/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-22 13:48:33.356 UTC [37241][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.017 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.417 UTC [37255][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.417 UTC [37255][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-22 13:48:33.417 UTC [37255][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.418 UTC [37255][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-22 13:48:33.418 UTC [37255][client backend] [001_ssltests.pl][8/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-22 13:48:33.471 UTC [37255][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.054 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.541 UTC [37286][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.542 UTC [37286][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-22 13:48:33.542 UTC [37286][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.542 UTC [37286][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-22 13:48:33.542 UTC [37286][client backend] [001_ssltests.pl][9/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-22 13:48:33.562 UTC [37286][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.593 UTC [37298][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.593 UTC [37298][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-22 13:48:33.593 UTC [37298][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.593 UTC [37298][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-22 13:48:33.594 UTC [37298][client backend] [001_ssltests.pl][10/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-22 13:48:33.624 UTC [37298][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.635 UTC [37083][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:33.635 UTC [37083][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:33.636 UTC [37083][postmaster] LOG:  background worker "logical replication launcher" (PID 37105) exited with exit code 1
2024-03-22 13:48:33.636 UTC [37099][checkpointer] LOG:  shutting down
2024-03-22 13:48:33.637 UTC [37099][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:33.700 UTC [37099][checkpointer] LOG:  checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.063 s, sync=0.001 s, total=0.063 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4103700, redo lsn=0/4103700
2024-03-22 13:48:33.704 UTC [37083][postmaster] LOG:  database system is shut down
2024-03-22 13:48:33.749 UTC [37340][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:33.749 UTC [37340][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:33.749 UTC [37340][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:33.753 UTC [37347][startup] LOG:  database system was shut down at 2024-03-22 13:48:33 UTC
2024-03-22 13:48:33.773 UTC [37340][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:33.857 UTC [37340][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:33.857 UTC [37340][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:33.858 UTC [37340][postmaster] LOG:  background worker "logical replication launcher" (PID 37355) exited with exit code 1
2024-03-22 13:48:33.859 UTC [37345][checkpointer] LOG:  shutting down
2024-03-22 13:48:33.859 UTC [37345][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:33.859 UTC [37345][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/4103778, redo lsn=0/4103778
2024-03-22 13:48:33.861 UTC [37340][postmaster] LOG:  database system is shut down
2024-03-22 13:48:33.967 UTC [37404][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-22 13:48:33.967 UTC [37404][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[13:48:34.059](0.205s) 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 37433
[13:48:34.164](0.105s) 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-22 13:48:32.959 UTC [37083][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:32.959 UTC [37083][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:32.961 UTC [37101][startup] LOG:  database system was shut down at 2024-03-22 13:46:16 UTC
2024-03-22 13:48:32.963 UTC [37083][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:33.038 UTC [37130][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.038 UTC [37130][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-22 13:48:33.038 UTC [37130][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.043 UTC [37130][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-22 13:48:33.044 UTC [37130][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.061 UTC [37142][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.061 UTC [37142][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-22 13:48:33.061 UTC [37142][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.062 UTC [37142][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-22 13:48:33.062 UTC [37142][client backend] [001_ssltests.pl][1/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-22 13:48:33.063 UTC [37142][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.107 UTC [37172][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.107 UTC [37172][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-22 13:48:33.107 UTC [37172][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.108 UTC [37172][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-22 13:48:33.108 UTC [37172][client backend] [001_ssltests.pl][2/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-22 13:48:33.109 UTC [37172][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.148 UTC [37185][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.148 UTC [37185][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-22 13:48:33.148 UTC [37185][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.149 UTC [37185][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-22 13:48:33.149 UTC [37185][client backend] [001_ssltests.pl][3/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-22 13:48:33.149 UTC [37185][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.193 UTC [37197][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.194 UTC [37197][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-22 13:48:33.194 UTC [37197][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.194 UTC [37197][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-22 13:48:33.194 UTC [37197][client backend] [001_ssltests.pl][4/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-22 13:48:33.195 UTC [37197][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.242 UTC [37215][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.243 UTC [37215][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-22 13:48:33.243 UTC [37215][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.243 UTC [37215][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-22 13:48:33.243 UTC [37215][client backend] [001_ssltests.pl][5/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-22 13:48:33.271 UTC [37215][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.289 UTC [37226][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.289 UTC [37226][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-22 13:48:33.289 UTC [37226][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.290 UTC [37226][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-22 13:48:33.290 UTC [37226][client backend] [001_ssltests.pl][6/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-22 13:48:33.315 UTC [37226][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.339 UTC [37241][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.339 UTC [37241][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-22 13:48:33.339 UTC [37241][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.340 UTC [37241][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-22 13:48:33.340 UTC [37241][client backend] [001_ssltests.pl][7/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-22 13:48:33.356 UTC [37241][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.017 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.417 UTC [37255][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.417 UTC [37255][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-22 13:48:33.417 UTC [37255][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.418 UTC [37255][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-22 13:48:33.418 UTC [37255][client backend] [001_ssltests.pl][8/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-22 13:48:33.471 UTC [37255][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.054 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.541 UTC [37286][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.542 UTC [37286][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-22 13:48:33.542 UTC [37286][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.542 UTC [37286][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-22 13:48:33.542 UTC [37286][client backend] [001_ssltests.pl][9/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-22 13:48:33.562 UTC [37286][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.593 UTC [37298][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.593 UTC [37298][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-22 13:48:33.593 UTC [37298][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.593 UTC [37298][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-22 13:48:33.594 UTC [37298][client backend] [001_ssltests.pl][10/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-22 13:48:33.624 UTC [37298][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.635 UTC [37083][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:33.635 UTC [37083][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:33.636 UTC [37083][postmaster] LOG:  background worker "logical replication launcher" (PID 37105) exited with exit code 1
2024-03-22 13:48:33.636 UTC [37099][checkpointer] LOG:  shutting down
2024-03-22 13:48:33.637 UTC [37099][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:33.700 UTC [37099][checkpointer] LOG:  checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.063 s, sync=0.001 s, total=0.063 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4103700, redo lsn=0/4103700
2024-03-22 13:48:33.704 UTC [37083][postmaster] LOG:  database system is shut down
2024-03-22 13:48:33.749 UTC [37340][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:33.749 UTC [37340][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:33.749 UTC [37340][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:33.753 UTC [37347][startup] LOG:  database system was shut down at 2024-03-22 13:48:33 UTC
2024-03-22 13:48:33.773 UTC [37340][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:33.857 UTC [37340][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:33.857 UTC [37340][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:33.858 UTC [37340][postmaster] LOG:  background worker "logical replication launcher" (PID 37355) exited with exit code 1
2024-03-22 13:48:33.859 UTC [37345][checkpointer] LOG:  shutting down
2024-03-22 13:48:33.859 UTC [37345][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:33.859 UTC [37345][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/4103778, redo lsn=0/4103778
2024-03-22 13:48:33.861 UTC [37340][postmaster] LOG:  database system is shut down
2024-03-22 13:48:33.967 UTC [37404][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-22 13:48:33.967 UTC [37404][postmaster] LOG:  database system is shut down
2024-03-22 13:48:34.075 UTC [37433][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:34.075 UTC [37433][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:34.075 UTC [37433][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:34.077 UTC [37439][startup] LOG:  database system was shut down at 2024-03-22 13:48:33 UTC
2024-03-22 13:48:34.081 UTC [37433][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:34.167 UTC [37433][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:34.167 UTC [37433][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:34.168 UTC [37433][postmaster] LOG:  background worker "logical replication launcher" (PID 37443) exited with exit code 1
2024-03-22 13:48:34.168 UTC [37437][checkpointer] LOG:  shutting down
2024-03-22 13:48:34.168 UTC [37437][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:34.169 UTC [37437][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/41037F0, redo lsn=0/41037F0
2024-03-22 13:48:34.170 UTC [37433][postmaster] LOG:  database system is shut down
2024-03-22 13:48:34.277 UTC [37505][postmaster] FATAL:  could not set SSL protocol version range
2024-03-22 13:48:34.277 UTC [37505][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-22 13:48:34.278 UTC [37505][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[13:48:34.370](0.206s) 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 37527
[13:48:34.478](0.107s) ok 5 - restart succeeds with correct SSL protocol bounds
[13:48:34.478](0.001s) # running client tests
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 37576
[13:48:34.696](0.218s) ok 6 - server doesn't accept non-SSL connections
[13:48:34.696](0.001s) ok 7 - server doesn't accept non-SSL connections: matches
[13:48:34.713](0.017s) ok 8 - connect without server root cert sslmode=require
[13:48:34.714](0.001s) ok 9 - connect without server root cert sslmode=require: no stderr
[13:48:34.722](0.009s) ok 10 - connect without server root cert sslmode=verify-ca
[13:48:34.723](0.001s) ok 11 - connect without server root cert sslmode=verify-ca: matches
[13:48:34.731](0.008s) ok 12 - connect without server root cert sslmode=verify-full
[13:48:34.731](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches
[13:48:34.742](0.011s) ok 14 - connect with wrong server root cert sslmode=require
[13:48:34.743](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches
[13:48:34.753](0.011s) ok 16 - connect with wrong server root cert sslmode=verify-ca
[13:48:34.753](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches
[13:48:34.764](0.011s) ok 18 - connect with wrong server root cert sslmode=verify-full
[13:48:34.764](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches
[13:48:34.775](0.010s) ok 20 - connect with server CA cert, without root CA
[13:48:34.775](0.000s) ok 21 - connect with server CA cert, without root CA: matches
[13:48:34.788](0.013s) ok 22 - connect with correct server CA cert file sslmode=require
[13:48:34.788](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr
[13:48:34.801](0.013s) ok 24 - connect with correct server CA cert file sslmode=verify-ca
[13:48:34.802](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr
[13:48:34.815](0.013s) ok 26 - connect with correct server CA cert file sslmode=verify-full
[13:48:34.816](0.001s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr
[13:48:34.829](0.013s) ok 28 - cert root file that contains two certificates, order 1
[13:48:34.829](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr
[13:48:34.842](0.013s) ok 30 - cert root file that contains two certificates, order 2
[13:48:34.843](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr
[13:48:34.855](0.013s) ok 32 - connect with sslcertmode=disable
[13:48:34.856](0.001s) ok 33 - connect with sslcertmode=disable: no stderr
[13:48:34.868](0.013s) ok 34 - connect with sslcertmode=allow
[13:48:34.869](0.000s) ok 35 - connect with sslcertmode=allow: no stderr
[13:48:34.883](0.015s) ok 36 - connect with sslcertmode=require fails without a client certificate
[13:48:34.884](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches
[13:48:34.899](0.015s) ok 38 - sslcrl option with invalid file name
[13:48:34.899](0.000s) ok 39 - sslcrl option with invalid file name: no stderr
[13:48:34.911](0.012s) ok 40 - CRL belonging to a different CA
[13:48:34.911](0.000s) ok 41 - CRL belonging to a different CA: matches
[13:48:34.923](0.012s) ok 42 - directory CRL belonging to a different CA
[13:48:34.923](0.000s) ok 43 - directory CRL belonging to a different CA: matches
[13:48:34.938](0.014s) ok 44 - CRL with a non-revoked cert
[13:48:34.938](0.000s) ok 45 - CRL with a non-revoked cert: no stderr
[13:48:34.951](0.014s) ok 46 - directory CRL with a non-revoked cert
[13:48:34.952](0.001s) ok 47 - directory CRL with a non-revoked cert: no stderr
[13:48:34.965](0.013s) ok 48 - mismatch between host name and server certificate sslmode=require
[13:48:34.965](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr
[13:48:34.979](0.014s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca
[13:48:34.979](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr
[13:48:34.990](0.011s) ok 52 - mismatch between host name and server certificate sslmode=verify-full
[13:48:34.990](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 37702
[13:48:35.220](0.230s) ok 54 - IP address in the Common Name
[13:48:35.221](0.000s) ok 55 - IP address in the Common Name: no stderr
[13:48:35.233](0.012s) ok 56 - mismatch between host name and server certificate IP address
[13:48:35.233](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 37734
[13:48:35.456](0.223s) ok 58 - IP address in a dNSName
[13:48:35.456](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 37756
[13:48:35.678](0.222s) ok 60 - host name matching with X.509 Subject Alternative Names 1
[13:48:35.678](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr
[13:48:35.693](0.014s) ok 62 - host name matching with X.509 Subject Alternative Names 2
[13:48:35.693](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr
[13:48:35.707](0.014s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard
[13:48:35.707](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr
[13:48:35.718](0.012s) ok 66 - host name not matching with X.509 Subject Alternative Names
[13:48:35.719](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches
[13:48:35.729](0.011s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard
[13:48:35.730](0.000s) ok 69 - host name not matching with X.509 Subject Alternative Names wildcard: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 37791
[13:48:35.957](0.227s) ok 70 - host name matching with a single X.509 Subject Alternative Name
[13:48:35.957](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr
[13:48:35.968](0.011s) ok 72 - host name not matching with a single X.509 Subject Alternative Name
[13:48:35.968](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches
[13:48:35.978](0.010s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard
[13:48:35.978](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 37818
[13:48:36.206](0.228s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1)
[13:48:36.206](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr
[13:48:36.218](0.012s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1)
[13:48:36.218](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr
[13:48:36.229](0.011s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1)
[13:48:36.229](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches
[13:48:36.241](0.012s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2)
[13:48:36.242](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr
[13:48:36.253](0.012s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2)
[13:48:36.253](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr
[13:48:36.264](0.011s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2)
[13:48:36.264](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr
[13:48:36.275](0.010s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2)
[13:48:36.275](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches
[13:48:36.284](0.010s) ok 90 - IPv6 host with CIDR mask does not match
[13:48:36.285](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 37859
[13:48:36.518](0.233s) ok 92 - certificate with both a CN and SANs 1
[13:48:36.518](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr
[13:48:36.530](0.012s) ok 94 - certificate with both a CN and SANs 2
[13:48:36.530](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr
[13:48:36.541](0.010s) ok 96 - certificate with both a CN and SANs ignores CN
[13:48:36.541](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 37888
[13:48:36.770](0.230s) ok 98 - certificate with both a CN and IP SANs matches CN
[13:48:36.771](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr
[13:48:36.784](0.013s) ok 100 - certificate with both a CN and IP SANs matches SAN 1
[13:48:36.784](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr
[13:48:36.796](0.012s) ok 102 - certificate with both a CN and IP SANs matches SAN 2
[13:48:36.796](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 37904
[13:48:37.029](0.233s) ok 104 - certificate with both an IP CN and IP SANs 1
[13:48:37.030](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr
[13:48:37.043](0.014s) ok 106 - certificate with both an IP CN and IP SANs 2
[13:48:37.044](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr
[13:48:37.055](0.011s) ok 108 - certificate with both an IP CN and IP SANs ignores CN
[13:48:37.055](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 37919
[13:48:37.289](0.234s) ok 110 - certificate with both an IP CN and DNS SANs matches CN
[13:48:37.289](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr
[13:48:37.301](0.012s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1
[13:48:37.301](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr
[13:48:37.314](0.012s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2
[13:48:37.314](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 37933
[13:48:37.546](0.232s) ok 116 - server certificate without CN or SANs sslmode=verify-ca
[13:48:37.546](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr
[13:48:37.556](0.011s) ok 118 - server certificate without CN or SANs sslmode=verify-full
[13:48:37.557](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 37945
[13:48:37.784](0.227s) ok 120 - sslrootcert=system does not connect with private CA
[13:48:37.784](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches
[13:48:37.790](0.005s) ok 122 - sslrootcert=system only accepts sslmode=verify-full
[13:48:37.790](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches
[13:48:37.806](0.017s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE
[13:48:37.807](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr
[13:48:37.818](0.012s) ok 126 - sslrootcert=system defaults to sslmode=verify-full
[13:48:37.819](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 37960
[13:48:38.050](0.231s) ok 128 - connects without client-side CRL
[13:48:38.050](0.000s) ok 129 - connects without client-side CRL: no stderr
[13:48:38.060](0.010s) ok 130 - does not connect with client-side CRL file
[13:48:38.060](0.000s) ok 131 - does not connect with client-side CRL file: matches
[13:48:38.071](0.010s) ok 132 - does not connect with client-side CRL directory
[13:48:38.071](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()
[13:48:38.084](0.013s) ok 134 - pg_stat_ssl view without client certificate: exit code 0
[13:48:38.084](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr
[13:48:38.084](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches
[13:48:38.096](0.012s) ok 137 - connection success with correct range of TLS protocol versions
[13:48:38.096](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr
[13:48:38.101](0.005s) ok 139 - connection failure with incorrect range of TLS protocol versions
[13:48:38.101](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches
[13:48:38.106](0.005s) ok 141 - connection failure with an incorrect SSL protocol minimum bound
[13:48:38.106](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches
[13:48:38.111](0.004s) ok 143 - connection failure with an incorrect SSL protocol maximum bound
[13:48:38.111](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches
[13:48:38.111](0.000s) # running server tests
[13:48:38.122](0.011s) ok 145 - certificate authorization fails without client cert
[13:48:38.122](0.000s) ok 146 - certificate authorization fails without client cert: matches
[13:48:38.140](0.018s) ok 147 - certificate authorization succeeds with correct client cert in PEM format
[13:48:38.140](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr
[13:48:38.154](0.014s) ok 149 - certificate authorization succeeds with correct client cert in DER format
[13:48:38.154](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr
[13:48:38.169](0.015s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format
[13:48:38.170](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr
[13:48:38.185](0.016s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format
[13:48:38.185](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr
[13:48:38.201](0.016s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require
[13:48:38.201](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr
[13:48:38.216](0.015s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow
[13:48:38.217](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr
[13:48:38.229](0.013s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable
[13:48:38.229](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches
[13:48:38.238](0.008s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format
[13:48:38.238](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches
[13:48:38.255](0.017s) ok 163 - certificate authorization succeeds with DN mapping
[13:48:38.255](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr
[13:48:38.255](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches
[13:48:38.273](0.017s) ok 166 - certificate authorization succeeds with DN regex mapping
[13:48:38.273](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr
[13:48:38.291](0.018s) ok 168 - certificate authorization succeeds with CN mapping
[13:48:38.291](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr
[13:48:38.291](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches
[13:48:38.292](0.000s) not ok 171 # TODO & SKIP Need Pty support
[13:48:38.292](0.000s) not ok 172 # TODO & SKIP Need Pty support
[13:48:38.292](0.000s) not ok 173 # TODO & SKIP Need Pty support
[13:48:38.292](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_cNpV/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
[13:48:38.322](0.030s) ok 175 - pg_stat_ssl with client certificate: exit code 0
[13:48:38.322](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr
[13:48:38.322](0.000s) ok 177 - pg_stat_ssl with client certificate: matches
[13:48:38.330](0.007s) ok 178 - certificate authorization fails because of file permissions
[13:48:38.330](0.000s) ok 179 - certificate authorization fails because of file permissions: matches
[13:48:38.341](0.011s) ok 180 - certificate authorization fails with client cert belonging to another user
[13:48:38.341](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches
[13:48:38.353](0.011s) ok 182 - certificate authorization fails with revoked client cert
[13:48:38.353](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches
[13:48:38.353](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match
[13:48:38.370](0.017s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name
[13:48:38.370](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr
[13:48:38.370](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches
[13:48:38.382](0.012s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name
[13:48:38.383](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches
[13:48:38.383](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match
[13:48:38.396](0.013s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name
[13:48:38.396](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr
[13:48:38.396](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 38020
[13:48:38.631](0.235s) ok 194 - intermediate client certificate is provided by client
[13:48:38.631](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr
[13:48:38.643](0.012s) ok 196 - intermediate client certificate is missing
[13:48:38.643](0.000s) ok 197 - intermediate client certificate is missing: matches
[13:48:38.655](0.012s) ok 198 - logged client certificate Subjects are truncated if they're too long
[13:48:38.655](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 38034
[13:48:38.884](0.229s) ok 200 - intermediate client certificate is untrusted
[13:48:38.884](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 38044
[13:48:39.116](0.232s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory
[13:48:39.116](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches
[13:48:39.131](0.014s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory
[13:48:39.131](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 38056
[13:48:39.358](0.227s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1
[13:48:39.358](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.
[13:48:39.359](0.000s) #          got: '2'
#     expected: '0'
[13:48:39.359](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[13:48:39.359](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.
[13:48:39.359](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 58026 failed: SSL SYSCALL error: EOF detected'
#     expected: ''
[13:48:39.377](0.018s) ok 208 - connect without requesting ocsp response when sslocspstapling=0
[13:48:39.377](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 38068
[13:48:39.605](0.228s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1
[13:48:39.622](0.017s) ok 211 - connect without requesting ocsp response when sslocspstapling=0
[13:48:39.622](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 38080
[13:48:39.842](0.220s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1
[13:48:39.858](0.016s) ok 214 - connect without requesting ocsp response when sslocspstapling=0
[13:48:39.858](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 38092
[13:48:40.084](0.226s) ok 216 - failed with an expired ocsp response when sslocspstapling=1
[13:48:40.100](0.016s) ok 217 - connect without requesting ocsp response when sslocspstapling=0
[13:48:40.100](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 38104
[13:48:40.325](0.225s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1
[13:48:40.326](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.
[13:48:40.326](0.000s) #          got: '2'
#     expected: '0'
[13:48:40.326](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[13:48:40.326](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.
[13:48:40.326](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 58026 failed: SSL SYSCALL error: EOF detected'
#     expected: ''
[13:48:40.342](0.016s) ok 221 - connect without requesting ocsp response when sslocspstapling=0
[13:48:40.342](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 38116
[13:48:40.568](0.225s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1
[13:48:40.583](0.016s) ok 224 - connect without requesting ocsp response when sslocspstapling=0
[13:48:40.584](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 38128
[13:48:40.809](0.226s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1
[13:48:40.826](0.016s) ok 227 - connect without requesting ocsp response when sslocspstapling=0
[13:48:40.826](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 38140
[13:48:41.051](0.225s) ok 229 - failed with an expired ocsp response when sslocspstapling=1
[13:48:41.067](0.016s) ok 230 - connect without requesting ocsp response when sslocspstapling=0
[13:48:41.067](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 38152
[13:48:41.292](0.225s) ok 232 - failed with an expired ocsp response when sslocspstapling=1
[13:48:41.309](0.017s) ok 233 - connect without requesting ocsp response when sslocspstapling=0
[13:48:41.309](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-22 13:48:32.959 UTC [37083][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:32.959 UTC [37083][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:32.961 UTC [37101][startup] LOG:  database system was shut down at 2024-03-22 13:46:16 UTC
2024-03-22 13:48:32.963 UTC [37083][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:33.038 UTC [37130][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.038 UTC [37130][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-22 13:48:33.038 UTC [37130][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.043 UTC [37130][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-22 13:48:33.044 UTC [37130][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.061 UTC [37142][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.061 UTC [37142][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-22 13:48:33.061 UTC [37142][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.062 UTC [37142][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-22 13:48:33.062 UTC [37142][client backend] [001_ssltests.pl][1/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-22 13:48:33.063 UTC [37142][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.107 UTC [37172][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.107 UTC [37172][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-22 13:48:33.107 UTC [37172][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.108 UTC [37172][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-22 13:48:33.108 UTC [37172][client backend] [001_ssltests.pl][2/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-22 13:48:33.109 UTC [37172][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.148 UTC [37185][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.148 UTC [37185][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-22 13:48:33.148 UTC [37185][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.149 UTC [37185][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-22 13:48:33.149 UTC [37185][client backend] [001_ssltests.pl][3/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-22 13:48:33.149 UTC [37185][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.193 UTC [37197][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.194 UTC [37197][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-22 13:48:33.194 UTC [37197][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.194 UTC [37197][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-22 13:48:33.194 UTC [37197][client backend] [001_ssltests.pl][4/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-22 13:48:33.195 UTC [37197][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.242 UTC [37215][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.243 UTC [37215][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-22 13:48:33.243 UTC [37215][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.243 UTC [37215][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-22 13:48:33.243 UTC [37215][client backend] [001_ssltests.pl][5/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-22 13:48:33.271 UTC [37215][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.289 UTC [37226][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.289 UTC [37226][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-22 13:48:33.289 UTC [37226][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.290 UTC [37226][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-22 13:48:33.290 UTC [37226][client backend] [001_ssltests.pl][6/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-22 13:48:33.315 UTC [37226][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.339 UTC [37241][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.339 UTC [37241][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-22 13:48:33.339 UTC [37241][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.340 UTC [37241][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-22 13:48:33.340 UTC [37241][client backend] [001_ssltests.pl][7/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-22 13:48:33.356 UTC [37241][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.017 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.417 UTC [37255][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.417 UTC [37255][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-22 13:48:33.417 UTC [37255][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.418 UTC [37255][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-22 13:48:33.418 UTC [37255][client backend] [001_ssltests.pl][8/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-22 13:48:33.471 UTC [37255][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.054 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.541 UTC [37286][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.542 UTC [37286][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-22 13:48:33.542 UTC [37286][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.542 UTC [37286][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-22 13:48:33.542 UTC [37286][client backend] [001_ssltests.pl][9/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-22 13:48:33.562 UTC [37286][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.593 UTC [37298][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:48:33.593 UTC [37298][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-22 13:48:33.593 UTC [37298][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:48:33.593 UTC [37298][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-22 13:48:33.594 UTC [37298][client backend] [001_ssltests.pl][10/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-22 13:48:33.624 UTC [37298][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=postgres database=postgres host=[local]
2024-03-22 13:48:33.635 UTC [37083][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:33.635 UTC [37083][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:33.636 UTC [37083][postmaster] LOG:  background worker "logical replication launcher" (PID 37105) exited with exit code 1
2024-03-22 13:48:33.636 UTC [37099][checkpointer] LOG:  shutting down
2024-03-22 13:48:33.637 UTC [37099][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:33.700 UTC [37099][checkpointer] LOG:  checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.063 s, sync=0.001 s, total=0.063 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4103700, redo lsn=0/4103700
2024-03-22 13:48:33.704 UTC [37083][postmaster] LOG:  database system is shut down
2024-03-22 13:48:33.749 UTC [37340][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:33.749 UTC [37340][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:33.749 UTC [37340][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:33.753 UTC [37347][startup] LOG:  database system was shut down at 2024-03-22 13:48:33 UTC
2024-03-22 13:48:33.773 UTC [37340][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:33.857 UTC [37340][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:33.857 UTC [37340][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:33.858 UTC [37340][postmaster] LOG:  background worker "logical replication launcher" (PID 37355) exited with exit code 1
2024-03-22 13:48:33.859 UTC [37345][checkpointer] LOG:  shutting down
2024-03-22 13:48:33.859 UTC [37345][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:33.859 UTC [37345][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/4103778, redo lsn=0/4103778
2024-03-22 13:48:33.861 UTC [37340][postmaster] LOG:  database system is shut down
2024-03-22 13:48:33.967 UTC [37404][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-22 13:48:33.967 UTC [37404][postmaster] LOG:  database system is shut down
2024-03-22 13:48:34.075 UTC [37433][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:34.075 UTC [37433][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:34.075 UTC [37433][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:34.077 UTC [37439][startup] LOG:  database system was shut down at 2024-03-22 13:48:33 UTC
2024-03-22 13:48:34.081 UTC [37433][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:34.167 UTC [37433][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:34.167 UTC [37433][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:34.168 UTC [37433][postmaster] LOG:  background worker "logical replication launcher" (PID 37443) exited with exit code 1
2024-03-22 13:48:34.168 UTC [37437][checkpointer] LOG:  shutting down
2024-03-22 13:48:34.168 UTC [37437][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:34.169 UTC [37437][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/41037F0, redo lsn=0/41037F0
2024-03-22 13:48:34.170 UTC [37433][postmaster] LOG:  database system is shut down
2024-03-22 13:48:34.277 UTC [37505][postmaster] FATAL:  could not set SSL protocol version range
2024-03-22 13:48:34.277 UTC [37505][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-22 13:48:34.278 UTC [37505][postmaster] LOG:  database system is shut down
2024-03-22 13:48:34.389 UTC [37527][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:34.389 UTC [37527][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:34.390 UTC [37527][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:34.392 UTC [37534][startup] LOG:  database system was shut down at 2024-03-22 13:48:34 UTC
2024-03-22 13:48:34.394 UTC [37527][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:34.480 UTC [37527][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:34.480 UTC [37527][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:34.482 UTC [37527][postmaster] LOG:  background worker "logical replication launcher" (PID 37537) exited with exit code 1
2024-03-22 13:48:34.483 UTC [37532][checkpointer] LOG:  shutting down
2024-03-22 13:48:34.483 UTC [37532][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:34.483 UTC [37532][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/4103868, redo lsn=0/4103868
2024-03-22 13:48:34.484 UTC [37527][postmaster] LOG:  database system is shut down
2024-03-22 13:48:34.598 UTC [37576][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:34.598 UTC [37576][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:34.599 UTC [37576][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:34.601 UTC [37588][startup] LOG:  database system was shut down at 2024-03-22 13:48:34 UTC
2024-03-22 13:48:34.604 UTC [37576][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:34.694 UTC [37611][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30197
2024-03-22 13:48:34.694 UTC [37611][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-22 13:48:34.694 UTC [37611][client backend] [[unknown]][0/1:0] DETAIL:  Client IP address resolved to "localhost", forward lookup not checked.
2024-03-22 13:48:34.703 UTC [37615][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30199
2024-03-22 13:48:34.707 UTC [37615][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-22 13:48:34.707 UTC [37615][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-22 13:48:34.711 UTC [37615][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-22 13:48:34.712 UTC [37615][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=30199
2024-03-22 13:48:34.720 UTC [37622][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30202
2024-03-22 13:48:34.721 UTC [37622][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-22 13:48:34.729 UTC [37627][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30204
2024-03-22 13:48:34.730 UTC [37627][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-22 13:48:34.737 UTC [37631][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30207
2024-03-22 13:48:34.741 UTC [37631][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-22 13:48:34.748 UTC [37636][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30209
2024-03-22 13:48:34.752 UTC [37636][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-22 13:48:34.759 UTC [37639][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30210
2024-03-22 13:48:34.763 UTC [37639][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-22 13:48:34.770 UTC [37643][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30212
2024-03-22 13:48:34.773 UTC [37643][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-22 13:48:34.781 UTC [37648][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30214
2024-03-22 13:48:34.785 UTC [37648][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-22 13:48:34.785 UTC [37648][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-22 13:48:34.786 UTC [37648][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-22 13:48:34.787 UTC [37648][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=30214
2024-03-22 13:48:34.795 UTC [37652][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30216
2024-03-22 13:48:34.799 UTC [37652][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-22 13:48:34.799 UTC [37652][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-22 13:48:34.800 UTC [37652][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-22 13:48:34.800 UTC [37652][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=30216
2024-03-22 13:48:34.808 UTC [37656][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30217
2024-03-22 13:48:34.812 UTC [37656][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-22 13:48:34.812 UTC [37656][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-22 13:48:34.813 UTC [37656][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-22 13:48:34.814 UTC [37656][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=30217
2024-03-22 13:48:34.822 UTC [37659][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30219
2024-03-22 13:48:34.826 UTC [37659][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-22 13:48:34.826 UTC [37659][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-22 13:48:34.827 UTC [37659][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-22 13:48:34.828 UTC [37659][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=30219
2024-03-22 13:48:34.836 UTC [37661][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30220
2024-03-22 13:48:34.840 UTC [37661][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-22 13:48:34.840 UTC [37661][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-22 13:48:34.841 UTC [37661][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-22 13:48:34.841 UTC [37661][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=30220
2024-03-22 13:48:34.848 UTC [37664][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30221
2024-03-22 13:48:34.853 UTC [37664][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-22 13:48:34.853 UTC [37664][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-22 13:48:34.853 UTC [37664][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-22 13:48:34.855 UTC [37664][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=30221
2024-03-22 13:48:34.862 UTC [37672][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30222
2024-03-22 13:48:34.866 UTC [37672][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-22 13:48:34.866 UTC [37672][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-22 13:48:34.867 UTC [37672][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-22 13:48:34.867 UTC [37672][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=30222
2024-03-22 13:48:34.876 UTC [37674][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30223
2024-03-22 13:48:34.881 UTC [37674][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-22 13:48:34.881 UTC [37674][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-22 13:48:34.882 UTC [37674][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=30223
2024-03-22 13:48:34.891 UTC [37676][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30224
2024-03-22 13:48:34.896 UTC [37676][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-22 13:48:34.896 UTC [37676][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-22 13:48:34.897 UTC [37676][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-22 13:48:34.897 UTC [37676][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=30224
2024-03-22 13:48:34.905 UTC [37678][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30225
2024-03-22 13:48:34.910 UTC [37678][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-22 13:48:34.918 UTC [37680][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30226
2024-03-22 13:48:34.922 UTC [37680][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-22 13:48:34.930 UTC [37682][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30227
2024-03-22 13:48:34.935 UTC [37682][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-22 13:48:34.935 UTC [37682][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-22 13:48:34.936 UTC [37682][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-22 13:48:34.936 UTC [37682][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=30227
2024-03-22 13:48:34.944 UTC [37686][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30228
2024-03-22 13:48:34.949 UTC [37686][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-22 13:48:34.949 UTC [37686][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-22 13:48:34.950 UTC [37686][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-22 13:48:34.951 UTC [37686][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=30228
2024-03-22 13:48:34.958 UTC [37690][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30230
2024-03-22 13:48:34.962 UTC [37690][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-22 13:48:34.962 UTC [37690][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-22 13:48:34.963 UTC [37690][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-22 13:48:34.971 UTC [37690][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=30230
2024-03-22 13:48:34.972 UTC [37694][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30231
2024-03-22 13:48:34.977 UTC [37694][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-22 13:48:34.977 UTC [37694][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-22 13:48:34.977 UTC [37694][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-22 13:48:34.980 UTC [37694][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=30231
2024-03-22 13:48:34.985 UTC [37697][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30233
2024-03-22 13:48:34.992 UTC [37576][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:34.992 UTC [37576][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:34.994 UTC [37576][postmaster] LOG:  background worker "logical replication launcher" (PID 37592) exited with exit code 1
2024-03-22 13:48:34.994 UTC [37586][checkpointer] LOG:  shutting down
2024-03-22 13:48:34.994 UTC [37586][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:34.995 UTC [37586][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/41038E0, redo lsn=0/41038E0
2024-03-22 13:48:34.996 UTC [37576][postmaster] LOG:  database system is shut down
2024-03-22 13:48:35.110 UTC [37702][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:35.110 UTC [37702][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:35.110 UTC [37702][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:35.112 UTC [37711][startup] LOG:  database system was shut down at 2024-03-22 13:48:34 UTC
2024-03-22 13:48:35.114 UTC [37702][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:35.209 UTC [37719][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30235
2024-03-22 13:48:35.214 UTC [37719][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-22 13:48:35.214 UTC [37719][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-22 13:48:35.219 UTC [37719][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-22 13:48:35.219 UTC [37719][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=30235
2024-03-22 13:48:35.227 UTC [37723][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30237
2024-03-22 13:48:35.235 UTC [37702][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:35.235 UTC [37702][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:35.236 UTC [37702][postmaster] LOG:  background worker "logical replication launcher" (PID 37714) exited with exit code 1
2024-03-22 13:48:35.237 UTC [37709][checkpointer] LOG:  shutting down
2024-03-22 13:48:35.237 UTC [37709][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:35.237 UTC [37709][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/4103958, redo lsn=0/4103958
2024-03-22 13:48:35.238 UTC [37702][postmaster] LOG:  database system is shut down
2024-03-22 13:48:35.349 UTC [37734][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:35.349 UTC [37734][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:35.349 UTC [37734][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:35.350 UTC [37737][startup] LOG:  database system was shut down at 2024-03-22 13:48:35 UTC
2024-03-22 13:48:35.353 UTC [37734][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:35.445 UTC [37748][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30239
2024-03-22 13:48:35.449 UTC [37748][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-22 13:48:35.449 UTC [37748][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-22 13:48:35.454 UTC [37748][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-22 13:48:35.455 UTC [37748][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=30239
2024-03-22 13:48:35.458 UTC [37734][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:35.458 UTC [37734][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:35.460 UTC [37734][postmaster] LOG:  background worker "logical replication launcher" (PID 37740) exited with exit code 1
2024-03-22 13:48:35.460 UTC [37735][checkpointer] LOG:  shutting down
2024-03-22 13:48:35.460 UTC [37735][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:35.460 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/41039D0, redo lsn=0/41039D0
2024-03-22 13:48:35.461 UTC [37734][postmaster] LOG:  database system is shut down
2024-03-22 13:48:35.571 UTC [37756][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:35.571 UTC [37756][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:35.571 UTC [37756][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:35.573 UTC [37762][startup] LOG:  database system was shut down at 2024-03-22 13:48:35 UTC
2024-03-22 13:48:35.575 UTC [37756][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:35.667 UTC [37772][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30241
2024-03-22 13:48:35.672 UTC [37772][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-22 13:48:35.672 UTC [37772][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-22 13:48:35.677 UTC [37772][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-22 13:48:35.677 UTC [37772][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=30241
2024-03-22 13:48:35.685 UTC [37774][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30242
2024-03-22 13:48:35.690 UTC [37774][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-22 13:48:35.690 UTC [37774][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-22 13:48:35.691 UTC [37774][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-22 13:48:35.691 UTC [37774][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=30242
2024-03-22 13:48:35.699 UTC [37776][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30243
2024-03-22 13:48:35.704 UTC [37776][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-22 13:48:35.704 UTC [37776][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-22 13:48:35.705 UTC [37776][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-22 13:48:35.706 UTC [37776][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=30243
2024-03-22 13:48:35.713 UTC [37778][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30244
2024-03-22 13:48:35.724 UTC [37780][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30245
2024-03-22 13:48:35.731 UTC [37756][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:35.731 UTC [37756][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:35.732 UTC [37756][postmaster] LOG:  background worker "logical replication launcher" (PID 37765) exited with exit code 1
2024-03-22 13:48:35.733 UTC [37760][checkpointer] LOG:  shutting down
2024-03-22 13:48:35.733 UTC [37760][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:35.733 UTC [37760][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/4103A48, redo lsn=0/4103A48
2024-03-22 13:48:35.735 UTC [37756][postmaster] LOG:  database system is shut down
2024-03-22 13:48:35.843 UTC [37791][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:35.843 UTC [37791][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:35.843 UTC [37791][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:35.845 UTC [37794][startup] LOG:  database system was shut down at 2024-03-22 13:48:35 UTC
2024-03-22 13:48:35.848 UTC [37791][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:35.945 UTC [37802][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30247
2024-03-22 13:48:35.951 UTC [37802][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-22 13:48:35.951 UTC [37802][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-22 13:48:35.955 UTC [37802][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-22 13:48:35.956 UTC [37802][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=30247
2024-03-22 13:48:35.962 UTC [37806][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30248
2024-03-22 13:48:35.973 UTC [37808][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30249
2024-03-22 13:48:35.982 UTC [37791][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:35.982 UTC [37791][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:35.983 UTC [37791][postmaster] LOG:  background worker "logical replication launcher" (PID 37797) exited with exit code 1
2024-03-22 13:48:35.984 UTC [37792][checkpointer] LOG:  shutting down
2024-03-22 13:48:35.984 UTC [37792][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:35.984 UTC [37792][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/4103AC0, redo lsn=0/4103AC0
2024-03-22 13:48:35.985 UTC [37791][postmaster] LOG:  database system is shut down
2024-03-22 13:48:36.096 UTC [37818][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:36.096 UTC [37818][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:36.096 UTC [37818][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:36.098 UTC [37821][startup] LOG:  database system was shut down at 2024-03-22 13:48:35 UTC
2024-03-22 13:48:36.101 UTC [37818][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:36.195 UTC [37831][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30251
2024-03-22 13:48:36.200 UTC [37831][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-22 13:48:36.200 UTC [37831][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-22 13:48:36.205 UTC [37831][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-22 13:48:36.205 UTC [37831][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=30251
2024-03-22 13:48:36.211 UTC [37833][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30252
2024-03-22 13:48:36.216 UTC [37833][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-22 13:48:36.216 UTC [37833][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-22 13:48:36.217 UTC [37833][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-22 13:48:36.217 UTC [37833][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=30252
2024-03-22 13:48:36.223 UTC [37836][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30253
2024-03-22 13:48:36.234 UTC [37844][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30254
2024-03-22 13:48:36.239 UTC [37844][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-22 13:48:36.239 UTC [37844][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-22 13:48:36.240 UTC [37844][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-22 13:48:36.240 UTC [37844][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=30254
2024-03-22 13:48:36.247 UTC [37846][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30255
2024-03-22 13:48:36.251 UTC [37846][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-22 13:48:36.251 UTC [37846][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-22 13:48:36.252 UTC [37846][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-22 13:48:36.252 UTC [37846][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=30255
2024-03-22 13:48:36.258 UTC [37848][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30256
2024-03-22 13:48:36.262 UTC [37848][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-22 13:48:36.262 UTC [37848][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-22 13:48:36.263 UTC [37848][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-22 13:48:36.263 UTC [37848][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=30256
2024-03-22 13:48:36.269 UTC [37850][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30257
2024-03-22 13:48:36.280 UTC [37853][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30258
2024-03-22 13:48:36.286 UTC [37818][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:36.286 UTC [37818][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:36.287 UTC [37818][postmaster] LOG:  background worker "logical replication launcher" (PID 37825) exited with exit code 1
2024-03-22 13:48:36.288 UTC [37819][checkpointer] LOG:  shutting down
2024-03-22 13:48:36.288 UTC [37819][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:36.288 UTC [37819][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/4103B38, redo lsn=0/4103B38
2024-03-22 13:48:36.289 UTC [37818][postmaster] LOG:  database system is shut down
2024-03-22 13:48:36.404 UTC [37859][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:36.404 UTC [37859][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:36.404 UTC [37859][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:36.406 UTC [37862][startup] LOG:  database system was shut down at 2024-03-22 13:48:36 UTC
2024-03-22 13:48:36.408 UTC [37859][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:36.506 UTC [37876][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30260
2024-03-22 13:48:36.511 UTC [37876][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-22 13:48:36.511 UTC [37876][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-22 13:48:36.516 UTC [37876][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-22 13:48:36.516 UTC [37876][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=30260
2024-03-22 13:48:36.524 UTC [37878][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30261
2024-03-22 13:48:36.528 UTC [37878][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-22 13:48:36.528 UTC [37878][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-22 13:48:36.529 UTC [37878][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-22 13:48:36.529 UTC [37878][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=30261
2024-03-22 13:48:36.536 UTC [37880][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30262
2024-03-22 13:48:36.546 UTC [37859][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:36.546 UTC [37859][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:36.547 UTC [37859][postmaster] LOG:  background worker "logical replication launcher" (PID 37865) exited with exit code 1
2024-03-22 13:48:36.548 UTC [37860][checkpointer] LOG:  shutting down
2024-03-22 13:48:36.548 UTC [37860][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:36.548 UTC [37860][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/4103BB0, redo lsn=0/4103BB0
2024-03-22 13:48:36.550 UTC [37859][postmaster] LOG:  database system is shut down
2024-03-22 13:48:36.659 UTC [37888][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:36.659 UTC [37888][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:36.659 UTC [37888][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:36.661 UTC [37891][startup] LOG:  database system was shut down at 2024-03-22 13:48:36 UTC
2024-03-22 13:48:36.664 UTC [37888][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:36.758 UTC [37896][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30264
2024-03-22 13:48:36.764 UTC [37896][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-22 13:48:36.764 UTC [37896][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-22 13:48:36.769 UTC [37896][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-22 13:48:36.769 UTC [37896][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=30264
2024-03-22 13:48:36.776 UTC [37900][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30265
2024-03-22 13:48:36.782 UTC [37900][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-22 13:48:36.782 UTC [37900][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-22 13:48:36.782 UTC [37900][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-22 13:48:36.783 UTC [37900][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=30265
2024-03-22 13:48:36.789 UTC [37902][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30266
2024-03-22 13:48:36.794 UTC [37902][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-22 13:48:36.794 UTC [37902][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-22 13:48:36.794 UTC [37902][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-22 13:48:36.795 UTC [37902][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=30266
2024-03-22 13:48:36.798 UTC [37888][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:36.798 UTC [37888][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:36.799 UTC [37888][postmaster] LOG:  background worker "logical replication launcher" (PID 37894) exited with exit code 1
2024-03-22 13:48:36.799 UTC [37889][checkpointer] LOG:  shutting down
2024-03-22 13:48:36.799 UTC [37889][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:36.800 UTC [37889][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/4103C28, redo lsn=0/4103C28
2024-03-22 13:48:36.801 UTC [37888][postmaster] LOG:  database system is shut down
2024-03-22 13:48:36.916 UTC [37904][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:36.916 UTC [37904][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:36.916 UTC [37904][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:36.918 UTC [37907][startup] LOG:  database system was shut down at 2024-03-22 13:48:36 UTC
2024-03-22 13:48:36.920 UTC [37904][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:37.017 UTC [37913][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30267
2024-03-22 13:48:37.023 UTC [37913][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-22 13:48:37.023 UTC [37913][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-22 13:48:37.028 UTC [37913][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-22 13:48:37.028 UTC [37913][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=30267
2024-03-22 13:48:37.035 UTC [37915][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30268
2024-03-22 13:48:37.041 UTC [37915][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-22 13:48:37.041 UTC [37915][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-22 13:48:37.042 UTC [37915][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-22 13:48:37.042 UTC [37915][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=30268
2024-03-22 13:48:37.049 UTC [37917][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30269
2024-03-22 13:48:37.057 UTC [37904][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:37.057 UTC [37904][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:37.058 UTC [37904][postmaster] LOG:  background worker "logical replication launcher" (PID 37910) exited with exit code 1
2024-03-22 13:48:37.058 UTC [37905][checkpointer] LOG:  shutting down
2024-03-22 13:48:37.058 UTC [37905][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:37.058 UTC [37905][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/4103CA0, redo lsn=0/4103CA0
2024-03-22 13:48:37.060 UTC [37904][postmaster] LOG:  database system is shut down
2024-03-22 13:48:37.175 UTC [37919][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:37.175 UTC [37919][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:37.175 UTC [37919][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:37.177 UTC [37922][startup] LOG:  database system was shut down at 2024-03-22 13:48:37 UTC
2024-03-22 13:48:37.180 UTC [37919][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:37.277 UTC [37927][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30270
2024-03-22 13:48:37.282 UTC [37927][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-22 13:48:37.282 UTC [37927][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-22 13:48:37.287 UTC [37927][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-22 13:48:37.288 UTC [37927][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=30270
2024-03-22 13:48:37.294 UTC [37929][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30271
2024-03-22 13:48:37.299 UTC [37929][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-22 13:48:37.299 UTC [37929][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-22 13:48:37.300 UTC [37929][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-22 13:48:37.300 UTC [37929][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=30271
2024-03-22 13:48:37.306 UTC [37931][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30272
2024-03-22 13:48:37.311 UTC [37931][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-22 13:48:37.311 UTC [37931][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-22 13:48:37.312 UTC [37931][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-22 13:48:37.312 UTC [37931][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=30272
2024-03-22 13:48:37.316 UTC [37919][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:37.316 UTC [37919][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:37.316 UTC [37919][postmaster] LOG:  background worker "logical replication launcher" (PID 37925) exited with exit code 1
2024-03-22 13:48:37.317 UTC [37920][checkpointer] LOG:  shutting down
2024-03-22 13:48:37.317 UTC [37920][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:37.317 UTC [37920][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/4103D18, redo lsn=0/4103D18
2024-03-22 13:48:37.318 UTC [37919][postmaster] LOG:  database system is shut down
2024-03-22 13:48:37.434 UTC [37933][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:37.434 UTC [37933][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:37.434 UTC [37933][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:37.435 UTC [37936][startup] LOG:  database system was shut down at 2024-03-22 13:48:37 UTC
2024-03-22 13:48:37.438 UTC [37933][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:37.535 UTC [37941][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30273
2024-03-22 13:48:37.540 UTC [37941][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-22 13:48:37.540 UTC [37941][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-22 13:48:37.544 UTC [37941][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-22 13:48:37.544 UTC [37941][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=30273
2024-03-22 13:48:37.551 UTC [37943][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30274
2024-03-22 13:48:37.558 UTC [37933][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:37.558 UTC [37933][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:37.559 UTC [37933][postmaster] LOG:  background worker "logical replication launcher" (PID 37939) exited with exit code 1
2024-03-22 13:48:37.559 UTC [37934][checkpointer] LOG:  shutting down
2024-03-22 13:48:37.559 UTC [37934][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:37.560 UTC [37934][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/4103D90, redo lsn=0/4103D90
2024-03-22 13:48:37.561 UTC [37933][postmaster] LOG:  database system is shut down
2024-03-22 13:48:37.677 UTC [37945][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:37.677 UTC [37945][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:37.677 UTC [37945][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:37.679 UTC [37948][startup] LOG:  database system was shut down at 2024-03-22 13:48:37 UTC
2024-03-22 13:48:37.682 UTC [37945][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:37.778 UTC [37953][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30275
2024-03-22 13:48:37.782 UTC [37953][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-22 13:48:37.795 UTC [37956][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30276
2024-03-22 13:48:37.800 UTC [37956][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-22 13:48:37.800 UTC [37956][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-22 13:48:37.805 UTC [37956][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-22 13:48:37.805 UTC [37956][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=30276
2024-03-22 13:48:37.812 UTC [37958][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30277
2024-03-22 13:48:37.821 UTC [37945][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:37.821 UTC [37945][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:37.822 UTC [37945][postmaster] LOG:  background worker "logical replication launcher" (PID 37951) exited with exit code 1
2024-03-22 13:48:37.822 UTC [37946][checkpointer] LOG:  shutting down
2024-03-22 13:48:37.822 UTC [37946][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:37.822 UTC [37946][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/4103E08, redo lsn=0/4103E08
2024-03-22 13:48:37.824 UTC [37945][postmaster] LOG:  database system is shut down
2024-03-22 13:48:37.936 UTC [37960][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:37.936 UTC [37960][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:37.936 UTC [37960][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:37.938 UTC [37963][startup] LOG:  database system was shut down at 2024-03-22 13:48:37 UTC
2024-03-22 13:48:37.941 UTC [37960][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:38.038 UTC [37968][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30278
2024-03-22 13:48:38.043 UTC [37968][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-22 13:48:38.043 UTC [37968][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-22 13:48:38.048 UTC [37968][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-22 13:48:38.048 UTC [37968][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=30278
2024-03-22 13:48:38.055 UTC [37970][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30279
2024-03-22 13:48:38.059 UTC [37970][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-22 13:48:38.065 UTC [37972][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30280
2024-03-22 13:48:38.069 UTC [37972][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-22 13:48:38.076 UTC [37974][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30281
2024-03-22 13:48:38.080 UTC [37974][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-22 13:48:38.080 UTC [37974][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-22 13:48:38.081 UTC [37974][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-22 13:48:38.083 UTC [37974][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=30281
2024-03-22 13:48:38.089 UTC [37976][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30282
2024-03-22 13:48:38.094 UTC [37976][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-22 13:48:38.094 UTC [37976][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-22 13:48:38.094 UTC [37976][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-22 13:48:38.095 UTC [37976][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=30282
2024-03-22 13:48:38.116 UTC [37981][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30283
2024-03-22 13:48:38.121 UTC [37981][client backend] [[unknown]][3/1:0] FATAL:  connection requires a valid client certificate
2024-03-22 13:48:38.127 UTC [37983][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30284
2024-03-22 13:48:38.133 UTC [37983][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-22 13:48:38.133 UTC [37983][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-22 13:48:38.138 UTC [37983][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_cNpV/client.key$$
2024-03-22 13:48:38.138 UTC [37983][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=30284
2024-03-22 13:48:38.145 UTC [37985][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30285
2024-03-22 13:48:38.152 UTC [37985][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-22 13:48:38.152 UTC [37985][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-22 13:48:38.152 UTC [37985][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_cNpV/client-der.key$$
2024-03-22 13:48:38.153 UTC [37985][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=30285
2024-03-22 13:48:38.159 UTC [37987][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30286
2024-03-22 13:48:38.167 UTC [37987][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-22 13:48:38.167 UTC [37987][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-22 13:48:38.167 UTC [37987][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_cNpV/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$
2024-03-22 13:48:38.168 UTC [37987][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=30286
2024-03-22 13:48:38.175 UTC [37989][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30287
2024-03-22 13:48:38.182 UTC [37989][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-22 13:48:38.182 UTC [37989][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-22 13:48:38.183 UTC [37989][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_cNpV/client-encrypted-der.key sslpassword='dUmmyP^#+'$$
2024-03-22 13:48:38.184 UTC [37989][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=30287
2024-03-22 13:48:38.192 UTC [37991][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30288
2024-03-22 13:48:38.199 UTC [37991][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-22 13:48:38.199 UTC [37991][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-22 13:48:38.199 UTC [37991][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_cNpV/client.key$$
2024-03-22 13:48:38.200 UTC [37991][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=30288
2024-03-22 13:48:38.207 UTC [37993][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30289
2024-03-22 13:48:38.214 UTC [37993][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-22 13:48:38.214 UTC [37993][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-22 13:48:38.215 UTC [37993][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_cNpV/client.key$$
2024-03-22 13:48:38.215 UTC [37993][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=30289
2024-03-22 13:48:38.223 UTC [37995][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30290
2024-03-22 13:48:38.228 UTC [37995][client backend] [[unknown]][10/1:0] FATAL:  connection requires a valid client certificate
2024-03-22 13:48:38.235 UTC [37997][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30291
2024-03-22 13:48:38.237 UTC [37997][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-22 13:48:38.243 UTC [37999][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30292
2024-03-22 13:48:38.249 UTC [37999][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-22 13:48:38.249 UTC [37999][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-22 13:48:38.253 UTC [37999][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_cNpV/client-dn.key$$
2024-03-22 13:48:38.254 UTC [37999][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_dn host=localhost port=30292
2024-03-22 13:48:38.260 UTC [38001][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30293
2024-03-22 13:48:38.266 UTC [38001][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-22 13:48:38.266 UTC [38001][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-22 13:48:38.271 UTC [38001][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_cNpV/client-dn.key$$
2024-03-22 13:48:38.271 UTC [38001][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_dn_re host=localhost port=30293
2024-03-22 13:48:38.279 UTC [38003][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30294
2024-03-22 13:48:38.285 UTC [38003][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-22 13:48:38.285 UTC [38003][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-22 13:48:38.289 UTC [38003][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_cNpV/client-dn.key$$
2024-03-22 13:48:38.290 UTC [38003][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_cn host=localhost port=30294
2024-03-22 13:48:38.312 UTC [38006][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30295
2024-03-22 13:48:38.318 UTC [38006][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-22 13:48:38.319 UTC [38006][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-22 13:48:38.319 UTC [38006][client backend] [001_ssltests.pl][14/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-22 13:48:38.321 UTC [38006][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=30295
2024-03-22 13:48:38.327 UTC [38008][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30296
2024-03-22 13:48:38.329 UTC [38008][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-22 13:48:38.334 UTC [38010][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30297
2024-03-22 13:48:38.340 UTC [38010][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-22 13:48:38.340 UTC [38010][client backend] [[unknown]][15/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-22 13:48:38.340 UTC [38010][client backend] [[unknown]][15/1:0] FATAL:  certificate authentication failed for user "anotheruser"
2024-03-22 13:48:38.340 UTC [38010][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-22 13:48:38.346 UTC [38012][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30298
2024-03-22 13:48:38.352 UTC [38012][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-22 13:48:38.352 UTC [38012][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-22 13:48:38.358 UTC [38014][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30299
2024-03-22 13:48:38.364 UTC [38014][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-22 13:48:38.364 UTC [38014][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-22 13:48:38.368 UTC [38014][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_cNpV/client.key$$
2024-03-22 13:48:38.369 UTC [38014][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=verifydb host=localhost port=30299
2024-03-22 13:48:38.375 UTC [38016][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30300
2024-03-22 13:48:38.381 UTC [38016][client backend] [[unknown]][17/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-22 13:48:38.381 UTC [38016][client backend] [[unknown]][17/1:0] LOG:  certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch
2024-03-22 13:48:38.381 UTC [38016][client backend] [[unknown]][17/1:0] FATAL:  "trust" authentication failed for user "anotheruser"
2024-03-22 13:48:38.381 UTC [38016][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-22 13:48:38.387 UTC [38018][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30301
2024-03-22 13:48:38.393 UTC [38018][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-22 13:48:38.394 UTC [38018][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-22 13:48:38.394 UTC [38018][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_cNpV/client.key$$
2024-03-22 13:48:38.395 UTC [38018][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=yetanotheruser database=verifydb host=localhost port=30301
2024-03-22 13:48:38.398 UTC [37960][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:38.398 UTC [37960][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:38.399 UTC [37960][postmaster] LOG:  background worker "logical replication launcher" (PID 37966) exited with exit code 1
2024-03-22 13:48:38.399 UTC [37961][checkpointer] LOG:  shutting down
2024-03-22 13:48:38.399 UTC [37961][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:38.399 UTC [37961][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/4103E80, redo lsn=0/4103E80
2024-03-22 13:48:38.400 UTC [37960][postmaster] LOG:  database system is shut down
2024-03-22 13:48:38.516 UTC [38020][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:38.516 UTC [38020][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:38.516 UTC [38020][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:38.517 UTC [38023][startup] LOG:  database system was shut down at 2024-03-22 13:48:38 UTC
2024-03-22 13:48:38.520 UTC [38020][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:38.617 UTC [38028][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30302
2024-03-22 13:48:38.624 UTC [38028][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-22 13:48:38.624 UTC [38028][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-22 13:48:38.629 UTC [38028][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_cNpV/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-22 13:48:38.629 UTC [38028][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb host=localhost port=30302
2024-03-22 13:48:38.636 UTC [38030][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30303
2024-03-22 13:48:38.641 UTC [38030][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-22 13:48:38.641 UTC [38030][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-22 13:48:38.648 UTC [38032][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30304
2024-03-22 13:48:38.654 UTC [38032][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-22 13:48:38.654 UTC [38032][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-22 13:48:38.656 UTC [38020][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:38.656 UTC [38020][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:38.657 UTC [38020][postmaster] LOG:  background worker "logical replication launcher" (PID 38026) exited with exit code 1
2024-03-22 13:48:38.657 UTC [38021][checkpointer] LOG:  shutting down
2024-03-22 13:48:38.657 UTC [38021][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:38.658 UTC [38021][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/4103EF8, redo lsn=0/4103EF8
2024-03-22 13:48:38.659 UTC [38020][postmaster] LOG:  database system is shut down
2024-03-22 13:48:38.776 UTC [38034][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:38.776 UTC [38034][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:38.776 UTC [38034][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:38.778 UTC [38037][startup] LOG:  database system was shut down at 2024-03-22 13:48:38 UTC
2024-03-22 13:48:38.781 UTC [38034][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:38.876 UTC [38042][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30305
2024-03-22 13:48:38.883 UTC [38042][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-22 13:48:38.883 UTC [38042][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-22 13:48:38.886 UTC [38034][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:38.886 UTC [38034][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:38.887 UTC [38034][postmaster] LOG:  background worker "logical replication launcher" (PID 38040) exited with exit code 1
2024-03-22 13:48:38.887 UTC [38035][checkpointer] LOG:  shutting down
2024-03-22 13:48:38.887 UTC [38035][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:38.888 UTC [38035][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/4103F70, redo lsn=0/4103F70
2024-03-22 13:48:38.889 UTC [38034][postmaster] LOG:  database system is shut down
2024-03-22 13:48:39.006 UTC [38044][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:39.006 UTC [38044][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:39.006 UTC [38044][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:39.008 UTC [38047][startup] LOG:  database system was shut down at 2024-03-22 13:48:38 UTC
2024-03-22 13:48:39.011 UTC [38044][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:39.107 UTC [38052][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30306
2024-03-22 13:48:39.115 UTC [38052][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-22 13:48:39.115 UTC [38052][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-22 13:48:39.123 UTC [38054][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30307
2024-03-22 13:48:39.129 UTC [38054][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-22 13:48:39.129 UTC [38054][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-22 13:48:39.133 UTC [38044][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:39.133 UTC [38044][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:39.134 UTC [38044][postmaster] LOG:  background worker "logical replication launcher" (PID 38050) exited with exit code 1
2024-03-22 13:48:39.135 UTC [38045][checkpointer] LOG:  shutting down
2024-03-22 13:48:39.135 UTC [38045][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:39.135 UTC [38045][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/4103FE8, redo lsn=0/4103FE8
2024-03-22 13:48:39.137 UTC [38044][postmaster] LOG:  database system is shut down
2024-03-22 13:48:39.251 UTC [38056][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:39.251 UTC [38056][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:39.251 UTC [38056][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:39.253 UTC [38059][startup] LOG:  database system was shut down at 2024-03-22 13:48:39 UTC
2024-03-22 13:48:39.256 UTC [38056][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:39.354 UTC [38064][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30308
2024-03-22 13:48:39.356 UTC [38064][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-22 13:48:39.356 UTC [38064][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-22 13:48:39.366 UTC [38066][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30309
2024-03-22 13:48:39.371 UTC [38066][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-22 13:48:39.371 UTC [38066][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-22 13:48:39.375 UTC [38066][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-22 13:48:39.376 UTC [38066][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=30309
2024-03-22 13:48:39.379 UTC [38056][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:39.379 UTC [38056][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:39.380 UTC [38056][postmaster] LOG:  background worker "logical replication launcher" (PID 38062) exited with exit code 1
2024-03-22 13:48:39.381 UTC [38057][checkpointer] LOG:  shutting down
2024-03-22 13:48:39.381 UTC [38057][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:39.381 UTC [38057][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/4104078, redo lsn=0/4104078
2024-03-22 13:48:39.383 UTC [38056][postmaster] LOG:  database system is shut down
2024-03-22 13:48:39.498 UTC [38068][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:39.498 UTC [38068][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:39.498 UTC [38068][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:39.500 UTC [38071][startup] LOG:  database system was shut down at 2024-03-22 13:48:39 UTC
2024-03-22 13:48:39.502 UTC [38068][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:39.600 UTC [38076][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30310
2024-03-22 13:48:39.603 UTC [38076][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-22 13:48:39.603 UTC [38076][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-22 13:48:39.611 UTC [38078][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30311
2024-03-22 13:48:39.616 UTC [38078][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-22 13:48:39.616 UTC [38078][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-22 13:48:39.620 UTC [38078][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-22 13:48:39.620 UTC [38078][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=30311
2024-03-22 13:48:39.624 UTC [38068][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:39.624 UTC [38068][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:39.625 UTC [38068][postmaster] LOG:  background worker "logical replication launcher" (PID 38074) exited with exit code 1
2024-03-22 13:48:39.625 UTC [38069][checkpointer] LOG:  shutting down
2024-03-22 13:48:39.625 UTC [38069][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:39.626 UTC [38069][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/41040F0, redo lsn=0/41040F0
2024-03-22 13:48:39.627 UTC [38068][postmaster] LOG:  database system is shut down
2024-03-22 13:48:39.742 UTC [38080][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:39.742 UTC [38080][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:39.742 UTC [38080][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:39.744 UTC [38083][startup] LOG:  database system was shut down at 2024-03-22 13:48:39 UTC
2024-03-22 13:48:39.747 UTC [38080][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:39.838 UTC [38088][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30312
2024-03-22 13:48:39.840 UTC [38088][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-22 13:48:39.840 UTC [38088][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-22 13:48:39.847 UTC [38090][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30313
2024-03-22 13:48:39.852 UTC [38090][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-22 13:48:39.852 UTC [38090][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-22 13:48:39.857 UTC [38090][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-22 13:48:39.857 UTC [38090][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=30313
2024-03-22 13:48:39.860 UTC [38080][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:39.860 UTC [38080][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:39.861 UTC [38080][postmaster] LOG:  background worker "logical replication launcher" (PID 38086) exited with exit code 1
2024-03-22 13:48:39.861 UTC [38081][checkpointer] LOG:  shutting down
2024-03-22 13:48:39.862 UTC [38081][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:39.862 UTC [38081][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/4104168, redo lsn=0/4104168
2024-03-22 13:48:39.863 UTC [38080][postmaster] LOG:  database system is shut down
2024-03-22 13:48:39.979 UTC [38092][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:39.979 UTC [38092][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:39.979 UTC [38092][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:39.981 UTC [38095][startup] LOG:  database system was shut down at 2024-03-22 13:48:39 UTC
2024-03-22 13:48:39.984 UTC [38092][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:40.080 UTC [38100][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30314
2024-03-22 13:48:40.082 UTC [38100][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-22 13:48:40.082 UTC [38100][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-22 13:48:40.089 UTC [38102][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30315
2024-03-22 13:48:40.094 UTC [38102][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-22 13:48:40.094 UTC [38102][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-22 13:48:40.098 UTC [38102][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-22 13:48:40.099 UTC [38102][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=30315
2024-03-22 13:48:40.102 UTC [38092][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:40.102 UTC [38092][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:40.103 UTC [38092][postmaster] LOG:  background worker "logical replication launcher" (PID 38098) exited with exit code 1
2024-03-22 13:48:40.103 UTC [38093][checkpointer] LOG:  shutting down
2024-03-22 13:48:40.103 UTC [38093][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:40.103 UTC [38093][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/41041E0, redo lsn=0/41041E0
2024-03-22 13:48:40.105 UTC [38092][postmaster] LOG:  database system is shut down
2024-03-22 13:48:40.221 UTC [38104][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:40.221 UTC [38104][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:40.221 UTC [38104][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:40.223 UTC [38107][startup] LOG:  database system was shut down at 2024-03-22 13:48:40 UTC
2024-03-22 13:48:40.225 UTC [38104][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:40.321 UTC [38112][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30316
2024-03-22 13:48:40.323 UTC [38112][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-22 13:48:40.323 UTC [38112][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-22 13:48:40.331 UTC [38114][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30317
2024-03-22 13:48:40.336 UTC [38114][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-22 13:48:40.336 UTC [38114][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-22 13:48:40.341 UTC [38114][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-22 13:48:40.341 UTC [38114][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=30317
2024-03-22 13:48:40.344 UTC [38104][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:40.344 UTC [38104][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:40.345 UTC [38104][postmaster] LOG:  background worker "logical replication launcher" (PID 38110) exited with exit code 1
2024-03-22 13:48:40.345 UTC [38105][checkpointer] LOG:  shutting down
2024-03-22 13:48:40.345 UTC [38105][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:40.345 UTC [38105][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/4104258, redo lsn=0/4104258
2024-03-22 13:48:40.346 UTC [38104][postmaster] LOG:  database system is shut down
2024-03-22 13:48:40.463 UTC [38116][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:40.463 UTC [38116][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:40.463 UTC [38116][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:40.465 UTC [38119][startup] LOG:  database system was shut down at 2024-03-22 13:48:40 UTC
2024-03-22 13:48:40.467 UTC [38116][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:40.564 UTC [38124][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30318
2024-03-22 13:48:40.566 UTC [38124][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-22 13:48:40.566 UTC [38124][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-22 13:48:40.573 UTC [38126][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30319
2024-03-22 13:48:40.577 UTC [38126][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-22 13:48:40.577 UTC [38126][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-22 13:48:40.582 UTC [38126][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-22 13:48:40.582 UTC [38126][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=30319
2024-03-22 13:48:40.585 UTC [38116][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:40.585 UTC [38116][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:40.586 UTC [38116][postmaster] LOG:  background worker "logical replication launcher" (PID 38122) exited with exit code 1
2024-03-22 13:48:40.586 UTC [38117][checkpointer] LOG:  shutting down
2024-03-22 13:48:40.586 UTC [38117][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:40.587 UTC [38117][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/41042D0, redo lsn=0/41042D0
2024-03-22 13:48:40.588 UTC [38116][postmaster] LOG:  database system is shut down
2024-03-22 13:48:40.703 UTC [38128][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:40.703 UTC [38128][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:40.703 UTC [38128][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:40.704 UTC [38131][startup] LOG:  database system was shut down at 2024-03-22 13:48:40 UTC
2024-03-22 13:48:40.707 UTC [38128][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:40.805 UTC [38136][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30320
2024-03-22 13:48:40.807 UTC [38136][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-22 13:48:40.807 UTC [38136][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-22 13:48:40.814 UTC [38138][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30321
2024-03-22 13:48:40.819 UTC [38138][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-22 13:48:40.819 UTC [38138][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-22 13:48:40.824 UTC [38138][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-22 13:48:40.824 UTC [38138][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=30321
2024-03-22 13:48:40.828 UTC [38128][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:40.828 UTC [38128][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:40.829 UTC [38128][postmaster] LOG:  background worker "logical replication launcher" (PID 38134) exited with exit code 1
2024-03-22 13:48:40.829 UTC [38129][checkpointer] LOG:  shutting down
2024-03-22 13:48:40.829 UTC [38129][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:40.830 UTC [38129][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/4104348, redo lsn=0/4104348
2024-03-22 13:48:40.831 UTC [38128][postmaster] LOG:  database system is shut down
2024-03-22 13:48:40.946 UTC [38140][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:40.946 UTC [38140][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:40.946 UTC [38140][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:40.948 UTC [38143][startup] LOG:  database system was shut down at 2024-03-22 13:48:40 UTC
2024-03-22 13:48:40.951 UTC [38140][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:41.047 UTC [38148][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30322
2024-03-22 13:48:41.049 UTC [38148][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-22 13:48:41.049 UTC [38148][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-22 13:48:41.056 UTC [38150][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30323
2024-03-22 13:48:41.061 UTC [38150][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-22 13:48:41.061 UTC [38150][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-22 13:48:41.066 UTC [38150][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-22 13:48:41.066 UTC [38150][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=30323
2024-03-22 13:48:41.069 UTC [38140][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:41.069 UTC [38140][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:41.070 UTC [38140][postmaster] LOG:  background worker "logical replication launcher" (PID 38146) exited with exit code 1
2024-03-22 13:48:41.070 UTC [38141][checkpointer] LOG:  shutting down
2024-03-22 13:48:41.070 UTC [38141][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:41.070 UTC [38141][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/41043C0, redo lsn=0/41043C0
2024-03-22 13:48:41.072 UTC [38140][postmaster] LOG:  database system is shut down
2024-03-22 13:48:41.187 UTC [38152][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-22 13:48:41.187 UTC [38152][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 58026
2024-03-22 13:48:41.187 UTC [38152][postmaster] LOG:  listening on Unix socket "/tmp/SWfMzE1J9U/.s.PGSQL.58026"
2024-03-22 13:48:41.189 UTC [38155][startup] LOG:  database system was shut down at 2024-03-22 13:48:41 UTC
2024-03-22 13:48:41.191 UTC [38152][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:48:41.289 UTC [38160][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30324
2024-03-22 13:48:41.290 UTC [38160][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-22 13:48:41.290 UTC [38160][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-22 13:48:41.297 UTC [38162][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=30325
2024-03-22 13:48:41.302 UTC [38162][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-22 13:48:41.302 UTC [38162][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:48:41.307 UTC [38162][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-22 13:48:41.308 UTC [38162][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=30325
2024-03-22 13:48:41.311 UTC [38152][postmaster] LOG:  received fast shutdown request
2024-03-22 13:48:41.311 UTC [38152][postmaster] LOG:  aborting any active transactions
2024-03-22 13:48:41.312 UTC [38152][postmaster] LOG:  background worker "logical replication launcher" (PID 38158) exited with exit code 1
2024-03-22 13:48:41.312 UTC [38153][checkpointer] LOG:  shutting down
2024-03-22 13:48:41.312 UTC [38153][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:48:41.312 UTC [38153][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/4104438, redo lsn=0/4104438
2024-03-22 13:48:41.313 UTC [38152][postmaster] LOG:  database system is shut down
2024-03-22 13:48:41.419 UTC [38164][postmaster] FATAL:  could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory
2024-03-22 13:48:41.419 UTC [38164][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[13:48:41.518](0.209s) Bail out!  pg_ctl restart failed
# No postmaster PID for node "primary"