[23:18:04.824](0.032s) # setting up data directory
# Checking port 59270
# Found port 59270
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=59270 host=/tmp/1DTXpjOuWd
Log file: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log
[23:18:04.829](0.005s) # 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 29571
[23:18:05.008](0.179s) 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 29691
[23:18:05.709](0.701s) # 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-27 23:18:04.903 UTC [29571][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-27 23:18:04.903 UTC [29571][postmaster] LOG:  listening on Unix socket "/tmp/1DTXpjOuWd/.s.PGSQL.59270"
2024-03-27 23:18:04.906 UTC [29578][startup] LOG:  database system was shut down at 2024-03-27 22:36:58 UTC
2024-03-27 23:18:04.910 UTC [29571][postmaster] LOG:  database system is ready to accept connections
2024-03-27 23:18:04.999 UTC [29591][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-27 23:18:05.000 UTC [29591][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-27 23:18:05.000 UTC [29591][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-27 23:18:05.005 UTC [29591][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-27 23:18:05.005 UTC [29591][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local]
2024-03-27 23:18:05.017 UTC [29599][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-27 23:18:05.017 UTC [29599][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-27 23:18:05.017 UTC [29599][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-27 23:18:05.018 UTC [29599][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-27 23:18:05.018 UTC [29599][client backend] [001_ssltests.pl][1/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-27 23:18:05.018 UTC [29599][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-27 23:18:05.064 UTC [29601][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-27 23:18:05.065 UTC [29601][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-27 23:18:05.065 UTC [29601][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-27 23:18:05.066 UTC [29601][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-27 23:18:05.066 UTC [29601][client backend] [001_ssltests.pl][2/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-27 23:18:05.066 UTC [29601][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-27 23:18:05.111 UTC [29612][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-27 23:18:05.111 UTC [29612][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-27 23:18:05.111 UTC [29612][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-27 23:18:05.112 UTC [29612][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-27 23:18:05.112 UTC [29612][client backend] [001_ssltests.pl][3/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-27 23:18:05.113 UTC [29612][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-27 23:18:05.158 UTC [29620][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-27 23:18:05.159 UTC [29620][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-27 23:18:05.159 UTC [29620][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-27 23:18:05.160 UTC [29620][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-27 23:18:05.160 UTC [29620][client backend] [001_ssltests.pl][4/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-27 23:18:05.160 UTC [29620][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-27 23:18:05.206 UTC [29627][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-27 23:18:05.207 UTC [29627][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-27 23:18:05.207 UTC [29627][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-27 23:18:05.207 UTC [29627][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-27 23:18:05.207 UTC [29627][client backend] [001_ssltests.pl][5/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-27 23:18:05.230 UTC [29627][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=postgres database=postgres host=[local]
2024-03-27 23:18:05.251 UTC [29639][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-27 23:18:05.252 UTC [29639][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-27 23:18:05.252 UTC [29639][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-27 23:18:05.253 UTC [29639][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-27 23:18:05.253 UTC [29639][client backend] [001_ssltests.pl][6/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-27 23:18:05.281 UTC [29639][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=postgres database=postgres host=[local]
2024-03-27 23:18:05.298 UTC [29641][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-27 23:18:05.298 UTC [29641][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-27 23:18:05.298 UTC [29641][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-27 23:18:05.299 UTC [29641][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-27 23:18:05.299 UTC [29641][client backend] [001_ssltests.pl][7/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-27 23:18:05.320 UTC [29641][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=postgres database=postgres host=[local]
2024-03-27 23:18:05.345 UTC [29652][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-27 23:18:05.346 UTC [29652][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-27 23:18:05.346 UTC [29652][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-27 23:18:05.346 UTC [29652][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-27 23:18:05.347 UTC [29652][client backend] [001_ssltests.pl][8/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-27 23:18:05.374 UTC [29652][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.028 user=postgres database=postgres host=[local]
2024-03-27 23:18:05.391 UTC [29659][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-27 23:18:05.392 UTC [29659][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-27 23:18:05.392 UTC [29659][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-27 23:18:05.393 UTC [29659][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-27 23:18:05.393 UTC [29659][client backend] [001_ssltests.pl][9/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-27 23:18:05.411 UTC [29659][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=postgres database=postgres host=[local]
2024-03-27 23:18:05.439 UTC [29664][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-27 23:18:05.440 UTC [29664][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-27 23:18:05.440 UTC [29664][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-27 23:18:05.441 UTC [29664][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-27 23:18:05.441 UTC [29664][client backend] [001_ssltests.pl][10/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-27 23:18:05.467 UTC [29664][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=postgres database=postgres host=[local]
2024-03-27 23:18:05.506 UTC [29571][postmaster] LOG:  received fast shutdown request
2024-03-27 23:18:05.506 UTC [29571][postmaster] LOG:  aborting any active transactions
2024-03-27 23:18:05.508 UTC [29571][postmaster] LOG:  background worker "logical replication launcher" (PID 29581) exited with exit code 1
2024-03-27 23:18:05.508 UTC [29576][checkpointer] LOG:  shutting down
2024-03-27 23:18:05.508 UTC [29576][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-27 23:18:05.561 UTC [29576][checkpointer] LOG:  checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.052 s, sync=0.001 s, total=0.053 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4100AC0, redo lsn=0/4100AC0
2024-03-27 23:18:05.568 UTC [29571][postmaster] LOG:  database system is shut down
2024-03-27 23:18:05.620 UTC [29691][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-27 23:18:05.620 UTC [29691][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 59270
2024-03-27 23:18:05.620 UTC [29691][postmaster] LOG:  listening on Unix socket "/tmp/1DTXpjOuWd/.s.PGSQL.59270"
2024-03-27 23:18:05.623 UTC [29694][startup] LOG:  database system was shut down at 2024-03-27 23:18:05 UTC
2024-03-27 23:18:05.627 UTC [29691][postmaster] LOG:  database system is ready to accept connections
2024-03-27 23:18:05.712 UTC [29691][postmaster] LOG:  received fast shutdown request
2024-03-27 23:18:05.712 UTC [29691][postmaster] LOG:  aborting any active transactions
2024-03-27 23:18:05.714 UTC [29691][postmaster] LOG:  background worker "logical replication launcher" (PID 29697) exited with exit code 1
2024-03-27 23:18:05.714 UTC [29692][checkpointer] LOG:  shutting down
2024-03-27 23:18:05.714 UTC [29692][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-27 23:18:05.715 UTC [29692][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/4100B38, redo lsn=0/4100B38
2024-03-27 23:18:05.717 UTC [29691][postmaster] LOG:  database system is shut down
2024-03-27 23:18:05.826 UTC [29721][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-27 23:18:05.826 UTC [29721][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[23:18:05.915](0.206s) 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 29733
[23:18:06.026](0.111s) 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-27 23:18:04.903 UTC [29571][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-27 23:18:04.903 UTC [29571][postmaster] LOG:  listening on Unix socket "/tmp/1DTXpjOuWd/.s.PGSQL.59270"
2024-03-27 23:18:04.906 UTC [29578][startup] LOG:  database system was shut down at 2024-03-27 22:36:58 UTC
2024-03-27 23:18:04.910 UTC [29571][postmaster] LOG:  database system is ready to accept connections
2024-03-27 23:18:04.999 UTC [29591][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-27 23:18:05.000 UTC [29591][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-27 23:18:05.000 UTC [29591][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-27 23:18:05.005 UTC [29591][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-27 23:18:05.005 UTC [29591][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local]
2024-03-27 23:18:05.017 UTC [29599][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-27 23:18:05.017 UTC [29599][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-27 23:18:05.017 UTC [29599][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-27 23:18:05.018 UTC [29599][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-27 23:18:05.018 UTC [29599][client backend] [001_ssltests.pl][1/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-27 23:18:05.018 UTC [29599][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-27 23:18:05.064 UTC [29601][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-27 23:18:05.065 UTC [29601][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-27 23:18:05.065 UTC [29601][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-27 23:18:05.066 UTC [29601][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-27 23:18:05.066 UTC [29601][client backend] [001_ssltests.pl][2/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-27 23:18:05.066 UTC [29601][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-27 23:18:05.111 UTC [29612][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-27 23:18:05.111 UTC [29612][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-27 23:18:05.111 UTC [29612][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-27 23:18:05.112 UTC [29612][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-27 23:18:05.112 UTC [29612][client backend] [001_ssltests.pl][3/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-27 23:18:05.113 UTC [29612][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-27 23:18:05.158 UTC [29620][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-27 23:18:05.159 UTC [29620][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-27 23:18:05.159 UTC [29620][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-27 23:18:05.160 UTC [29620][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-27 23:18:05.160 UTC [29620][client backend] [001_ssltests.pl][4/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-27 23:18:05.160 UTC [29620][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-27 23:18:05.206 UTC [29627][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-27 23:18:05.207 UTC [29627][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-27 23:18:05.207 UTC [29627][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-27 23:18:05.207 UTC [29627][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-27 23:18:05.207 UTC [29627][client backend] [001_ssltests.pl][5/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-27 23:18:05.230 UTC [29627][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=postgres database=postgres host=[local]
2024-03-27 23:18:05.251 UTC [29639][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-27 23:18:05.252 UTC [29639][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-27 23:18:05.252 UTC [29639][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-27 23:18:05.253 UTC [29639][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-27 23:18:05.253 UTC [29639][client backend] [001_ssltests.pl][6/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-27 23:18:05.281 UTC [29639][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=postgres database=postgres host=[local]
2024-03-27 23:18:05.298 UTC [29641][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-27 23:18:05.298 UTC [29641][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-27 23:18:05.298 UTC [29641][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-27 23:18:05.299 UTC [29641][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-27 23:18:05.299 UTC [29641][client backend] [001_ssltests.pl][7/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-27 23:18:05.320 UTC [29641][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=postgres database=postgres host=[local]
2024-03-27 23:18:05.345 UTC [29652][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-27 23:18:05.346 UTC [29652][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-27 23:18:05.346 UTC [29652][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-27 23:18:05.346 UTC [29652][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-27 23:18:05.347 UTC [29652][client backend] [001_ssltests.pl][8/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-27 23:18:05.374 UTC [29652][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.028 user=postgres database=postgres host=[local]
2024-03-27 23:18:05.391 UTC [29659][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-27 23:18:05.392 UTC [29659][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-27 23:18:05.392 UTC [29659][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-27 23:18:05.393 UTC [29659][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-27 23:18:05.393 UTC [29659][client backend] [001_ssltests.pl][9/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-27 23:18:05.411 UTC [29659][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=postgres database=postgres host=[local]
2024-03-27 23:18:05.439 UTC [29664][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-27 23:18:05.440 UTC [29664][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-27 23:18:05.440 UTC [29664][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-27 23:18:05.441 UTC [29664][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-27 23:18:05.441 UTC [29664][client backend] [001_ssltests.pl][10/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-27 23:18:05.467 UTC [29664][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=postgres database=postgres host=[local]
2024-03-27 23:18:05.506 UTC [29571][postmaster] LOG:  received fast shutdown request
2024-03-27 23:18:05.506 UTC [29571][postmaster] LOG:  aborting any active transactions
2024-03-27 23:18:05.508 UTC [29571][postmaster] LOG:  background worker "logical replication launcher" (PID 29581) exited with exit code 1
2024-03-27 23:18:05.508 UTC [29576][checkpointer] LOG:  shutting down
2024-03-27 23:18:05.508 UTC [29576][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-27 23:18:05.561 UTC [29576][checkpointer] LOG:  checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.052 s, sync=0.001 s, total=0.053 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4100AC0, redo lsn=0/4100AC0
2024-03-27 23:18:05.568 UTC [29571][postmaster] LOG:  database system is shut down
2024-03-27 23:18:05.620 UTC [29691][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-27 23:18:05.620 UTC [29691][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 59270
2024-03-27 23:18:05.620 UTC [29691][postmaster] LOG:  listening on Unix socket "/tmp/1DTXpjOuWd/.s.PGSQL.59270"
2024-03-27 23:18:05.623 UTC [29694][startup] LOG:  database system was shut down at 2024-03-27 23:18:05 UTC
2024-03-27 23:18:05.627 UTC [29691][postmaster] LOG:  database system is ready to accept connections
2024-03-27 23:18:05.712 UTC [29691][postmaster] LOG:  received fast shutdown request
2024-03-27 23:18:05.712 UTC [29691][postmaster] LOG:  aborting any active transactions
2024-03-27 23:18:05.714 UTC [29691][postmaster] LOG:  background worker "logical replication launcher" (PID 29697) exited with exit code 1
2024-03-27 23:18:05.714 UTC [29692][checkpointer] LOG:  shutting down
2024-03-27 23:18:05.714 UTC [29692][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-27 23:18:05.715 UTC [29692][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/4100B38, redo lsn=0/4100B38
2024-03-27 23:18:05.717 UTC [29691][postmaster] LOG:  database system is shut down
2024-03-27 23:18:05.826 UTC [29721][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-27 23:18:05.826 UTC [29721][postmaster] LOG:  database system is shut down
2024-03-27 23:18:05.936 UTC [29733][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-27 23:18:05.936 UTC [29733][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 59270
2024-03-27 23:18:05.936 UTC [29733][postmaster] LOG:  listening on Unix socket "/tmp/1DTXpjOuWd/.s.PGSQL.59270"
2024-03-27 23:18:05.939 UTC [29744][startup] LOG:  database system was shut down at 2024-03-27 23:18:05 UTC
2024-03-27 23:18:05.942 UTC [29733][postmaster] LOG:  database system is ready to accept connections
2024-03-27 23:18:06.029 UTC [29733][postmaster] LOG:  received fast shutdown request
2024-03-27 23:18:06.029 UTC [29733][postmaster] LOG:  aborting any active transactions
2024-03-27 23:18:06.031 UTC [29733][postmaster] LOG:  background worker "logical replication launcher" (PID 29747) exited with exit code 1
2024-03-27 23:18:06.032 UTC [29742][checkpointer] LOG:  shutting down
2024-03-27 23:18:06.032 UTC [29742][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-27 23:18:06.033 UTC [29742][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/4100BB0, redo lsn=0/4100BB0
2024-03-27 23:18:06.036 UTC [29733][postmaster] LOG:  database system is shut down
2024-03-27 23:18:06.148 UTC [29768][postmaster] FATAL:  could not set SSL protocol version range
2024-03-27 23:18:06.148 UTC [29768][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-27 23:18:06.148 UTC [29768][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[23:18:06.243](0.217s) 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 29786
[23:18:06.353](0.110s) ok 5 - restart succeeds with correct SSL protocol bounds
[23:18:06.353](0.000s) # running client tests
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 29823
[23:18:06.596](0.243s) ok 6 - server doesn't accept non-SSL connections
[23:18:06.596](0.000s) ok 7 - server doesn't accept non-SSL connections: matches
[23:18:06.629](0.033s) ok 8 - connect without server root cert sslmode=require
[23:18:06.632](0.003s) ok 9 - connect without server root cert sslmode=require: no stderr
[23:18:06.646](0.013s) ok 10 - connect without server root cert sslmode=verify-ca
[23:18:06.646](0.001s) ok 11 - connect without server root cert sslmode=verify-ca: matches
[23:18:06.657](0.011s) ok 12 - connect without server root cert sslmode=verify-full
[23:18:06.658](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches
[23:18:06.672](0.014s) ok 14 - connect with wrong server root cert sslmode=require
[23:18:06.672](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches
[23:18:06.686](0.014s) ok 16 - connect with wrong server root cert sslmode=verify-ca
[23:18:06.686](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches
[23:18:06.701](0.015s) ok 18 - connect with wrong server root cert sslmode=verify-full
[23:18:06.702](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches
[23:18:06.718](0.016s) ok 20 - connect with server CA cert, without root CA
[23:18:06.718](0.000s) ok 21 - connect with server CA cert, without root CA: matches
[23:18:06.735](0.017s) ok 22 - connect with correct server CA cert file sslmode=require
[23:18:06.735](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr
[23:18:06.754](0.019s) ok 24 - connect with correct server CA cert file sslmode=verify-ca
[23:18:06.755](0.001s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr
[23:18:06.774](0.019s) ok 26 - connect with correct server CA cert file sslmode=verify-full
[23:18:06.774](0.001s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr
[23:18:06.792](0.018s) ok 28 - cert root file that contains two certificates, order 1
[23:18:06.793](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr
[23:18:06.812](0.019s) ok 30 - cert root file that contains two certificates, order 2
[23:18:06.812](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr
[23:18:06.830](0.017s) ok 32 - connect with sslcertmode=disable
[23:18:06.830](0.000s) ok 33 - connect with sslcertmode=disable: no stderr
[23:18:06.848](0.018s) ok 34 - connect with sslcertmode=allow
[23:18:06.848](0.000s) ok 35 - connect with sslcertmode=allow: no stderr
[23:18:06.864](0.016s) ok 36 - connect with sslcertmode=require fails without a client certificate
[23:18:06.865](0.001s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches
[23:18:06.886](0.021s) ok 38 - sslcrl option with invalid file name
[23:18:06.887](0.001s) ok 39 - sslcrl option with invalid file name: no stderr
[23:18:06.985](0.099s) ok 40 - CRL belonging to a different CA
[23:18:06.986](0.000s) ok 41 - CRL belonging to a different CA: matches
[23:18:07.004](0.019s) ok 42 - directory CRL belonging to a different CA
[23:18:07.007](0.002s) ok 43 - directory CRL belonging to a different CA: matches
[23:18:07.025](0.018s) ok 44 - CRL with a non-revoked cert
[23:18:07.025](0.000s) ok 45 - CRL with a non-revoked cert: no stderr
[23:18:07.041](0.016s) ok 46 - directory CRL with a non-revoked cert
[23:18:07.042](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr
[23:18:07.060](0.019s) ok 48 - mismatch between host name and server certificate sslmode=require
[23:18:07.061](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr
[23:18:07.079](0.018s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca
[23:18:07.079](0.001s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr
[23:18:07.099](0.019s) ok 52 - mismatch between host name and server certificate sslmode=verify-full
[23:18:07.100](0.001s) ok 53 - mismatch between host name and server certificate sslmode=verify-full: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 29977
[23:18:07.334](0.234s) ok 54 - IP address in the Common Name
[23:18:07.335](0.001s) ok 55 - IP address in the Common Name: no stderr
[23:18:07.349](0.015s) ok 56 - mismatch between host name and server certificate IP address
[23:18:07.349](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 30025
[23:18:07.578](0.228s) ok 58 - IP address in a dNSName
[23:18:07.578](0.001s) 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 30068
[23:18:07.807](0.229s) ok 60 - host name matching with X.509 Subject Alternative Names 1
[23:18:07.808](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr
[23:18:07.826](0.018s) ok 62 - host name matching with X.509 Subject Alternative Names 2
[23:18:07.827](0.001s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr
[23:18:07.848](0.021s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard
[23:18:07.848](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr
[23:18:07.863](0.015s) ok 66 - host name not matching with X.509 Subject Alternative Names
[23:18:07.863](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches
[23:18:07.877](0.014s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard
[23:18:07.877](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 30128
[23:18:08.105](0.228s) ok 70 - host name matching with a single X.509 Subject Alternative Name
[23:18:08.105](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr
[23:18:08.121](0.016s) ok 72 - host name not matching with a single X.509 Subject Alternative Name
[23:18:08.122](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches
[23:18:08.137](0.015s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard
[23:18:08.137](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 30178
[23:18:08.372](0.235s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1)
[23:18:08.373](0.001s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr
[23:18:08.393](0.020s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1)
[23:18:08.394](0.001s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr
[23:18:08.410](0.017s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1)
[23:18:08.411](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches
[23:18:08.432](0.021s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2)
[23:18:08.432](0.001s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr
[23:18:08.449](0.017s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2)
[23:18:08.450](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr
[23:18:08.467](0.018s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2)
[23:18:08.468](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr
[23:18:08.483](0.016s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2)
[23:18:08.484](0.001s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches
[23:18:08.500](0.016s) ok 90 - IPv6 host with CIDR mask does not match
[23:18:08.501](0.001s) 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 30260
[23:18:08.742](0.241s) ok 92 - certificate with both a CN and SANs 1
[23:18:08.743](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr
[23:18:08.762](0.020s) ok 94 - certificate with both a CN and SANs 2
[23:18:08.762](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr
[23:18:08.780](0.017s) ok 96 - certificate with both a CN and SANs ignores CN
[23:18:08.781](0.001s) 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 30316
[23:18:09.018](0.238s) ok 98 - certificate with both a CN and IP SANs matches CN
[23:18:09.019](0.001s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr
[23:18:09.037](0.017s) ok 100 - certificate with both a CN and IP SANs matches SAN 1
[23:18:09.037](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr
[23:18:09.055](0.018s) ok 102 - certificate with both a CN and IP SANs matches SAN 2
[23:18:09.055](0.001s) 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 30366
[23:18:09.289](0.234s) ok 104 - certificate with both an IP CN and IP SANs 1
[23:18:09.289](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr
[23:18:09.307](0.017s) ok 106 - certificate with both an IP CN and IP SANs 2
[23:18:09.307](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr
[23:18:09.322](0.015s) ok 108 - certificate with both an IP CN and IP SANs ignores CN
[23:18:09.322](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 30422
[23:18:09.550](0.228s) ok 110 - certificate with both an IP CN and DNS SANs matches CN
[23:18:09.550](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr
[23:18:09.568](0.018s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1
[23:18:09.568](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr
[23:18:09.587](0.019s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2
[23:18:09.588](0.001s) 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 30473
[23:18:09.825](0.238s) ok 116 - server certificate without CN or SANs sslmode=verify-ca
[23:18:09.826](0.001s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr
[23:18:09.850](0.024s) ok 118 - server certificate without CN or SANs sslmode=verify-full
[23:18:09.850](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 30527
[23:18:10.084](0.234s) ok 120 - sslrootcert=system does not connect with private CA
[23:18:10.085](0.001s) ok 121 - sslrootcert=system does not connect with private CA: matches
[23:18:10.093](0.008s) ok 122 - sslrootcert=system only accepts sslmode=verify-full
[23:18:10.094](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches
[23:18:10.116](0.022s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE
[23:18:10.116](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr
[23:18:10.132](0.015s) ok 126 - sslrootcert=system defaults to sslmode=verify-full
[23:18:10.132](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 30581
[23:18:10.360](0.228s) ok 128 - connects without client-side CRL
[23:18:10.360](0.000s) ok 129 - connects without client-side CRL: no stderr
[23:18:10.374](0.014s) ok 130 - does not connect with client-side CRL file
[23:18:10.375](0.000s) ok 131 - does not connect with client-side CRL file: matches
[23:18:10.391](0.016s) ok 132 - does not connect with client-side CRL directory
[23:18:10.391](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()
[23:18:10.410](0.019s) ok 134 - pg_stat_ssl view without client certificate: exit code 0
[23:18:10.411](0.001s) not ok 135 - pg_stat_ssl view without client certificate: no stderr
[23:18:10.411](0.000s) #   Failed test 'pg_stat_ssl view without client certificate: no stderr'
#   at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 532.
[23:18:10.411](0.000s) #          got: 'WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
# '
#     expected: ''
[23:18:10.411](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches
[23:18:10.437](0.026s) ok 137 - connection success with correct range of TLS protocol versions
[23:18:10.438](0.001s) ok 138 - connection success with correct range of TLS protocol versions: no stderr
[23:18:10.446](0.008s) ok 139 - connection failure with incorrect range of TLS protocol versions
[23:18:10.447](0.001s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches
[23:18:10.456](0.009s) ok 141 - connection failure with an incorrect SSL protocol minimum bound
[23:18:10.456](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches
[23:18:10.465](0.009s) ok 143 - connection failure with an incorrect SSL protocol maximum bound
[23:18:10.465](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches
[23:18:10.466](0.000s) # running server tests
[23:18:10.483](0.017s) ok 145 - certificate authorization fails without client cert
[23:18:10.483](0.000s) ok 146 - certificate authorization fails without client cert: matches
[23:18:10.507](0.024s) ok 147 - certificate authorization succeeds with correct client cert in PEM format
[23:18:10.507](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr
[23:18:10.527](0.020s) ok 149 - certificate authorization succeeds with correct client cert in DER format
[23:18:10.527](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr
[23:18:10.551](0.024s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format
[23:18:10.555](0.003s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr
[23:18:10.578](0.023s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format
[23:18:10.578](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr
[23:18:10.597](0.019s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require
[23:18:10.597](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr
[23:18:10.617](0.020s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow
[23:18:10.617](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr
[23:18:10.632](0.014s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable
[23:18:10.632](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches
[23:18:10.642](0.010s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format
[23:18:10.642](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches
[23:18:10.665](0.023s) ok 163 - certificate authorization succeeds with DN mapping
[23:18:10.666](0.001s) ok 164 - certificate authorization succeeds with DN mapping: no stderr
[23:18:10.667](0.001s) ok 165 - certificate authorization succeeds with DN mapping: log matches
[23:18:10.697](0.031s) ok 166 - certificate authorization succeeds with DN regex mapping
[23:18:10.698](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr
[23:18:10.720](0.022s) ok 168 - certificate authorization succeeds with CN mapping
[23:18:10.720](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr
[23:18:10.721](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches
[23:18:10.721](0.000s) not ok 171 # TODO & SKIP Need Pty support
[23:18:10.721](0.000s) not ok 172 # TODO & SKIP Need Pty support
[23:18:10.721](0.000s) not ok 173 # TODO & SKIP Need Pty support
[23:18:10.721](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_yOlW/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
[23:18:10.764](0.043s) ok 175 - pg_stat_ssl with client certificate: exit code 0
[23:18:10.764](0.000s) not ok 176 - pg_stat_ssl with client certificate: no stderr
[23:18:10.765](0.000s) #   Failed test 'pg_stat_ssl with client certificate: no stderr'
#   at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 730.
[23:18:10.765](0.000s) #          got: 'WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
# '
#     expected: ''
[23:18:10.765](0.000s) ok 177 - pg_stat_ssl with client certificate: matches
[23:18:10.779](0.014s) ok 178 - certificate authorization fails because of file permissions
[23:18:10.780](0.001s) ok 179 - certificate authorization fails because of file permissions: matches
[23:18:10.813](0.033s) ok 180 - certificate authorization fails with client cert belonging to another user
[23:18:10.814](0.001s) ok 181 - certificate authorization fails with client cert belonging to another user: matches
[23:18:10.834](0.020s) ok 182 - certificate authorization fails with revoked client cert
[23:18:10.834](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches
[23:18:10.835](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match
[23:18:10.859](0.024s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name
[23:18:10.859](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr
[23:18:10.859](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches
[23:18:10.877](0.018s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name
[23:18:10.878](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches
[23:18:10.878](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match
[23:18:10.898](0.020s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name
[23:18:10.898](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr
[23:18:10.899](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 30755
[23:18:11.136](0.237s) ok 194 - intermediate client certificate is provided by client
[23:18:11.136](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr
[23:18:11.155](0.019s) ok 196 - intermediate client certificate is missing
[23:18:11.156](0.001s) ok 197 - intermediate client certificate is missing: matches
[23:18:11.178](0.022s) ok 198 - logged client certificate Subjects are truncated if they're too long
[23:18:11.178](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 30805
[23:18:11.413](0.235s) ok 200 - intermediate client certificate is untrusted
[23:18:11.413](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 30851
[23:18:11.638](0.225s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory
[23:18:11.639](0.001s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches
[23:18:11.656](0.017s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory
[23:18:11.656](0.000s) ok 205 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory: matches
[23:18:11.657](0.000s) 1..205
### Stopping node "primary" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "primary"
[23:18:11.762](0.105s) # Looks like you failed 2 tests of 205.