[14:17:33.016](0.032s) # setting up data directory
# Checking port 55167
# Found port 55167
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=55167 host=/tmp/kWyg0Tt6Gp
Log file: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log
[14:17:33.021](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 29781
[14:17:33.200](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 29897
[14:17:33.885](0.684s) # testing password-protected keys
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... stopped waiting
pg_ctl: could not start server
Examine the log output.
# pg_ctl restart failed; logfile:
2024-03-28 14:17:33.091 UTC [29781][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 14:17:33.091 UTC [29781][postmaster] LOG:  listening on Unix socket "/tmp/kWyg0Tt6Gp/.s.PGSQL.55167"
2024-03-28 14:17:33.094 UTC [29784][startup] LOG:  database system was shut down at 2024-03-28 13:36:43 UTC
2024-03-28 14:17:33.097 UTC [29781][postmaster] LOG:  database system is ready to accept connections
2024-03-28 14:17:33.194 UTC [29803][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 14:17:33.195 UTC [29803][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 14:17:33.195 UTC [29803][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 14:17:33.199 UTC [29803][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-28 14:17:33.199 UTC [29803][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-28 14:17:33.208 UTC [29805][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 14:17:33.208 UTC [29805][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 14:17:33.208 UTC [29805][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 14:17:33.209 UTC [29805][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-28 14:17:33.209 UTC [29805][client backend] [001_ssltests.pl][1/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-28 14:17:33.210 UTC [29805][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-28 14:17:33.253 UTC [29813][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 14:17:33.253 UTC [29813][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 14:17:33.253 UTC [29813][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 14:17:33.254 UTC [29813][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-28 14:17:33.254 UTC [29813][client backend] [001_ssltests.pl][2/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-28 14:17:33.254 UTC [29813][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-28 14:17:33.297 UTC [29823][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 14:17:33.297 UTC [29823][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 14:17:33.297 UTC [29823][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 14:17:33.298 UTC [29823][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-28 14:17:33.298 UTC [29823][client backend] [001_ssltests.pl][3/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-28 14:17:33.298 UTC [29823][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-28 14:17:33.341 UTC [29831][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 14:17:33.342 UTC [29831][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 14:17:33.342 UTC [29831][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 14:17:33.342 UTC [29831][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-28 14:17:33.342 UTC [29831][client backend] [001_ssltests.pl][4/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-28 14:17:33.343 UTC [29831][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-28 14:17:33.387 UTC [29837][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 14:17:33.387 UTC [29837][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 14:17:33.387 UTC [29837][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 14:17:33.388 UTC [29837][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-28 14:17:33.388 UTC [29837][client backend] [001_ssltests.pl][5/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 14:17:33.409 UTC [29837][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.022 user=postgres database=postgres host=[local]
2024-03-28 14:17:33.434 UTC [29843][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 14:17:33.434 UTC [29843][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 14:17:33.434 UTC [29843][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 14:17:33.435 UTC [29843][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-28 14:17:33.435 UTC [29843][client backend] [001_ssltests.pl][6/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 14:17:33.460 UTC [29843][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local]
2024-03-28 14:17:33.479 UTC [29851][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 14:17:33.480 UTC [29851][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 14:17:33.480 UTC [29851][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 14:17:33.481 UTC [29851][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-28 14:17:33.481 UTC [29851][client backend] [001_ssltests.pl][7/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 14:17:33.499 UTC [29851][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=postgres database=postgres host=[local]
2024-03-28 14:17:33.525 UTC [29861][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 14:17:33.526 UTC [29861][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 14:17:33.526 UTC [29861][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 14:17:33.526 UTC [29861][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-28 14:17:33.526 UTC [29861][client backend] [001_ssltests.pl][8/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 14:17:33.552 UTC [29861][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local]
2024-03-28 14:17:33.569 UTC [29869][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 14:17:33.570 UTC [29869][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 14:17:33.570 UTC [29869][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 14:17:33.570 UTC [29869][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-28 14:17:33.570 UTC [29869][client backend] [001_ssltests.pl][9/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 14:17:33.589 UTC [29869][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.019 user=postgres database=postgres host=[local]
2024-03-28 14:17:33.616 UTC [29878][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 14:17:33.616 UTC [29878][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 14:17:33.616 UTC [29878][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 14:17:33.617 UTC [29878][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-28 14:17:33.617 UTC [29878][client backend] [001_ssltests.pl][10/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 14:17:33.642 UTC [29878][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local]
2024-03-28 14:17:33.682 UTC [29781][postmaster] LOG:  received fast shutdown request
2024-03-28 14:17:33.682 UTC [29781][postmaster] LOG:  aborting any active transactions
2024-03-28 14:17:33.683 UTC [29781][postmaster] LOG:  background worker "logical replication launcher" (PID 29787) exited with exit code 1
2024-03-28 14:17:33.684 UTC [29782][checkpointer] LOG:  shutting down
2024-03-28 14:17:33.684 UTC [29782][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 14:17:33.730 UTC [29782][checkpointer] LOG:  checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.046 s, sync=0.001 s, total=0.047 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-28 14:17:33.736 UTC [29781][postmaster] LOG:  database system is shut down
2024-03-28 14:17:33.794 UTC [29897][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 14:17:33.794 UTC [29897][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55167
2024-03-28 14:17:33.794 UTC [29897][postmaster] LOG:  listening on Unix socket "/tmp/kWyg0Tt6Gp/.s.PGSQL.55167"
2024-03-28 14:17:33.797 UTC [29905][startup] LOG:  database system was shut down at 2024-03-28 14:17:33 UTC
2024-03-28 14:17:33.800 UTC [29897][postmaster] LOG:  database system is ready to accept connections
2024-03-28 14:17:33.888 UTC [29897][postmaster] LOG:  received fast shutdown request
2024-03-28 14:17:33.888 UTC [29897][postmaster] LOG:  aborting any active transactions
2024-03-28 14:17:33.889 UTC [29897][postmaster] LOG:  background worker "logical replication launcher" (PID 29908) exited with exit code 1
2024-03-28 14:17:33.889 UTC [29902][checkpointer] LOG:  shutting down
2024-03-28 14:17:33.889 UTC [29902][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 14:17:33.890 UTC [29902][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-28 14:17:33.892 UTC [29897][postmaster] LOG:  database system is shut down
2024-03-28 14:17:34.002 UTC [29932][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-28 14:17:34.002 UTC [29932][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[14:17:34.092](0.208s) 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 29949
[14:17:34.196](0.104s) ok 3 - restart succeeds with password-protected key file
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... stopped waiting
pg_ctl: could not start server
Examine the log output.
# pg_ctl restart failed; logfile:
2024-03-28 14:17:33.091 UTC [29781][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 14:17:33.091 UTC [29781][postmaster] LOG:  listening on Unix socket "/tmp/kWyg0Tt6Gp/.s.PGSQL.55167"
2024-03-28 14:17:33.094 UTC [29784][startup] LOG:  database system was shut down at 2024-03-28 13:36:43 UTC
2024-03-28 14:17:33.097 UTC [29781][postmaster] LOG:  database system is ready to accept connections
2024-03-28 14:17:33.194 UTC [29803][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 14:17:33.195 UTC [29803][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 14:17:33.195 UTC [29803][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 14:17:33.199 UTC [29803][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-28 14:17:33.199 UTC [29803][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-28 14:17:33.208 UTC [29805][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 14:17:33.208 UTC [29805][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 14:17:33.208 UTC [29805][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 14:17:33.209 UTC [29805][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-28 14:17:33.209 UTC [29805][client backend] [001_ssltests.pl][1/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-28 14:17:33.210 UTC [29805][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-28 14:17:33.253 UTC [29813][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 14:17:33.253 UTC [29813][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 14:17:33.253 UTC [29813][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 14:17:33.254 UTC [29813][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-28 14:17:33.254 UTC [29813][client backend] [001_ssltests.pl][2/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-28 14:17:33.254 UTC [29813][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-28 14:17:33.297 UTC [29823][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 14:17:33.297 UTC [29823][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 14:17:33.297 UTC [29823][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 14:17:33.298 UTC [29823][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-28 14:17:33.298 UTC [29823][client backend] [001_ssltests.pl][3/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-28 14:17:33.298 UTC [29823][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-28 14:17:33.341 UTC [29831][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 14:17:33.342 UTC [29831][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 14:17:33.342 UTC [29831][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 14:17:33.342 UTC [29831][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-28 14:17:33.342 UTC [29831][client backend] [001_ssltests.pl][4/2:0] WARNING:  roles created by regression test cases should have names starting with "regress_"
2024-03-28 14:17:33.343 UTC [29831][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-28 14:17:33.387 UTC [29837][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 14:17:33.387 UTC [29837][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 14:17:33.387 UTC [29837][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 14:17:33.388 UTC [29837][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-28 14:17:33.388 UTC [29837][client backend] [001_ssltests.pl][5/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 14:17:33.409 UTC [29837][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.022 user=postgres database=postgres host=[local]
2024-03-28 14:17:33.434 UTC [29843][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 14:17:33.434 UTC [29843][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 14:17:33.434 UTC [29843][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 14:17:33.435 UTC [29843][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-28 14:17:33.435 UTC [29843][client backend] [001_ssltests.pl][6/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 14:17:33.460 UTC [29843][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local]
2024-03-28 14:17:33.479 UTC [29851][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 14:17:33.480 UTC [29851][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 14:17:33.480 UTC [29851][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 14:17:33.481 UTC [29851][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-28 14:17:33.481 UTC [29851][client backend] [001_ssltests.pl][7/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 14:17:33.499 UTC [29851][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=postgres database=postgres host=[local]
2024-03-28 14:17:33.525 UTC [29861][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 14:17:33.526 UTC [29861][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 14:17:33.526 UTC [29861][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 14:17:33.526 UTC [29861][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-28 14:17:33.526 UTC [29861][client backend] [001_ssltests.pl][8/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 14:17:33.552 UTC [29861][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local]
2024-03-28 14:17:33.569 UTC [29869][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 14:17:33.570 UTC [29869][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 14:17:33.570 UTC [29869][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 14:17:33.570 UTC [29869][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-28 14:17:33.570 UTC [29869][client backend] [001_ssltests.pl][9/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 14:17:33.589 UTC [29869][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.019 user=postgres database=postgres host=[local]
2024-03-28 14:17:33.616 UTC [29878][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-28 14:17:33.616 UTC [29878][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-28 14:17:33.616 UTC [29878][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-28 14:17:33.617 UTC [29878][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-28 14:17:33.617 UTC [29878][client backend] [001_ssltests.pl][10/2:0] WARNING:  databases created by regression test cases should have names including "regression"
2024-03-28 14:17:33.642 UTC [29878][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local]
2024-03-28 14:17:33.682 UTC [29781][postmaster] LOG:  received fast shutdown request
2024-03-28 14:17:33.682 UTC [29781][postmaster] LOG:  aborting any active transactions
2024-03-28 14:17:33.683 UTC [29781][postmaster] LOG:  background worker "logical replication launcher" (PID 29787) exited with exit code 1
2024-03-28 14:17:33.684 UTC [29782][checkpointer] LOG:  shutting down
2024-03-28 14:17:33.684 UTC [29782][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 14:17:33.730 UTC [29782][checkpointer] LOG:  checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.046 s, sync=0.001 s, total=0.047 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-28 14:17:33.736 UTC [29781][postmaster] LOG:  database system is shut down
2024-03-28 14:17:33.794 UTC [29897][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 14:17:33.794 UTC [29897][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55167
2024-03-28 14:17:33.794 UTC [29897][postmaster] LOG:  listening on Unix socket "/tmp/kWyg0Tt6Gp/.s.PGSQL.55167"
2024-03-28 14:17:33.797 UTC [29905][startup] LOG:  database system was shut down at 2024-03-28 14:17:33 UTC
2024-03-28 14:17:33.800 UTC [29897][postmaster] LOG:  database system is ready to accept connections
2024-03-28 14:17:33.888 UTC [29897][postmaster] LOG:  received fast shutdown request
2024-03-28 14:17:33.888 UTC [29897][postmaster] LOG:  aborting any active transactions
2024-03-28 14:17:33.889 UTC [29897][postmaster] LOG:  background worker "logical replication launcher" (PID 29908) exited with exit code 1
2024-03-28 14:17:33.889 UTC [29902][checkpointer] LOG:  shutting down
2024-03-28 14:17:33.889 UTC [29902][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 14:17:33.890 UTC [29902][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-28 14:17:33.892 UTC [29897][postmaster] LOG:  database system is shut down
2024-03-28 14:17:34.002 UTC [29932][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-28 14:17:34.002 UTC [29932][postmaster] LOG:  database system is shut down
2024-03-28 14:17:34.109 UTC [29949][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit
2024-03-28 14:17:34.109 UTC [29949][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 55167
2024-03-28 14:17:34.109 UTC [29949][postmaster] LOG:  listening on Unix socket "/tmp/kWyg0Tt6Gp/.s.PGSQL.55167"
2024-03-28 14:17:34.111 UTC [29954][startup] LOG:  database system was shut down at 2024-03-28 14:17:33 UTC
2024-03-28 14:17:34.114 UTC [29949][postmaster] LOG:  database system is ready to accept connections
2024-03-28 14:17:34.200 UTC [29949][postmaster] LOG:  received fast shutdown request
2024-03-28 14:17:34.200 UTC [29949][postmaster] LOG:  aborting any active transactions
2024-03-28 14:17:34.204 UTC [29949][postmaster] LOG:  background worker "logical replication launcher" (PID 29957) exited with exit code 1
2024-03-28 14:17:34.204 UTC [29952][checkpointer] LOG:  shutting down
2024-03-28 14:17:34.204 UTC [29952][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-28 14:17:34.205 UTC [29952][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-28 14:17:34.207 UTC [29949][postmaster] LOG:  database system is shut down
2024-03-28 14:17:34.316 UTC [29979][postmaster] FATAL:  could not set SSL protocol version range
2024-03-28 14:17:34.316 UTC [29979][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-28 14:17:34.316 UTC [29979][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[14:17:34.414](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 29996
[14:17:34.525](0.111s) ok 5 - restart succeeds with correct SSL protocol bounds
[14:17:34.525](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 30034
[14:17:34.757](0.232s) ok 6 - server doesn't accept non-SSL connections
[14:17:34.757](0.000s) ok 7 - server doesn't accept non-SSL connections: matches
[14:17:34.787](0.030s) ok 8 - connect without server root cert sslmode=require
[14:17:34.787](0.001s) ok 9 - connect without server root cert sslmode=require: no stderr
[14:17:34.800](0.013s) ok 10 - connect without server root cert sslmode=verify-ca
[14:17:34.800](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches
[14:17:34.812](0.012s) ok 12 - connect without server root cert sslmode=verify-full
[14:17:34.812](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches
[14:17:34.828](0.015s) ok 14 - connect with wrong server root cert sslmode=require
[14:17:34.828](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches
[14:17:34.843](0.015s) ok 16 - connect with wrong server root cert sslmode=verify-ca
[14:17:34.843](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches
[14:17:34.856](0.013s) ok 18 - connect with wrong server root cert sslmode=verify-full
[14:17:34.857](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches
[14:17:34.871](0.014s) ok 20 - connect with server CA cert, without root CA
[14:17:34.871](0.000s) ok 21 - connect with server CA cert, without root CA: matches
[14:17:34.887](0.016s) ok 22 - connect with correct server CA cert file sslmode=require
[14:17:34.887](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr
[14:17:34.904](0.017s) ok 24 - connect with correct server CA cert file sslmode=verify-ca
[14:17:34.904](0.001s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr
[14:17:34.921](0.017s) ok 26 - connect with correct server CA cert file sslmode=verify-full
[14:17:34.922](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr
[14:17:34.939](0.017s) ok 28 - cert root file that contains two certificates, order 1
[14:17:34.939](0.001s) ok 29 - cert root file that contains two certificates, order 1: no stderr
[14:17:34.956](0.016s) ok 30 - cert root file that contains two certificates, order 2
[14:17:34.956](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr
[14:17:34.972](0.016s) ok 32 - connect with sslcertmode=disable
[14:17:34.972](0.000s) ok 33 - connect with sslcertmode=disable: no stderr
[14:17:34.986](0.014s) ok 34 - connect with sslcertmode=allow
[14:17:34.987](0.000s) ok 35 - connect with sslcertmode=allow: no stderr
[14:17:35.001](0.014s) ok 36 - connect with sslcertmode=require fails without a client certificate
[14:17:35.001](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches
[14:17:35.015](0.014s) ok 38 - sslcrl option with invalid file name
[14:17:35.015](0.000s) ok 39 - sslcrl option with invalid file name: no stderr
[14:17:35.030](0.014s) ok 40 - CRL belonging to a different CA
[14:17:35.030](0.001s) ok 41 - CRL belonging to a different CA: matches
[14:17:35.045](0.014s) ok 42 - directory CRL belonging to a different CA
[14:17:35.045](0.001s) ok 43 - directory CRL belonging to a different CA: matches
[14:17:35.063](0.018s) ok 44 - CRL with a non-revoked cert
[14:17:35.063](0.000s) ok 45 - CRL with a non-revoked cert: no stderr
[14:17:35.077](0.014s) ok 46 - directory CRL with a non-revoked cert
[14:17:35.078](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr
[14:17:35.092](0.015s) ok 48 - mismatch between host name and server certificate sslmode=require
[14:17:35.093](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr
[14:17:35.109](0.016s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca
[14:17:35.109](0.001s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr
[14:17:35.124](0.015s) ok 52 - mismatch between host name and server certificate sslmode=verify-full
[14:17:35.124](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 30161
[14:17:35.351](0.227s) ok 54 - IP address in the Common Name
[14:17:35.352](0.000s) ok 55 - IP address in the Common Name: no stderr
[14:17:35.367](0.016s) ok 56 - mismatch between host name and server certificate IP address
[14:17:35.370](0.002s) 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 30213
[14:17:35.596](0.227s) ok 58 - IP address in a dNSName
[14:17:35.597](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 30258
[14:17:35.830](0.233s) ok 60 - host name matching with X.509 Subject Alternative Names 1
[14:17:35.830](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr
[14:17:35.850](0.020s) ok 62 - host name matching with X.509 Subject Alternative Names 2
[14:17:35.851](0.001s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr
[14:17:35.868](0.018s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard
[14:17:35.869](0.001s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr
[14:17:35.882](0.013s) ok 66 - host name not matching with X.509 Subject Alternative Names
[14:17:35.882](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches
[14:17:35.894](0.012s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard
[14:17:35.895](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 30325
[14:17:36.124](0.229s) ok 70 - host name matching with a single X.509 Subject Alternative Name
[14:17:36.124](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr
[14:17:36.139](0.015s) ok 72 - host name not matching with a single X.509 Subject Alternative Name
[14:17:36.139](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches
[14:17:36.154](0.015s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard
[14:17:36.155](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 30373
[14:17:36.390](0.235s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1)
[14:17:36.390](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr
[14:17:36.405](0.014s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1)
[14:17:36.405](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr
[14:17:36.417](0.012s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1)
[14:17:36.417](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches
[14:17:36.433](0.015s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2)
[14:17:36.433](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr
[14:17:36.448](0.015s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2)
[14:17:36.449](0.001s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr
[14:17:36.470](0.021s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2)
[14:17:36.470](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr
[14:17:36.481](0.011s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2)
[14:17:36.482](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches
[14:17:36.493](0.012s) ok 90 - IPv6 host with CIDR mask does not match
[14:17:36.494](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 30461
[14:17:36.727](0.233s) ok 92 - certificate with both a CN and SANs 1
[14:17:36.727](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr
[14:17:36.742](0.014s) ok 94 - certificate with both a CN and SANs 2
[14:17:36.742](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr
[14:17:36.755](0.013s) ok 96 - certificate with both a CN and SANs ignores CN
[14:17:36.755](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 30512
[14:17:36.997](0.242s) ok 98 - certificate with both a CN and IP SANs matches CN
[14:17:36.997](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr
[14:17:37.012](0.016s) ok 100 - certificate with both a CN and IP SANs matches SAN 1
[14:17:37.013](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr
[14:17:37.030](0.017s) ok 102 - certificate with both a CN and IP SANs matches SAN 2
[14:17:37.030](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 30555
[14:17:37.266](0.236s) ok 104 - certificate with both an IP CN and IP SANs 1
[14:17:37.266](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr
[14:17:37.280](0.014s) ok 106 - certificate with both an IP CN and IP SANs 2
[14:17:37.281](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr
[14:17:37.293](0.012s) ok 108 - certificate with both an IP CN and IP SANs ignores CN
[14:17:37.294](0.001s) 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 30616
[14:17:37.521](0.227s) ok 110 - certificate with both an IP CN and DNS SANs matches CN
[14:17:37.521](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr
[14:17:37.539](0.018s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1
[14:17:37.540](0.001s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr
[14:17:37.555](0.015s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2
[14:17:37.556](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 30671
[14:17:37.780](0.224s) ok 116 - server certificate without CN or SANs sslmode=verify-ca
[14:17:37.780](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr
[14:17:37.794](0.014s) ok 118 - server certificate without CN or SANs sslmode=verify-full
[14:17:37.794](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 30719
[14:17:38.014](0.220s) ok 120 - sslrootcert=system does not connect with private CA
[14:17:38.015](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches
[14:17:38.023](0.008s) ok 122 - sslrootcert=system only accepts sslmode=verify-full
[14:17:38.023](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches
[14:17:38.044](0.021s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE
[14:17:38.044](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr
[14:17:38.059](0.015s) ok 126 - sslrootcert=system defaults to sslmode=verify-full
[14:17:38.060](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 30771
[14:17:38.287](0.227s) ok 128 - connects without client-side CRL
[14:17:38.287](0.000s) ok 129 - connects without client-side CRL: no stderr
[14:17:38.301](0.014s) ok 130 - does not connect with client-side CRL file
[14:17:38.302](0.000s) ok 131 - does not connect with client-side CRL file: matches
[14:17:38.317](0.015s) ok 132 - does not connect with client-side CRL directory
[14:17:38.317](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()
[14:17:38.341](0.024s) ok 134 - pg_stat_ssl view without client certificate: exit code 0
[14:17:38.342](0.001s) not ok 135 - pg_stat_ssl view without client certificate: no stderr
[14:17:38.342](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.
[14:17:38.342](0.000s) #          got: 'WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
# '
#     expected: ''
[14:17:38.342](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches
[14:17:38.356](0.014s) ok 137 - connection success with correct range of TLS protocol versions
[14:17:38.356](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr
[14:17:38.365](0.008s) ok 139 - connection failure with incorrect range of TLS protocol versions
[14:17:38.365](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches
[14:17:38.373](0.008s) ok 141 - connection failure with an incorrect SSL protocol minimum bound
[14:17:38.373](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches
[14:17:38.381](0.008s) ok 143 - connection failure with an incorrect SSL protocol maximum bound
[14:17:38.382](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches
[14:17:38.382](0.000s) # running server tests
[14:17:38.396](0.014s) ok 145 - certificate authorization fails without client cert
[14:17:38.396](0.000s) ok 146 - certificate authorization fails without client cert: matches
[14:17:38.416](0.020s) ok 147 - certificate authorization succeeds with correct client cert in PEM format
[14:17:38.416](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr
[14:17:38.434](0.018s) ok 149 - certificate authorization succeeds with correct client cert in DER format
[14:17:38.435](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr
[14:17:38.464](0.029s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format
[14:17:38.464](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr
[14:17:38.482](0.018s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format
[14:17:38.482](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr
[14:17:38.499](0.017s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require
[14:17:38.500](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr
[14:17:38.516](0.017s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow
[14:17:38.517](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr
[14:17:38.532](0.016s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable
[14:17:38.533](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches
[14:17:38.545](0.012s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format
[14:17:38.545](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches
[14:17:38.575](0.030s) ok 163 - certificate authorization succeeds with DN mapping
[14:17:38.577](0.002s) ok 164 - certificate authorization succeeds with DN mapping: no stderr
[14:17:38.578](0.001s) ok 165 - certificate authorization succeeds with DN mapping: log matches
[14:17:38.603](0.025s) ok 166 - certificate authorization succeeds with DN regex mapping
[14:17:38.603](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr
[14:17:38.623](0.020s) ok 168 - certificate authorization succeeds with CN mapping
[14:17:38.623](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr
[14:17:38.623](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches
[14:17:38.624](0.000s) not ok 171 # TODO & SKIP Need Pty support
[14:17:38.624](0.000s) not ok 172 # TODO & SKIP Need Pty support
[14:17:38.624](0.000s) not ok 173 # TODO & SKIP Need Pty support
[14:17:38.624](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_mN7S/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
[14:17:38.657](0.033s) ok 175 - pg_stat_ssl with client certificate: exit code 0
[14:17:38.657](0.000s) not ok 176 - pg_stat_ssl with client certificate: no stderr
[14:17:38.658](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.
[14:17:38.658](0.000s) #          got: 'WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
# '
#     expected: ''
[14:17:38.658](0.000s) ok 177 - pg_stat_ssl with client certificate: matches
[14:17:38.669](0.011s) ok 178 - certificate authorization fails because of file permissions
[14:17:38.670](0.000s) ok 179 - certificate authorization fails because of file permissions: matches
[14:17:38.687](0.017s) ok 180 - certificate authorization fails with client cert belonging to another user
[14:17:38.688](0.002s) ok 181 - certificate authorization fails with client cert belonging to another user: matches
[14:17:38.711](0.023s) ok 182 - certificate authorization fails with revoked client cert
[14:17:38.711](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches
[14:17:38.712](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match
[14:17:38.732](0.021s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name
[14:17:38.733](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr
[14:17:38.733](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches
[14:17:38.747](0.014s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name
[14:17:38.747](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches
[14:17:38.747](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match
[14:17:38.765](0.018s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name
[14:17:38.766](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr
[14:17:38.766](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 30947
[14:17:38.995](0.229s) ok 194 - intermediate client certificate is provided by client
[14:17:38.995](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr
[14:17:39.012](0.017s) ok 196 - intermediate client certificate is missing
[14:17:39.012](0.000s) ok 197 - intermediate client certificate is missing: matches
[14:17:39.030](0.018s) ok 198 - logged client certificate Subjects are truncated if they're too long
[14:17:39.031](0.001s) 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 30992
[14:17:39.262](0.231s) ok 200 - intermediate client certificate is untrusted
[14:17:39.263](0.001s) 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 31036
[14:17:39.494](0.231s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory
[14:17:39.494](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches
[14:17:39.520](0.026s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory
[14:17:39.521](0.001s) ok 205 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory: matches
[14:17:39.521](0.001s) 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"
[14:17:39.627](0.106s) # Looks like you failed 2 tests of 205.