[15:19:20.761](0.079s) # setting up data directory
# Checking port 60027
# Found port 60027
Name: primary
Data directory: /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata
Backup directory: /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/backup
Archive directory: /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/archives
Connection string: port=60027 host=/tmp/R1aHYRw9Ix
Log file: /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log
[15:19:20.777](0.016s) # initializing database system by copying initdb template
# Running: cp -RPp /tmp/cirrus-ci-build/tmp_install/initdb-template /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata
# Running: /tmp/cirrus-ci-build/src/test/ssl/../../../src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata
### Starting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log -o --cluster-name=primary start
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 32150
[15:19:21.195](0.417s) ok 1 - ssl_library parameter
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 32386
[15:19:22.750](1.556s) # testing password-protected keys
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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-20 15:19:21.026 UTC [32150][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:21.026 UTC [32150][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:21.029 UTC [32158][startup] LOG:  database system was shut down at 2024-03-20 15:17:16 UTC
2024-03-20 15:19:21.036 UTC [32150][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:21.152 UTC [32185][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.152 UTC [32185][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.152 UTC [32185][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.192 UTC [32185][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-20 15:19:21.196 UTC [32185][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.044 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.216 UTC [32199][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.217 UTC [32199][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.217 UTC [32199][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.219 UTC [32199][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-20 15:19:21.221 UTC [32199][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.285 UTC [32214][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.285 UTC [32214][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.285 UTC [32214][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.292 UTC [32214][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-20 15:19:21.294 UTC [32214][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.398 UTC [32232][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.399 UTC [32232][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.399 UTC [32232][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.406 UTC [32232][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-20 15:19:21.408 UTC [32232][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.440 UTC [32237][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.441 UTC [32237][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.441 UTC [32237][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.450 UTC [32237][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-20 15:19:21.450 UTC [32237][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.501 UTC [32241][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.502 UTC [32241][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.502 UTC [32241][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.503 UTC [32241][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-20 15:19:21.586 UTC [32241][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.089 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.638 UTC [32257][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.639 UTC [32257][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.639 UTC [32257][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.643 UTC [32257][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-20 15:19:21.743 UTC [32257][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.106 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.774 UTC [32277][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.778 UTC [32277][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.778 UTC [32277][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.779 UTC [32277][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-20 15:19:21.864 UTC [32277][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.090 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.895 UTC [32291][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.896 UTC [32291][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.896 UTC [32291][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.898 UTC [32291][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-20 15:19:22.009 UTC [32291][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.113 user=postgres database=postgres host=[local]
2024-03-20 15:19:22.034 UTC [32318][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:22.035 UTC [32318][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:22.035 UTC [32318][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:22.039 UTC [32318][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-20 15:19:22.098 UTC [32318][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.064 user=postgres database=postgres host=[local]
2024-03-20 15:19:22.172 UTC [32347][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:22.173 UTC [32347][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:22.173 UTC [32347][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:22.178 UTC [32347][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-20 15:19:22.281 UTC [32347][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.110 user=postgres database=postgres host=[local]
2024-03-20 15:19:22.453 UTC [32150][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:22.453 UTC [32150][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:22.464 UTC [32150][postmaster] LOG:  background worker "logical replication launcher" (PID 32161) exited with exit code 1
2024-03-20 15:19:22.464 UTC [32156][checkpointer] LOG:  shutting down
2024-03-20 15:19:22.464 UTC [32156][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:22.570 UTC [32156][checkpointer] LOG:  checkpoint complete: wrote 5644 buffers (34.4%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.106 s, sync=0.001 s, total=0.106 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45431 kB, estimate=45431 kB; lsn=0/4187898, redo lsn=0/4187898
2024-03-20 15:19:22.590 UTC [32150][postmaster] LOG:  database system is shut down
2024-03-20 15:19:22.694 UTC [32386][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:22.694 UTC [32386][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:22.694 UTC [32386][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:22.702 UTC [32393][startup] LOG:  database system was shut down at 2024-03-20 15:19:22 UTC
2024-03-20 15:19:22.709 UTC [32386][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:22.759 UTC [32386][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:22.759 UTC [32386][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:22.762 UTC [32386][postmaster] LOG:  background worker "logical replication launcher" (PID 32396) exited with exit code 1
2024-03-20 15:19:22.765 UTC [32391][checkpointer] LOG:  shutting down
2024-03-20 15:19:22.765 UTC [32391][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:22.765 UTC [32391][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/4187910, redo lsn=0/4187910
2024-03-20 15:19:22.770 UTC [32386][postmaster] LOG:  database system is shut down
2024-03-20 15:19:22.912 UTC [32417][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-20 15:19:22.912 UTC [32417][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[15:19:22.961](0.211s) ok 2 - restart fails with password-protected key file with wrong password
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
pg_ctl: PID file "/tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 32434
[15:19:23.081](0.120s) ok 3 - restart succeeds with password-protected key file
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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-20 15:19:21.026 UTC [32150][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:21.026 UTC [32150][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:21.029 UTC [32158][startup] LOG:  database system was shut down at 2024-03-20 15:17:16 UTC
2024-03-20 15:19:21.036 UTC [32150][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:21.152 UTC [32185][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.152 UTC [32185][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.152 UTC [32185][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.192 UTC [32185][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-20 15:19:21.196 UTC [32185][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.044 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.216 UTC [32199][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.217 UTC [32199][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.217 UTC [32199][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.219 UTC [32199][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-20 15:19:21.221 UTC [32199][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.285 UTC [32214][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.285 UTC [32214][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.285 UTC [32214][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.292 UTC [32214][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-20 15:19:21.294 UTC [32214][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.398 UTC [32232][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.399 UTC [32232][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.399 UTC [32232][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.406 UTC [32232][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-20 15:19:21.408 UTC [32232][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.440 UTC [32237][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.441 UTC [32237][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.441 UTC [32237][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.450 UTC [32237][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-20 15:19:21.450 UTC [32237][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.501 UTC [32241][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.502 UTC [32241][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.502 UTC [32241][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.503 UTC [32241][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-20 15:19:21.586 UTC [32241][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.089 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.638 UTC [32257][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.639 UTC [32257][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.639 UTC [32257][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.643 UTC [32257][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-20 15:19:21.743 UTC [32257][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.106 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.774 UTC [32277][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.778 UTC [32277][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.778 UTC [32277][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.779 UTC [32277][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-20 15:19:21.864 UTC [32277][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.090 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.895 UTC [32291][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.896 UTC [32291][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.896 UTC [32291][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.898 UTC [32291][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-20 15:19:22.009 UTC [32291][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.113 user=postgres database=postgres host=[local]
2024-03-20 15:19:22.034 UTC [32318][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:22.035 UTC [32318][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:22.035 UTC [32318][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:22.039 UTC [32318][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-20 15:19:22.098 UTC [32318][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.064 user=postgres database=postgres host=[local]
2024-03-20 15:19:22.172 UTC [32347][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:22.173 UTC [32347][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:22.173 UTC [32347][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:22.178 UTC [32347][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-20 15:19:22.281 UTC [32347][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.110 user=postgres database=postgres host=[local]
2024-03-20 15:19:22.453 UTC [32150][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:22.453 UTC [32150][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:22.464 UTC [32150][postmaster] LOG:  background worker "logical replication launcher" (PID 32161) exited with exit code 1
2024-03-20 15:19:22.464 UTC [32156][checkpointer] LOG:  shutting down
2024-03-20 15:19:22.464 UTC [32156][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:22.570 UTC [32156][checkpointer] LOG:  checkpoint complete: wrote 5644 buffers (34.4%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.106 s, sync=0.001 s, total=0.106 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45431 kB, estimate=45431 kB; lsn=0/4187898, redo lsn=0/4187898
2024-03-20 15:19:22.590 UTC [32150][postmaster] LOG:  database system is shut down
2024-03-20 15:19:22.694 UTC [32386][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:22.694 UTC [32386][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:22.694 UTC [32386][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:22.702 UTC [32393][startup] LOG:  database system was shut down at 2024-03-20 15:19:22 UTC
2024-03-20 15:19:22.709 UTC [32386][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:22.759 UTC [32386][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:22.759 UTC [32386][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:22.762 UTC [32386][postmaster] LOG:  background worker "logical replication launcher" (PID 32396) exited with exit code 1
2024-03-20 15:19:22.765 UTC [32391][checkpointer] LOG:  shutting down
2024-03-20 15:19:22.765 UTC [32391][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:22.765 UTC [32391][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/4187910, redo lsn=0/4187910
2024-03-20 15:19:22.770 UTC [32386][postmaster] LOG:  database system is shut down
2024-03-20 15:19:22.912 UTC [32417][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-20 15:19:22.912 UTC [32417][postmaster] LOG:  database system is shut down
2024-03-20 15:19:23.043 UTC [32434][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:23.043 UTC [32434][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:23.044 UTC [32434][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:23.063 UTC [32447][startup] LOG:  database system was shut down at 2024-03-20 15:19:22 UTC
2024-03-20 15:19:23.075 UTC [32434][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:23.092 UTC [32434][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:23.092 UTC [32434][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:23.097 UTC [32434][postmaster] LOG:  background worker "logical replication launcher" (PID 32458) exited with exit code 1
2024-03-20 15:19:23.100 UTC [32443][checkpointer] LOG:  shutting down
2024-03-20 15:19:23.100 UTC [32443][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:23.101 UTC [32443][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/4187988, redo lsn=0/4187988
2024-03-20 15:19:23.106 UTC [32434][postmaster] LOG:  database system is shut down
2024-03-20 15:19:23.249 UTC [32478][postmaster] FATAL:  could not set SSL protocol version range
2024-03-20 15:19:23.249 UTC [32478][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-20 15:19:23.249 UTC [32478][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[15:19:23.304](0.223s) ok 4 - restart fails with incorrect SSL protocol bounds
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log restart
pg_ctl: PID file "/tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 32495
[15:19:23.413](0.109s) ok 5 - restart succeeds with correct SSL protocol bounds
[15:19:23.413](0.000s) # running client tests
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 32536
[15:19:23.658](0.245s) ok 6 - server doesn't accept non-SSL connections
[15:19:23.659](0.000s) ok 7 - server doesn't accept non-SSL connections: matches
[15:19:23.733](0.075s) ok 8 - connect without server root cert sslmode=require
[15:19:23.734](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr
[15:19:23.769](0.035s) ok 10 - connect without server root cert sslmode=verify-ca
[15:19:23.769](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches
[15:19:23.799](0.029s) ok 12 - connect without server root cert sslmode=verify-full
[15:19:23.799](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches
[15:19:23.878](0.079s) ok 14 - connect with wrong server root cert sslmode=require
[15:19:23.878](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches
[15:19:23.926](0.048s) ok 16 - connect with wrong server root cert sslmode=verify-ca
[15:19:23.927](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches
[15:19:23.967](0.040s) ok 18 - connect with wrong server root cert sslmode=verify-full
[15:19:23.967](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches
[15:19:24.008](0.041s) ok 20 - connect with server CA cert, without root CA
[15:19:24.008](0.000s) ok 21 - connect with server CA cert, without root CA: matches
[15:19:24.088](0.080s) ok 22 - connect with correct server CA cert file sslmode=require
[15:19:24.088](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr
[15:19:24.136](0.048s) ok 24 - connect with correct server CA cert file sslmode=verify-ca
[15:19:24.137](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr
[15:19:24.163](0.026s) ok 26 - connect with correct server CA cert file sslmode=verify-full
[15:19:24.163](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr
[15:19:24.242](0.079s) ok 28 - cert root file that contains two certificates, order 1
[15:19:24.242](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr
[15:19:24.290](0.048s) ok 30 - cert root file that contains two certificates, order 2
[15:19:24.290](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr
[15:19:24.337](0.047s) ok 32 - connect with sslcertmode=disable
[15:19:24.337](0.000s) ok 33 - connect with sslcertmode=disable: no stderr
[15:19:24.385](0.048s) ok 34 - connect with sslcertmode=allow
[15:19:24.386](0.000s) ok 35 - connect with sslcertmode=allow: no stderr
[15:19:24.424](0.039s) ok 36 - connect with sslcertmode=require fails without a client certificate
[15:19:24.425](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches
[15:19:24.488](0.064s) ok 38 - sslcrl option with invalid file name
[15:19:24.489](0.000s) ok 39 - sslcrl option with invalid file name: no stderr
[15:19:24.535](0.047s) ok 40 - CRL belonging to a different CA
[15:19:24.536](0.000s) ok 41 - CRL belonging to a different CA: matches
[15:19:24.563](0.027s) ok 42 - directory CRL belonging to a different CA
[15:19:24.563](0.000s) ok 43 - directory CRL belonging to a different CA: matches
[15:19:24.595](0.032s) ok 44 - CRL with a non-revoked cert
[15:19:24.595](0.000s) ok 45 - CRL with a non-revoked cert: no stderr
[15:19:24.632](0.037s) ok 46 - directory CRL with a non-revoked cert
[15:19:24.633](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr
[15:19:24.675](0.042s) ok 48 - mismatch between host name and server certificate sslmode=require
[15:19:24.675](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr
[15:19:24.747](0.072s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca
[15:19:24.747](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr
[15:19:24.774](0.026s) ok 52 - mismatch between host name and server certificate sslmode=verify-full
[15:19:24.774](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 32857
[15:19:25.067](0.293s) ok 54 - IP address in the Common Name
[15:19:25.067](0.000s) ok 55 - IP address in the Common Name: no stderr
[15:19:25.094](0.027s) ok 56 - mismatch between host name and server certificate IP address
[15:19:25.094](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 32910
[15:19:25.468](0.374s) ok 58 - IP address in a dNSName
[15:19:25.469](0.000s) ok 59 - IP address in a dNSName: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 32972
[15:19:25.783](0.314s) ok 60 - host name matching with X.509 Subject Alternative Names 1
[15:19:25.783](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr
[15:19:25.838](0.055s) ok 62 - host name matching with X.509 Subject Alternative Names 2
[15:19:25.838](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr
[15:19:25.881](0.042s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard
[15:19:25.881](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr
[15:19:25.929](0.048s) ok 66 - host name not matching with X.509 Subject Alternative Names
[15:19:25.929](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches
[15:19:25.969](0.041s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard
[15:19:25.970](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 33055
[15:19:26.355](0.386s) ok 70 - host name matching with a single X.509 Subject Alternative Name
[15:19:26.356](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr
[15:19:26.399](0.043s) ok 72 - host name not matching with a single X.509 Subject Alternative Name
[15:19:26.399](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches
[15:19:26.459](0.060s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard
[15:19:26.459](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 33155
[15:19:26.737](0.279s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1)
[15:19:26.738](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr
[15:19:26.797](0.059s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1)
[15:19:26.797](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr
[15:19:26.875](0.078s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1)
[15:19:26.876](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches
[15:19:26.965](0.089s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2)
[15:19:26.965](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr
[15:19:27.043](0.078s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2)
[15:19:27.043](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr
[15:19:27.101](0.057s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2)
[15:19:27.101](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr
[15:19:27.179](0.078s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2)
[15:19:27.179](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches
[15:19:27.214](0.035s) ok 90 - IPv6 host with CIDR mask does not match
[15:19:27.214](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 33296
[15:19:27.609](0.394s) ok 92 - certificate with both a CN and SANs 1
[15:19:27.609](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr
[15:19:27.667](0.058s) ok 94 - certificate with both a CN and SANs 2
[15:19:27.667](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr
[15:19:27.691](0.024s) ok 96 - certificate with both a CN and SANs ignores CN
[15:19:27.691](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 33348
[15:19:27.921](0.230s) ok 98 - certificate with both a CN and IP SANs matches CN
[15:19:27.921](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr
[15:19:27.976](0.055s) ok 100 - certificate with both a CN and IP SANs matches SAN 1
[15:19:27.977](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr
[15:19:28.049](0.073s) ok 102 - certificate with both a CN and IP SANs matches SAN 2
[15:19:28.049](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 33423
[15:19:28.376](0.326s) ok 104 - certificate with both an IP CN and IP SANs 1
[15:19:28.376](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr
[15:19:28.443](0.067s) ok 106 - certificate with both an IP CN and IP SANs 2
[15:19:28.443](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr
[15:19:28.547](0.103s) ok 108 - certificate with both an IP CN and IP SANs ignores CN
[15:19:28.547](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 33473
[15:19:28.910](0.362s) ok 110 - certificate with both an IP CN and DNS SANs matches CN
[15:19:28.910](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr
[15:19:28.973](0.063s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1
[15:19:28.974](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr
[15:19:29.058](0.085s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2
[15:19:29.059](0.000s) ok 115 - certificate with both an IP CN and DNS SANs matches SAN 2: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 33551
[15:19:29.379](0.321s) ok 116 - server certificate without CN or SANs sslmode=verify-ca
[15:19:29.380](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr
[15:19:29.443](0.064s) ok 118 - server certificate without CN or SANs sslmode=verify-full
[15:19:29.444](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 33618
[15:19:30.362](0.919s) ok 120 - sslrootcert=system does not connect with private CA
[15:19:30.363](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches
[15:19:30.413](0.051s) ok 122 - sslrootcert=system only accepts sslmode=verify-full
[15:19:30.414](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches
[15:19:30.546](0.132s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE
[15:19:30.546](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr
[15:19:30.603](0.056s) ok 126 - sslrootcert=system defaults to sslmode=verify-full
[15:19:30.603](0.000s) ok 127 - sslrootcert=system defaults to sslmode=verify-full: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 33714
[15:19:30.862](0.259s) ok 128 - connects without client-side CRL
[15:19:30.862](0.000s) ok 129 - connects without client-side CRL: no stderr
[15:19:30.925](0.064s) ok 130 - does not connect with client-side CRL file
[15:19:30.926](0.000s) ok 131 - does not connect with client-side CRL file: matches
[15:19:30.991](0.066s) ok 132 - does not connect with client-side CRL directory
[15:19:30.992](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()
[15:19:31.050](0.058s) ok 134 - pg_stat_ssl view without client certificate: exit code 0
[15:19:31.050](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr
[15:19:31.050](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches
[15:19:31.097](0.047s) ok 137 - connection success with correct range of TLS protocol versions
[15:19:31.097](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr
[15:19:31.114](0.016s) ok 139 - connection failure with incorrect range of TLS protocol versions
[15:19:31.114](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches
[15:19:31.139](0.025s) ok 141 - connection failure with an incorrect SSL protocol minimum bound
[15:19:31.139](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches
[15:19:31.167](0.028s) ok 143 - connection failure with an incorrect SSL protocol maximum bound
[15:19:31.167](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches
[15:19:31.168](0.000s) # running server tests
[15:19:31.236](0.068s) ok 145 - certificate authorization fails without client cert
[15:19:31.236](0.000s) ok 146 - certificate authorization fails without client cert: matches
[15:19:31.301](0.065s) ok 147 - certificate authorization succeeds with correct client cert in PEM format
[15:19:31.301](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr
[15:19:31.368](0.067s) ok 149 - certificate authorization succeeds with correct client cert in DER format
[15:19:31.368](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr
[15:19:31.417](0.049s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format
[15:19:31.417](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr
[15:19:31.473](0.056s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format
[15:19:31.474](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr
[15:19:31.520](0.046s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require
[15:19:31.520](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr
[15:19:31.572](0.052s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow
[15:19:31.573](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr
[15:19:31.630](0.057s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable
[15:19:31.630](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches
[15:19:31.675](0.045s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format
[15:19:31.675](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches
[15:19:31.734](0.058s) ok 163 - certificate authorization succeeds with DN mapping
[15:19:31.734](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr
[15:19:31.734](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches
[15:19:31.813](0.079s) ok 166 - certificate authorization succeeds with DN regex mapping
[15:19:31.814](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr
[15:19:31.890](0.076s) ok 168 - certificate authorization succeeds with CN mapping
[15:19:31.890](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr
[15:19:31.890](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches
[15:19:31.891](0.000s) not ok 171 # TODO & SKIP Need Pty support
[15:19:31.891](0.000s) not ok 172 # TODO & SKIP Need Pty support
[15:19:31.891](0.000s) not ok 173 # TODO & SKIP Need Pty support
[15:19:31.891](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/src/test/ssl/tmp_check/tmp_test_rnyY/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
[15:19:31.961](0.070s) ok 175 - pg_stat_ssl with client certificate: exit code 0
[15:19:31.961](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr
[15:19:31.961](0.000s) ok 177 - pg_stat_ssl with client certificate: matches
[15:19:32.002](0.041s) ok 178 - certificate authorization fails because of file permissions
[15:19:32.003](0.000s) ok 179 - certificate authorization fails because of file permissions: matches
[15:19:32.059](0.056s) ok 180 - certificate authorization fails with client cert belonging to another user
[15:19:32.059](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches
[15:19:32.111](0.052s) ok 182 - certificate authorization fails with revoked client cert
[15:19:32.111](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches
[15:19:32.111](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match
[15:19:32.189](0.077s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name
[15:19:32.189](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr
[15:19:32.189](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches
[15:19:32.250](0.061s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name
[15:19:32.250](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches
[15:19:32.250](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match
[15:19:32.306](0.056s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name
[15:19:32.306](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr
[15:19:32.306](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 34040
[15:19:32.589](0.282s) ok 194 - intermediate client certificate is provided by client
[15:19:32.589](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr
[15:19:32.629](0.040s) ok 196 - intermediate client certificate is missing
[15:19:32.629](0.000s) ok 197 - intermediate client certificate is missing: matches
[15:19:32.683](0.053s) ok 198 - logged client certificate Subjects are truncated if they're too long
[15:19:32.683](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/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 34113
[15:19:32.946](0.263s) ok 200 - intermediate client certificate is untrusted
[15:19:32.946](0.000s) ok 201 - intermediate client certificate is untrusted: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 34147
[15:19:33.209](0.263s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory
[15:19:33.209](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches
[15:19:33.239](0.030s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory
[15:19:33.240](0.000s) ok 205 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory: matches
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 34191
[15:19:33.498](0.259s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1
[15:19:33.499](0.000s) 
[15:19:33.499](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1'
#   at t/001_ssltests.pl line 923.
[15:19:33.499](0.000s) #          got: '2'
#     expected: '0'
[15:19:33.499](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[15:19:33.499](0.000s) 
[15:19:33.499](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr'
#   at t/001_ssltests.pl line 923.
[15:19:33.499](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 60027 failed: server closed the connection unexpectedly
# 	This probably means the server terminated abnormally
# 	before or while processing the request.
# SSL SYSCALL error: Connection reset by peer'
#     expected: ''
[15:19:33.546](0.046s) ok 208 - connect without requesting ocsp response when sslocspstapling=0
[15:19:33.546](0.000s) ok 209 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 34263
[15:19:33.794](0.248s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1
[15:19:33.855](0.061s) ok 211 - connect without requesting ocsp response when sslocspstapling=0
[15:19:33.855](0.000s) ok 212 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 34326
[15:19:34.229](0.374s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1
[15:19:34.276](0.046s) ok 214 - connect without requesting ocsp response when sslocspstapling=0
[15:19:34.276](0.000s) ok 215 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 34400
[15:19:34.568](0.292s) ok 216 - failed with an expired ocsp response when sslocspstapling=1
[15:19:34.673](0.106s) ok 217 - connect without requesting ocsp response when sslocspstapling=0
[15:19:34.674](0.000s) ok 218 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 34451
[15:19:34.953](0.280s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1
[15:19:34.953](0.000s) 
[15:19:34.953](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1'
#   at t/001_ssltests.pl line 1001.
[15:19:34.954](0.000s) #          got: '2'
#     expected: '0'
[15:19:34.954](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[15:19:34.954](0.000s) 
[15:19:34.954](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr'
#   at t/001_ssltests.pl line 1001.
[15:19:34.954](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 60027 failed: SSL SYSCALL error: EOF detected'
#     expected: ''
[15:19:35.033](0.079s) ok 221 - connect without requesting ocsp response when sslocspstapling=0
[15:19:35.033](0.000s) ok 222 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 34510
[15:19:35.298](0.265s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1
[15:19:35.350](0.053s) ok 224 - connect without requesting ocsp response when sslocspstapling=0
[15:19:35.351](0.000s) ok 225 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 34574
[15:19:35.616](0.265s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1
[15:19:35.701](0.085s) ok 227 - connect without requesting ocsp response when sslocspstapling=0
[15:19:35.701](0.000s) ok 228 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 34626
[15:19:35.971](0.270s) ok 229 - failed with an expired ocsp response when sslocspstapling=1
[15:19:36.015](0.044s) ok 230 - connect without requesting ocsp response when sslocspstapling=0
[15:19:36.016](0.000s) ok 231 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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 34709
[15:19:36.268](0.252s) ok 232 - failed with an expired ocsp response when sslocspstapling=1
[15:19:36.349](0.081s) ok 233 - connect without requesting ocsp response when sslocspstapling=0
[15:19:36.349](0.000s) ok 234 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/src/test/ssl/tmp_check/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-20 15:19:21.026 UTC [32150][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:21.026 UTC [32150][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:21.029 UTC [32158][startup] LOG:  database system was shut down at 2024-03-20 15:17:16 UTC
2024-03-20 15:19:21.036 UTC [32150][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:21.152 UTC [32185][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.152 UTC [32185][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.152 UTC [32185][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.192 UTC [32185][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-20 15:19:21.196 UTC [32185][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.044 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.216 UTC [32199][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.217 UTC [32199][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.217 UTC [32199][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.219 UTC [32199][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-20 15:19:21.221 UTC [32199][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.285 UTC [32214][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.285 UTC [32214][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.285 UTC [32214][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.292 UTC [32214][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-20 15:19:21.294 UTC [32214][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.398 UTC [32232][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.399 UTC [32232][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.399 UTC [32232][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.406 UTC [32232][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-20 15:19:21.408 UTC [32232][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.440 UTC [32237][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.441 UTC [32237][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.441 UTC [32237][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.450 UTC [32237][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-20 15:19:21.450 UTC [32237][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.501 UTC [32241][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.502 UTC [32241][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.502 UTC [32241][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.503 UTC [32241][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-20 15:19:21.586 UTC [32241][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.089 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.638 UTC [32257][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.639 UTC [32257][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.639 UTC [32257][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.643 UTC [32257][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-20 15:19:21.743 UTC [32257][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.106 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.774 UTC [32277][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.778 UTC [32277][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.778 UTC [32277][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.779 UTC [32277][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-20 15:19:21.864 UTC [32277][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.090 user=postgres database=postgres host=[local]
2024-03-20 15:19:21.895 UTC [32291][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:21.896 UTC [32291][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:21.896 UTC [32291][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:21.898 UTC [32291][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-20 15:19:22.009 UTC [32291][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.113 user=postgres database=postgres host=[local]
2024-03-20 15:19:22.034 UTC [32318][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:22.035 UTC [32318][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:22.035 UTC [32318][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:22.039 UTC [32318][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-20 15:19:22.098 UTC [32318][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.064 user=postgres database=postgres host=[local]
2024-03-20 15:19:22.172 UTC [32347][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-20 15:19:22.173 UTC [32347][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-20 15:19:22.173 UTC [32347][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-20 15:19:22.178 UTC [32347][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-20 15:19:22.281 UTC [32347][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.110 user=postgres database=postgres host=[local]
2024-03-20 15:19:22.453 UTC [32150][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:22.453 UTC [32150][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:22.464 UTC [32150][postmaster] LOG:  background worker "logical replication launcher" (PID 32161) exited with exit code 1
2024-03-20 15:19:22.464 UTC [32156][checkpointer] LOG:  shutting down
2024-03-20 15:19:22.464 UTC [32156][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:22.570 UTC [32156][checkpointer] LOG:  checkpoint complete: wrote 5644 buffers (34.4%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.106 s, sync=0.001 s, total=0.106 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45431 kB, estimate=45431 kB; lsn=0/4187898, redo lsn=0/4187898
2024-03-20 15:19:22.590 UTC [32150][postmaster] LOG:  database system is shut down
2024-03-20 15:19:22.694 UTC [32386][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:22.694 UTC [32386][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:22.694 UTC [32386][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:22.702 UTC [32393][startup] LOG:  database system was shut down at 2024-03-20 15:19:22 UTC
2024-03-20 15:19:22.709 UTC [32386][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:22.759 UTC [32386][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:22.759 UTC [32386][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:22.762 UTC [32386][postmaster] LOG:  background worker "logical replication launcher" (PID 32396) exited with exit code 1
2024-03-20 15:19:22.765 UTC [32391][checkpointer] LOG:  shutting down
2024-03-20 15:19:22.765 UTC [32391][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:22.765 UTC [32391][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/4187910, redo lsn=0/4187910
2024-03-20 15:19:22.770 UTC [32386][postmaster] LOG:  database system is shut down
2024-03-20 15:19:22.912 UTC [32417][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-20 15:19:22.912 UTC [32417][postmaster] LOG:  database system is shut down
2024-03-20 15:19:23.043 UTC [32434][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:23.043 UTC [32434][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:23.044 UTC [32434][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:23.063 UTC [32447][startup] LOG:  database system was shut down at 2024-03-20 15:19:22 UTC
2024-03-20 15:19:23.075 UTC [32434][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:23.092 UTC [32434][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:23.092 UTC [32434][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:23.097 UTC [32434][postmaster] LOG:  background worker "logical replication launcher" (PID 32458) exited with exit code 1
2024-03-20 15:19:23.100 UTC [32443][checkpointer] LOG:  shutting down
2024-03-20 15:19:23.100 UTC [32443][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:23.101 UTC [32443][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/4187988, redo lsn=0/4187988
2024-03-20 15:19:23.106 UTC [32434][postmaster] LOG:  database system is shut down
2024-03-20 15:19:23.249 UTC [32478][postmaster] FATAL:  could not set SSL protocol version range
2024-03-20 15:19:23.249 UTC [32478][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-20 15:19:23.249 UTC [32478][postmaster] LOG:  database system is shut down
2024-03-20 15:19:23.343 UTC [32495][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:23.343 UTC [32495][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:23.343 UTC [32495][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:23.359 UTC [32505][startup] LOG:  database system was shut down at 2024-03-20 15:19:23 UTC
2024-03-20 15:19:23.366 UTC [32495][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:23.423 UTC [32495][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:23.423 UTC [32495][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:23.428 UTC [32495][postmaster] LOG:  background worker "logical replication launcher" (PID 32509) exited with exit code 1
2024-03-20 15:19:23.431 UTC [32503][checkpointer] LOG:  shutting down
2024-03-20 15:19:23.431 UTC [32503][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:23.432 UTC [32503][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/4187A00, redo lsn=0/4187A00
2024-03-20 15:19:23.437 UTC [32495][postmaster] LOG:  database system is shut down
2024-03-20 15:19:23.550 UTC [32536][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:23.550 UTC [32536][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:23.550 UTC [32536][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:23.555 UTC [32544][startup] LOG:  database system was shut down at 2024-03-20 15:19:23 UTC
2024-03-20 15:19:23.563 UTC [32536][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:23.655 UTC [32580][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=57914
2024-03-20 15:19:23.656 UTC [32580][client backend] [[unknown]][0/1:0] FATAL:  no pg_hba.conf entry for host "127.0.0.1", user "ssltestuser", database "trustdb", no encryption
2024-03-20 15:19:23.656 UTC [32580][client backend] [[unknown]][0/1:0] DETAIL:  Client IP address resolved to "localhost", forward lookup not checked.
2024-03-20 15:19:23.695 UTC [32590][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=57926
2024-03-20 15:19:23.708 UTC [32590][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:23.708 UTC [32590][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:23.725 UTC [32590][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid sslmode=require$$
2024-03-20 15:19:23.733 UTC [32590][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.039 user=ssltestuser database=trustdb host=localhost port=57926
2024-03-20 15:19:23.764 UTC [32606][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=57928
2024-03-20 15:19:23.768 UTC [32606][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-20 15:19:23.791 UTC [32616][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=57930
2024-03-20 15:19:23.797 UTC [32616][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-20 15:19:23.864 UTC [32640][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=57940
2024-03-20 15:19:23.878 UTC [32640][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-20 15:19:23.898 UTC [32656][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=57950
2024-03-20 15:19:23.917 UTC [32656][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-20 15:19:23.946 UTC [32668][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=57962
2024-03-20 15:19:23.959 UTC [32668][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-20 15:19:23.999 UTC [32681][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=57966
2024-03-20 15:19:24.007 UTC [32681][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-20 15:19:24.046 UTC [32686][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=57968
2024-03-20 15:19:24.082 UTC [32686][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:24.082 UTC [32686][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:24.084 UTC [32686][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require$$
2024-03-20 15:19:24.089 UTC [32686][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.045 user=ssltestuser database=trustdb host=localhost port=57968
2024-03-20 15:19:24.113 UTC [32699][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=57976
2024-03-20 15:19:24.126 UTC [32699][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:24.126 UTC [32699][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:24.128 UTC [32699][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$
2024-03-20 15:19:24.128 UTC [32699][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=57976
2024-03-20 15:19:24.151 UTC [32713][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=57988
2024-03-20 15:19:24.157 UTC [32713][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:24.157 UTC [32713][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:24.159 UTC [32713][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-full$$
2024-03-20 15:19:24.162 UTC [32713][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=57988
2024-03-20 15:19:24.213 UTC [32722][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=57998
2024-03-20 15:19:24.226 UTC [32722][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:24.226 UTC [32722][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:24.229 UTC [32722][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-1.crt sslmode=verify-ca$$
2024-03-20 15:19:24.231 UTC [32722][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.019 user=ssltestuser database=trustdb host=localhost port=57998
2024-03-20 15:19:24.258 UTC [32736][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58002
2024-03-20 15:19:24.271 UTC [32736][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:24.271 UTC [32736][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:24.273 UTC [32736][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-2.crt sslmode=verify-ca$$
2024-03-20 15:19:24.284 UTC [32736][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=ssltestuser database=trustdb host=localhost port=58002
2024-03-20 15:19:24.318 UTC [32752][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58018
2024-03-20 15:19:24.325 UTC [32752][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:24.325 UTC [32752][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:24.328 UTC [32752][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=disable$$
2024-03-20 15:19:24.332 UTC [32752][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=58018
2024-03-20 15:19:24.361 UTC [32768][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58030
2024-03-20 15:19:24.372 UTC [32768][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:24.372 UTC [32768][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:24.382 UTC [32768][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=allow$$
2024-03-20 15:19:24.382 UTC [32768][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=ssltestuser database=trustdb host=localhost port=58030
2024-03-20 15:19:24.413 UTC [32777][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58044
2024-03-20 15:19:24.420 UTC [32777][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:24.420 UTC [32777][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:24.426 UTC [32777][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=58044
2024-03-20 15:19:24.462 UTC [32789][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58046
2024-03-20 15:19:24.474 UTC [32789][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:24.474 UTC [32789][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:24.485 UTC [32789][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=invalid$$
2024-03-20 15:19:24.490 UTC [32789][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.033 user=ssltestuser database=trustdb host=localhost port=58046
2024-03-20 15:19:24.511 UTC [32796][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58052
2024-03-20 15:19:24.531 UTC [32796][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-20 15:19:24.555 UTC [32807][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58066
2024-03-20 15:19:24.562 UTC [32807][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-20 15:19:24.579 UTC [32816][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58068
2024-03-20 15:19:24.589 UTC [32816][client backend] [[unknown]][11/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:24.589 UTC [32816][client backend] [[unknown]][11/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:24.591 UTC [32816][client backend] [001_ssltests.pl][11/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=ssl/root+server.crl$$
2024-03-20 15:19:24.594 UTC [32816][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=58068
2024-03-20 15:19:24.618 UTC [32821][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58080
2024-03-20 15:19:24.624 UTC [32821][client backend] [[unknown]][12/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:24.624 UTC [32821][client backend] [[unknown]][12/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:24.625 UTC [32821][client backend] [001_ssltests.pl][12/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrldir=ssl/root+server-crldir$$
2024-03-20 15:19:24.628 UTC [32821][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=58080
2024-03-20 15:19:24.653 UTC [32831][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58090
2024-03-20 15:19:24.660 UTC [32831][client backend] [[unknown]][13/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:24.660 UTC [32831][client backend] [[unknown]][13/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:24.662 UTC [32831][client backend] [001_ssltests.pl][13/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=require host=wronghost.test$$
2024-03-20 15:19:24.670 UTC [32831][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=58090
2024-03-20 15:19:24.718 UTC [32845][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58092
2024-03-20 15:19:24.742 UTC [32845][client backend] [[unknown]][14/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:24.742 UTC [32845][client backend] [[unknown]][14/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:24.743 UTC [32845][client backend] [001_ssltests.pl][14/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=wronghost.test$$
2024-03-20 15:19:24.749 UTC [32845][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=ssltestuser database=trustdb host=localhost port=58092
2024-03-20 15:19:24.765 UTC [32849][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58102
2024-03-20 15:19:24.779 UTC [32536][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:24.779 UTC [32536][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:24.784 UTC [32536][postmaster] LOG:  background worker "logical replication launcher" (PID 32548) exited with exit code 1
2024-03-20 15:19:24.786 UTC [32542][checkpointer] LOG:  shutting down
2024-03-20 15:19:24.786 UTC [32542][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:24.787 UTC [32542][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/4187A78, redo lsn=0/4187A78
2024-03-20 15:19:24.792 UTC [32536][postmaster] LOG:  database system is shut down
2024-03-20 15:19:24.945 UTC [32857][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:24.945 UTC [32857][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:24.945 UTC [32857][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:24.949 UTC [32871][startup] LOG:  database system was shut down at 2024-03-20 15:19:24 UTC
2024-03-20 15:19:24.956 UTC [32857][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:25.029 UTC [32889][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58114
2024-03-20 15:19:25.035 UTC [32889][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:25.035 UTC [32889][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:25.063 UTC [32889][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-20 15:19:25.069 UTC [32889][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.043 user=ssltestuser database=trustdb host=localhost port=58114
2024-03-20 15:19:25.084 UTC [32895][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58122
2024-03-20 15:19:25.107 UTC [32857][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:25.107 UTC [32857][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:25.111 UTC [32857][postmaster] LOG:  background worker "logical replication launcher" (PID 32875) exited with exit code 1
2024-03-20 15:19:25.116 UTC [32869][checkpointer] LOG:  shutting down
2024-03-20 15:19:25.116 UTC [32869][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:25.117 UTC [32869][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/4187AF0, redo lsn=0/4187AF0
2024-03-20 15:19:25.122 UTC [32857][postmaster] LOG:  database system is shut down
2024-03-20 15:19:25.292 UTC [32910][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:25.292 UTC [32910][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:25.292 UTC [32910][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:25.313 UTC [32928][startup] LOG:  database system was shut down at 2024-03-20 15:19:25 UTC
2024-03-20 15:19:25.321 UTC [32910][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:25.442 UTC [32952][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58130
2024-03-20 15:19:25.452 UTC [32952][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:25.452 UTC [32952][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:25.464 UTC [32952][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-20 15:19:25.468 UTC [32952][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=ssltestuser database=trustdb host=localhost port=58130
2024-03-20 15:19:25.475 UTC [32910][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:25.475 UTC [32910][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:25.480 UTC [32910][postmaster] LOG:  background worker "logical replication launcher" (PID 32931) exited with exit code 1
2024-03-20 15:19:25.484 UTC [32925][checkpointer] LOG:  shutting down
2024-03-20 15:19:25.484 UTC [32925][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:25.484 UTC [32925][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/4187B68, redo lsn=0/4187B68
2024-03-20 15:19:25.491 UTC [32910][postmaster] LOG:  database system is shut down
2024-03-20 15:19:25.637 UTC [32972][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:25.637 UTC [32972][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:25.638 UTC [32972][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:25.653 UTC [32980][startup] LOG:  database system was shut down at 2024-03-20 15:19:25 UTC
2024-03-20 15:19:25.667 UTC [32972][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:25.722 UTC [32990][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58146
2024-03-20 15:19:25.733 UTC [32990][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:25.733 UTC [32990][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:25.779 UTC [32990][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$
2024-03-20 15:19:25.783 UTC [32990][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.061 user=ssltestuser database=trustdb host=localhost port=58146
2024-03-20 15:19:25.811 UTC [33001][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58156
2024-03-20 15:19:25.824 UTC [33001][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:25.824 UTC [33001][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:25.826 UTC [33001][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$
2024-03-20 15:19:25.829 UTC [33001][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.019 user=ssltestuser database=trustdb host=localhost port=58156
2024-03-20 15:19:25.857 UTC [33014][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58160
2024-03-20 15:19:25.869 UTC [33014][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:25.869 UTC [33014][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:25.873 UTC [33014][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=foo.wildcard.pg-ssltest.test$$
2024-03-20 15:19:25.880 UTC [33014][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=trustdb host=localhost port=58160
2024-03-20 15:19:25.919 UTC [33022][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58168
2024-03-20 15:19:25.952 UTC [33031][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58180
2024-03-20 15:19:25.983 UTC [32972][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:25.983 UTC [32972][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:25.991 UTC [32972][postmaster] LOG:  background worker "logical replication launcher" (PID 32987) exited with exit code 1
2024-03-20 15:19:25.992 UTC [32978][checkpointer] LOG:  shutting down
2024-03-20 15:19:25.992 UTC [32978][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:25.993 UTC [32978][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/4187BE0, redo lsn=0/4187BE0
2024-03-20 15:19:26.003 UTC [32972][postmaster] LOG:  database system is shut down
2024-03-20 15:19:26.158 UTC [33055][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:26.160 UTC [33055][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:26.160 UTC [33055][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:26.222 UTC [33081][startup] LOG:  database system was shut down at 2024-03-20 15:19:25 UTC
2024-03-20 15:19:26.230 UTC [33055][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:26.319 UTC [33105][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58190
2024-03-20 15:19:26.330 UTC [33105][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:26.330 UTC [33105][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:26.352 UTC [33105][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=single.alt-name.pg-ssltest.test$$
2024-03-20 15:19:26.357 UTC [33105][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.040 user=ssltestuser database=trustdb host=localhost port=58190
2024-03-20 15:19:26.385 UTC [33117][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58200
2024-03-20 15:19:26.432 UTC [33122][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=58208
2024-03-20 15:19:26.476 UTC [33055][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:26.476 UTC [33055][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:26.485 UTC [33055][postmaster] LOG:  background worker "logical replication launcher" (PID 33092) exited with exit code 1
2024-03-20 15:19:26.485 UTC [33077][checkpointer] LOG:  shutting down
2024-03-20 15:19:26.485 UTC [33077][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:26.486 UTC [33077][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/4187C58, redo lsn=0/4187C58
2024-03-20 15:19:26.492 UTC [33055][postmaster] LOG:  database system is shut down
2024-03-20 15:19:26.620 UTC [33155][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:26.620 UTC [33155][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:26.620 UTC [33155][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:26.646 UTC [33172][startup] LOG:  database system was shut down at 2024-03-20 15:19:26 UTC
2024-03-20 15:19:26.659 UTC [33155][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:26.714 UTC [33188][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51284
2024-03-20 15:19:26.720 UTC [33188][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:26.720 UTC [33188][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:26.733 UTC [33188][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-20 15:19:26.736 UTC [33188][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=ssltestuser database=trustdb host=localhost port=51284
2024-03-20 15:19:26.773 UTC [33199][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51288
2024-03-20 15:19:26.782 UTC [33199][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:26.782 UTC [33199][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:26.793 UTC [33199][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.000.002.001$$
2024-03-20 15:19:26.796 UTC [33199][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=ssltestuser database=trustdb host=localhost port=51288
2024-03-20 15:19:26.839 UTC [33208][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51294
2024-03-20 15:19:26.930 UTC [33235][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51310
2024-03-20 15:19:26.959 UTC [33235][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:26.959 UTC [33235][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:26.961 UTC [33235][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:DB8::1$$
2024-03-20 15:19:26.964 UTC [33235][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.035 user=ssltestuser database=trustdb host=localhost port=51310
2024-03-20 15:19:27.023 UTC [33243][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51314
2024-03-20 15:19:27.037 UTC [33243][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:27.037 UTC [33243][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:27.039 UTC [33243][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8:0:0:0:0:0:1$$
2024-03-20 15:19:27.045 UTC [33243][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.022 user=ssltestuser database=trustdb host=localhost port=51314
2024-03-20 15:19:27.085 UTC [33255][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51330
2024-03-20 15:19:27.092 UTC [33255][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:27.092 UTC [33255][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:27.096 UTC [33255][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::0.0.0.1$$
2024-03-20 15:19:27.100 UTC [33255][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=ssltestuser database=trustdb host=localhost port=51330
2024-03-20 15:19:27.157 UTC [33268][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51340
2024-03-20 15:19:27.206 UTC [33280][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51348
2024-03-20 15:19:27.219 UTC [33155][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:27.219 UTC [33155][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:27.227 UTC [33155][postmaster] LOG:  background worker "logical replication launcher" (PID 33177) exited with exit code 1
2024-03-20 15:19:27.227 UTC [33169][checkpointer] LOG:  shutting down
2024-03-20 15:19:27.227 UTC [33169][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:27.228 UTC [33169][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/4187CD0, redo lsn=0/4187CD0
2024-03-20 15:19:27.234 UTC [33155][postmaster] LOG:  database system is shut down
2024-03-20 15:19:27.405 UTC [33296][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:27.405 UTC [33296][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:27.405 UTC [33296][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:27.419 UTC [33310][startup] LOG:  database system was shut down at 2024-03-20 15:19:27 UTC
2024-03-20 15:19:27.439 UTC [33296][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:27.552 UTC [33332][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51358
2024-03-20 15:19:27.582 UTC [33332][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:27.582 UTC [33332][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:27.605 UTC [33332][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$
2024-03-20 15:19:27.607 UTC [33332][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.056 user=ssltestuser database=trustdb host=localhost port=51358
2024-03-20 15:19:27.651 UTC [33336][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51362
2024-03-20 15:19:27.662 UTC [33336][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:27.662 UTC [33336][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:27.663 UTC [33336][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$
2024-03-20 15:19:27.664 UTC [33336][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=51362
2024-03-20 15:19:27.682 UTC [33343][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51366
2024-03-20 15:19:27.716 UTC [33296][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:27.716 UTC [33296][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:27.720 UTC [33296][postmaster] LOG:  background worker "logical replication launcher" (PID 33317) exited with exit code 1
2024-03-20 15:19:27.723 UTC [33308][checkpointer] LOG:  shutting down
2024-03-20 15:19:27.723 UTC [33308][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:27.724 UTC [33308][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/4187D48, redo lsn=0/4187D48
2024-03-20 15:19:27.729 UTC [33296][postmaster] LOG:  database system is shut down
2024-03-20 15:19:27.773 UTC [33348][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:27.773 UTC [33348][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:27.773 UTC [33348][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:27.821 UTC [33356][startup] LOG:  database system was shut down at 2024-03-20 15:19:27 UTC
2024-03-20 15:19:27.829 UTC [33348][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:27.870 UTC [33377][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51376
2024-03-20 15:19:27.881 UTC [33377][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:27.881 UTC [33377][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:27.917 UTC [33377][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$
2024-03-20 15:19:27.922 UTC [33377][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.053 user=ssltestuser database=trustdb host=localhost port=51376
2024-03-20 15:19:27.959 UTC [33389][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51388
2024-03-20 15:19:27.967 UTC [33389][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:27.967 UTC [33389][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:27.973 UTC [33389][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-20 15:19:27.978 UTC [33389][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=ssltestuser database=trustdb host=localhost port=51388
2024-03-20 15:19:28.025 UTC [33412][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51402
2024-03-20 15:19:28.036 UTC [33412][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:28.036 UTC [33412][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:28.045 UTC [33412][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$
2024-03-20 15:19:28.048 UTC [33412][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.033 user=ssltestuser database=trustdb host=localhost port=51402
2024-03-20 15:19:28.061 UTC [33348][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:28.061 UTC [33348][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:28.065 UTC [33348][postmaster] LOG:  background worker "logical replication launcher" (PID 33368) exited with exit code 1
2024-03-20 15:19:28.071 UTC [33352][checkpointer] LOG:  shutting down
2024-03-20 15:19:28.071 UTC [33352][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:28.071 UTC [33352][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/4187DC0, redo lsn=0/4187DC0
2024-03-20 15:19:28.076 UTC [33348][postmaster] LOG:  database system is shut down
2024-03-20 15:19:28.172 UTC [33423][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:28.172 UTC [33423][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:28.172 UTC [33423][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:28.197 UTC [33436][startup] LOG:  database system was shut down at 2024-03-20 15:19:28 UTC
2024-03-20 15:19:28.225 UTC [33423][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:28.324 UTC [33454][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51408
2024-03-20 15:19:28.331 UTC [33454][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:28.331 UTC [33454][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:28.372 UTC [33454][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.2$$
2024-03-20 15:19:28.381 UTC [33454][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.060 user=ssltestuser database=trustdb host=localhost port=51408
2024-03-20 15:19:28.408 UTC [33460][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51416
2024-03-20 15:19:28.432 UTC [33460][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:28.432 UTC [33460][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:28.434 UTC [33460][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$
2024-03-20 15:19:28.438 UTC [33460][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=ssltestuser database=trustdb host=localhost port=51416
2024-03-20 15:19:28.529 UTC [33467][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51426
2024-03-20 15:19:28.564 UTC [33423][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:28.564 UTC [33423][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:28.572 UTC [33423][postmaster] LOG:  background worker "logical replication launcher" (PID 33443) exited with exit code 1
2024-03-20 15:19:28.572 UTC [33434][checkpointer] LOG:  shutting down
2024-03-20 15:19:28.572 UTC [33434][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:28.573 UTC [33434][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/4187E38, redo lsn=0/4187E38
2024-03-20 15:19:28.577 UTC [33423][postmaster] LOG:  database system is shut down
2024-03-20 15:19:28.676 UTC [33473][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:28.676 UTC [33473][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:28.676 UTC [33473][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:28.711 UTC [33485][startup] LOG:  database system was shut down at 2024-03-20 15:19:28 UTC
2024-03-20 15:19:28.719 UTC [33473][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:28.861 UTC [33512][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51440
2024-03-20 15:19:28.874 UTC [33512][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:28.874 UTC [33512][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:28.906 UTC [33512][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-20 15:19:28.911 UTC [33512][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.055 user=ssltestuser database=trustdb host=localhost port=51440
2024-03-20 15:19:28.961 UTC [33537][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51456
2024-03-20 15:19:28.968 UTC [33537][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:28.968 UTC [33537][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:28.969 UTC [33537][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$
2024-03-20 15:19:28.973 UTC [33537][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=ssltestuser database=trustdb host=localhost port=51456
2024-03-20 15:19:29.017 UTC [33543][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51464
2024-03-20 15:19:29.035 UTC [33543][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:29.035 UTC [33543][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:29.045 UTC [33543][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$
2024-03-20 15:19:29.053 UTC [33543][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.036 user=ssltestuser database=trustdb host=localhost port=51464
2024-03-20 15:19:29.068 UTC [33473][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:29.068 UTC [33473][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:29.079 UTC [33473][postmaster] LOG:  background worker "logical replication launcher" (PID 33490) exited with exit code 1
2024-03-20 15:19:29.079 UTC [33483][checkpointer] LOG:  shutting down
2024-03-20 15:19:29.079 UTC [33483][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:29.079 UTC [33483][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/4187EB0, redo lsn=0/4187EB0
2024-03-20 15:19:29.084 UTC [33473][postmaster] LOG:  database system is shut down
2024-03-20 15:19:29.187 UTC [33551][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:29.187 UTC [33551][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:29.187 UTC [33551][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:29.230 UTC [33573][startup] LOG:  database system was shut down at 2024-03-20 15:19:29 UTC
2024-03-20 15:19:29.243 UTC [33551][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:29.331 UTC [33596][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51478
2024-03-20 15:19:29.339 UTC [33596][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:29.339 UTC [33596][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:29.375 UTC [33596][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=common-name.pg-ssltest.test$$
2024-03-20 15:19:29.389 UTC [33596][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.063 user=ssltestuser database=trustdb host=localhost port=51478
2024-03-20 15:19:29.432 UTC [33609][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51494
2024-03-20 15:19:29.457 UTC [33551][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:29.457 UTC [33551][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:29.465 UTC [33551][postmaster] LOG:  background worker "logical replication launcher" (PID 33580) exited with exit code 1
2024-03-20 15:19:29.467 UTC [33571][checkpointer] LOG:  shutting down
2024-03-20 15:19:29.467 UTC [33571][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:29.467 UTC [33571][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/4187F28, redo lsn=0/4187F28
2024-03-20 15:19:29.472 UTC [33551][postmaster] LOG:  database system is shut down
2024-03-20 15:19:29.655 UTC [33618][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:29.655 UTC [33618][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:29.656 UTC [33618][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:29.685 UTC [33625][startup] LOG:  database system was shut down at 2024-03-20 15:19:29 UTC
2024-03-20 15:19:29.698 UTC [33618][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:29.817 UTC [33638][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51496
2024-03-20 15:19:29.838 UTC [33638][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-20 15:19:30.476 UTC [33689][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51498
2024-03-20 15:19:30.489 UTC [33689][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:30.489 UTC [33689][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:30.542 UTC [33689][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=system hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$
2024-03-20 15:19:30.548 UTC [33689][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.084 user=ssltestuser database=trustdb host=localhost port=51498
2024-03-20 15:19:30.585 UTC [33699][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51506
2024-03-20 15:19:30.609 UTC [33618][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:30.609 UTC [33618][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:30.620 UTC [33618][postmaster] LOG:  background worker "logical replication launcher" (PID 33628) exited with exit code 1
2024-03-20 15:19:30.621 UTC [33623][checkpointer] LOG:  shutting down
2024-03-20 15:19:30.621 UTC [33623][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:30.621 UTC [33623][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/4187FA0, redo lsn=0/4187FA0
2024-03-20 15:19:30.626 UTC [33618][postmaster] LOG:  database system is shut down
2024-03-20 15:19:30.699 UTC [33714][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:30.699 UTC [33714][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:30.699 UTC [33714][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:30.713 UTC [33722][startup] LOG:  database system was shut down at 2024-03-20 15:19:30 UTC
2024-03-20 15:19:30.730 UTC [33714][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:30.795 UTC [33736][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51520
2024-03-20 15:19:30.814 UTC [33736][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:30.814 UTC [33736][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:30.856 UTC [33736][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$
2024-03-20 15:19:30.865 UTC [33736][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.072 user=ssltestuser database=trustdb host=localhost port=51520
2024-03-20 15:19:30.896 UTC [33747][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51532
2024-03-20 15:19:30.916 UTC [33747][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-20 15:19:30.983 UTC [33758][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51534
2024-03-20 15:19:30.991 UTC [33758][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-20 15:19:31.023 UTC [33773][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51538
2024-03-20 15:19:31.039 UTC [33773][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:31.039 UTC [33773][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:31.044 UTC [33773][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-20 15:19:31.050 UTC [33773][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=ssltestuser database=trustdb host=localhost port=51538
2024-03-20 15:19:31.084 UTC [33784][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51554
2024-03-20 15:19:31.092 UTC [33784][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:31.092 UTC [33784][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.2, cipher=ECDHE-RSA-AES256-GCM-SHA384, bits=256)
2024-03-20 15:19:31.093 UTC [33784][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require ssl_min_protocol_version=TLSv1.2 ssl_max_protocol_version=TLSv1.2$$
2024-03-20 15:19:31.096 UTC [33784][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.019 user=ssltestuser database=trustdb host=localhost port=51554
2024-03-20 15:19:31.221 UTC [33801][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51558
2024-03-20 15:19:31.230 UTC [33801][client backend] [[unknown]][3/1:0] FATAL:  connection requires a valid client certificate
2024-03-20 15:19:31.265 UTC [33809][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51572
2024-03-20 15:19:31.273 UTC [33809][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-20 15:19:31.273 UTC [33809][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:31.296 UTC [33809][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_rnyY/client.key$$
2024-03-20 15:19:31.300 UTC [33809][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.045 user=ssltestuser database=certdb host=localhost port=51572
2024-03-20 15:19:31.345 UTC [33819][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51588
2024-03-20 15:19:31.354 UTC [33819][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-20 15:19:31.354 UTC [33819][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:31.364 UTC [33819][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_rnyY/client-der.key$$
2024-03-20 15:19:31.370 UTC [33819][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.033 user=ssltestuser database=certdb host=localhost port=51588
2024-03-20 15:19:31.399 UTC [33825][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51604
2024-03-20 15:19:31.408 UTC [33825][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-20 15:19:31.408 UTC [33825][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:31.413 UTC [33825][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_rnyY/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$
2024-03-20 15:19:31.416 UTC [33825][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.018 user=ssltestuser database=certdb host=localhost port=51604
2024-03-20 15:19:31.443 UTC [33836][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51612
2024-03-20 15:19:31.457 UTC [33836][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-20 15:19:31.457 UTC [33836][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:31.459 UTC [33836][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_rnyY/client-encrypted-der.key sslpassword='dUmmyP^#+'$$
2024-03-20 15:19:31.463 UTC [33836][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=ssltestuser database=certdb host=localhost port=51612
2024-03-20 15:19:31.501 UTC [33844][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51614
2024-03-20 15:19:31.509 UTC [33844][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-20 15:19:31.509 UTC [33844][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:31.511 UTC [33844][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcertmode=require sslcert=ssl/client.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_rnyY/client.key$$
2024-03-20 15:19:31.514 UTC [33844][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=certdb host=localhost port=51614
2024-03-20 15:19:31.545 UTC [33850][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51622
2024-03-20 15:19:31.566 UTC [33850][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-20 15:19:31.566 UTC [33850][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:31.568 UTC [33850][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcertmode=allow sslcert=ssl/client.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_rnyY/client.key$$
2024-03-20 15:19:31.569 UTC [33850][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=ssltestuser database=certdb host=localhost port=51622
2024-03-20 15:19:31.619 UTC [33859][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51636
2024-03-20 15:19:31.626 UTC [33859][client backend] [[unknown]][10/1:0] FATAL:  connection requires a valid client certificate
2024-03-20 15:19:31.661 UTC [33868][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51648
2024-03-20 15:19:31.673 UTC [33868][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-20 15:19:31.706 UTC [33875][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51660
2024-03-20 15:19:31.718 UTC [33875][client backend] [[unknown]][11/1:0] LOG:  connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:8)
2024-03-20 15:19:31.718 UTC [33875][client backend] [[unknown]][11/1:0] LOG:  connection authorized: user=ssltestuser database=certdb_dn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:31.730 UTC [33875][client backend] [001_ssltests.pl][11/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost dbname=certdb_dn user=ssltestuser sslcert=ssl/client-dn.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_rnyY/client-dn.key$$
2024-03-20 15:19:31.735 UTC [33875][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=ssltestuser database=certdb_dn host=localhost port=51660
2024-03-20 15:19:31.763 UTC [33885][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51676
2024-03-20 15:19:31.772 UTC [33885][client backend] [[unknown]][12/1:0] LOG:  connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:9)
2024-03-20 15:19:31.773 UTC [33885][client backend] [[unknown]][12/1:0] LOG:  connection authorized: user=ssltestuser database=certdb_dn_re application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:31.809 UTC [33885][client backend] [001_ssltests.pl][12/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost dbname=certdb_dn_re user=ssltestuser sslcert=ssl/client-dn.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_rnyY/client-dn.key$$
2024-03-20 15:19:31.815 UTC [33885][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.053 user=ssltestuser database=certdb_dn_re host=localhost port=51676
2024-03-20 15:19:31.854 UTC [33905][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51692
2024-03-20 15:19:31.869 UTC [33905][client backend] [[unknown]][13/1:0] LOG:  connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:10)
2024-03-20 15:19:31.869 UTC [33905][client backend] [[unknown]][13/1:0] LOG:  connection authorized: user=ssltestuser database=certdb_cn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:31.884 UTC [33905][client backend] [001_ssltests.pl][13/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost dbname=certdb_cn user=ssltestuser sslcert=ssl/client-dn.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_rnyY/client-dn.key$$
2024-03-20 15:19:31.889 UTC [33905][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.035 user=ssltestuser database=certdb_cn host=localhost port=51692
2024-03-20 15:19:31.931 UTC [33923][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51704
2024-03-20 15:19:31.945 UTC [33923][client backend] [[unknown]][14/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-20 15:19:31.945 UTC [33923][client backend] [[unknown]][14/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:31.955 UTC [33923][client backend] [001_ssltests.pl][14/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-20 15:19:31.960 UTC [33923][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=ssltestuser database=certdb host=localhost port=51704
2024-03-20 15:19:31.997 UTC [33945][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51712
2024-03-20 15:19:32.004 UTC [33945][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-20 15:19:32.044 UTC [33955][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51724
2024-03-20 15:19:32.054 UTC [33955][client backend] [[unknown]][15/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-20 15:19:32.054 UTC [33955][client backend] [[unknown]][15/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-20 15:19:32.055 UTC [33955][client backend] [[unknown]][15/1:0] FATAL:  certificate authentication failed for user "anotheruser"
2024-03-20 15:19:32.055 UTC [33955][client backend] [[unknown]][15/1:0] DETAIL:  Connection matched file "/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf" line 7: "hostssl certdb          all             127.0.0.1/32            cert"
2024-03-20 15:19:32.081 UTC [33966][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51734
2024-03-20 15:19:32.101 UTC [33966][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-20 15:19:32.101 UTC [33966][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: certificate revoked.
	Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-20 15:19:32.153 UTC [33979][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51746
2024-03-20 15:19:32.162 UTC [33979][client backend] [[unknown]][16/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:4)
2024-03-20 15:19:32.162 UTC [33979][client backend] [[unknown]][16/1:0] LOG:  connection authorized: user=ssltestuser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:32.184 UTC [33979][client backend] [001_ssltests.pl][16/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=verifydb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_rnyY/client.key$$
2024-03-20 15:19:32.186 UTC [33979][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.041 user=ssltestuser database=verifydb host=localhost port=51746
2024-03-20 15:19:32.214 UTC [33995][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51752
2024-03-20 15:19:32.241 UTC [33995][client backend] [[unknown]][17/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-20 15:19:32.241 UTC [33995][client backend] [[unknown]][17/1:0] LOG:  certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch
2024-03-20 15:19:32.241 UTC [33995][client backend] [[unknown]][17/1:0] FATAL:  "trust" authentication failed for user "anotheruser"
2024-03-20 15:19:32.241 UTC [33995][client backend] [[unknown]][17/1:0] DETAIL:  Connection matched file "/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf" line 5: "hostssl verifydb        anotheruser     127.0.0.1/32            trust        clientcert=verify-full"
2024-03-20 15:19:32.282 UTC [34011][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51766
2024-03-20 15:19:32.300 UTC [34011][client backend] [[unknown]][18/1:0] LOG:  connection authenticated: user="yetanotheruser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:6)
2024-03-20 15:19:32.300 UTC [34011][client backend] [[unknown]][18/1:0] LOG:  connection authorized: user=yetanotheruser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:32.302 UTC [34011][client backend] [001_ssltests.pl][18/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=verifydb hostaddr=127.0.0.1 host=localhost user=yetanotheruser sslcert=ssl/client.crt  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_rnyY/client.key$$
2024-03-20 15:19:32.305 UTC [34011][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=yetanotheruser database=verifydb host=localhost port=51766
2024-03-20 15:19:32.316 UTC [33714][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:32.316 UTC [33714][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:32.320 UTC [33714][postmaster] LOG:  background worker "logical replication launcher" (PID 33729) exited with exit code 1
2024-03-20 15:19:32.329 UTC [33720][checkpointer] LOG:  shutting down
2024-03-20 15:19:32.329 UTC [33720][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:32.330 UTC [33720][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/4188030, redo lsn=0/4188030
2024-03-20 15:19:32.338 UTC [33714][postmaster] LOG:  database system is shut down
2024-03-20 15:19:32.472 UTC [34040][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:32.472 UTC [34040][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:32.472 UTC [34040][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:32.488 UTC [34050][startup] LOG:  database system was shut down at 2024-03-20 15:19:32 UTC
2024-03-20 15:19:32.505 UTC [34040][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:32.556 UTC [34069][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51774
2024-03-20 15:19:32.565 UTC [34069][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-20 15:19:32.565 UTC [34069][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:32.585 UTC [34069][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=certdb  sslkey=/tmp/cirrus-ci-build/src/test/ssl/tmp_check/tmp_test_rnyY/client.key sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 host=localhost sslmode=require sslcert=ssl/client+client_ca.crt$$
2024-03-20 15:19:32.588 UTC [34069][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=ssltestuser database=certdb host=localhost port=51774
2024-03-20 15:19:32.610 UTC [34084][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51786
2024-03-20 15:19:32.617 UTC [34084][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-20 15:19:32.617 UTC [34084][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: unable to get local issuer certificate.
	Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921344, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-20 15:19:32.655 UTC [34093][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51800
2024-03-20 15:19:32.672 UTC [34093][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-20 15:19:32.672 UTC [34093][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: unable to get local issuer certificate.
	Failed certificate data (unverified): subject ".../CN=ssl-123456789012345678901234567890123456789012345678901234567890", serial number 2315702411956921348, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-20 15:19:32.715 UTC [34040][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:32.715 UTC [34040][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:32.727 UTC [34040][postmaster] LOG:  background worker "logical replication launcher" (PID 34058) exited with exit code 1
2024-03-20 15:19:32.727 UTC [34048][checkpointer] LOG:  shutting down
2024-03-20 15:19:32.727 UTC [34048][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:32.727 UTC [34048][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/41880A8, redo lsn=0/41880A8
2024-03-20 15:19:32.733 UTC [34040][postmaster] LOG:  database system is shut down
2024-03-20 15:19:32.860 UTC [34113][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:32.860 UTC [34113][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:32.860 UTC [34113][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:32.865 UTC [34118][startup] LOG:  database system was shut down at 2024-03-20 15:19:32 UTC
2024-03-20 15:19:32.872 UTC [34113][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:32.935 UTC [34131][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51814
2024-03-20 15:19:32.945 UTC [34131][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-20 15:19:32.945 UTC [34131][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 1: unable to get local issuer certificate.
	Failed certificate data (unverified): subject "/CN=Test CA for PostgreSQL SSL regression test client certs", serial number 2315702411956921089, issuer "/CN=Test root CA for PostgreSQL SSL regression test suite".
2024-03-20 15:19:32.953 UTC [34113][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:32.953 UTC [34113][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:32.961 UTC [34113][postmaster] LOG:  background worker "logical replication launcher" (PID 34123) exited with exit code 1
2024-03-20 15:19:32.961 UTC [34116][checkpointer] LOG:  shutting down
2024-03-20 15:19:32.961 UTC [34116][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:32.962 UTC [34116][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/4188120, redo lsn=0/4188120
2024-03-20 15:19:32.967 UTC [34113][postmaster] LOG:  database system is shut down
2024-03-20 15:19:33.104 UTC [34147][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:33.105 UTC [34147][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:33.105 UTC [34147][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:33.120 UTC [34154][startup] LOG:  database system was shut down at 2024-03-20 15:19:32 UTC
2024-03-20 15:19:33.128 UTC [34147][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:33.190 UTC [34164][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51824
2024-03-20 15:19:33.201 UTC [34164][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-20 15:19:33.201 UTC [34164][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: certificate revoked.
	Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-20 15:19:33.225 UTC [34171][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51838
2024-03-20 15:19:33.239 UTC [34171][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-20 15:19:33.239 UTC [34171][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: certificate revoked.
	Failed certificate data (unverified): subject "/CN=\xce\x9f\xce\xb4\xcf\x85\xcf\x83\xcf\x83\xce\xad\xce\xb1\xcf\x82", serial number 2315702411956921349, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-20 15:19:33.252 UTC [34147][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:33.252 UTC [34147][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:33.260 UTC [34147][postmaster] LOG:  background worker "logical replication launcher" (PID 34158) exited with exit code 1
2024-03-20 15:19:33.260 UTC [34152][checkpointer] LOG:  shutting down
2024-03-20 15:19:33.260 UTC [34152][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:33.261 UTC [34152][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/4188198, redo lsn=0/4188198
2024-03-20 15:19:33.266 UTC [34147][postmaster] LOG:  database system is shut down
2024-03-20 15:19:33.407 UTC [34191][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:33.407 UTC [34191][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:33.407 UTC [34191][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:33.412 UTC [34203][startup] LOG:  database system was shut down at 2024-03-20 15:19:33 UTC
2024-03-20 15:19:33.424 UTC [34191][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:33.488 UTC [34220][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51848
2024-03-20 15:19:33.490 UTC [34220][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-20 15:19:33.490 UTC [34220][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-20 15:19:33.518 UTC [34230][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51862
2024-03-20 15:19:33.525 UTC [34230][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:33.525 UTC [34230][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:33.541 UTC [34230][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-20 15:19:33.547 UTC [34230][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.034 user=ssltestuser database=trustdb host=localhost port=51862
2024-03-20 15:19:33.557 UTC [34191][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:33.557 UTC [34191][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:33.568 UTC [34191][postmaster] LOG:  background worker "logical replication launcher" (PID 34208) exited with exit code 1
2024-03-20 15:19:33.569 UTC [34201][checkpointer] LOG:  shutting down
2024-03-20 15:19:33.569 UTC [34201][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:33.569 UTC [34201][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/4188210, redo lsn=0/4188210
2024-03-20 15:19:33.575 UTC [34191][postmaster] LOG:  database system is shut down
2024-03-20 15:19:33.714 UTC [34263][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:33.714 UTC [34263][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:33.714 UTC [34263][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:33.719 UTC [34278][startup] LOG:  database system was shut down at 2024-03-20 15:19:33 UTC
2024-03-20 15:19:33.728 UTC [34263][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:33.785 UTC [34291][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51864
2024-03-20 15:19:33.787 UTC [34291][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-20 15:19:33.788 UTC [34291][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-20 15:19:33.793 UTC [34291][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert internal error
2024-03-20 15:19:33.832 UTC [34298][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51872
2024-03-20 15:19:33.838 UTC [34298][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:33.838 UTC [34298][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:33.851 UTC [34298][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-20 15:19:33.854 UTC [34298][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=ssltestuser database=trustdb host=localhost port=51872
2024-03-20 15:19:33.874 UTC [34263][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:33.874 UTC [34263][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:33.891 UTC [34263][postmaster] LOG:  background worker "logical replication launcher" (PID 34283) exited with exit code 1
2024-03-20 15:19:33.891 UTC [34276][checkpointer] LOG:  shutting down
2024-03-20 15:19:33.891 UTC [34276][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:33.892 UTC [34276][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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4188288, redo lsn=0/4188288
2024-03-20 15:19:33.898 UTC [34263][postmaster] LOG:  database system is shut down
2024-03-20 15:19:34.043 UTC [34326][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:34.043 UTC [34326][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:34.043 UTC [34326][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:34.077 UTC [34338][startup] LOG:  database system was shut down at 2024-03-20 15:19:33 UTC
2024-03-20 15:19:34.085 UTC [34326][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:34.214 UTC [34367][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51874
2024-03-20 15:19:34.219 UTC [34367][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-20 15:19:34.220 UTC [34367][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-20 15:19:34.247 UTC [34381][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51880
2024-03-20 15:19:34.260 UTC [34381][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:34.260 UTC [34381][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:34.272 UTC [34381][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-20 15:19:34.283 UTC [34326][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:34.283 UTC [34326][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:34.287 UTC [34381][client backend] [001_ssltests.pl][0/0:0] FATAL:  terminating connection due to administrator command
2024-03-20 15:19:34.287 UTC [34381][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.040 user=ssltestuser database=trustdb host=localhost port=51880
2024-03-20 15:19:34.291 UTC [34326][postmaster] LOG:  background worker "logical replication launcher" (PID 34345) exited with exit code 1
2024-03-20 15:19:34.292 UTC [34336][checkpointer] LOG:  shutting down
2024-03-20 15:19:34.292 UTC [34336][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:34.293 UTC [34336][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/4188300, redo lsn=0/4188300
2024-03-20 15:19:34.299 UTC [34326][postmaster] LOG:  database system is shut down
2024-03-20 15:19:34.446 UTC [34400][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:34.446 UTC [34400][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:34.446 UTC [34400][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:34.477 UTC [34412][startup] LOG:  database system was shut down at 2024-03-20 15:19:34 UTC
2024-03-20 15:19:34.485 UTC [34400][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:34.549 UTC [34423][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51886
2024-03-20 15:19:34.561 UTC [34423][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-20 15:19:34.561 UTC [34423][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-20 15:19:34.615 UTC [34430][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51894
2024-03-20 15:19:34.623 UTC [34430][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:34.623 UTC [34430][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:34.669 UTC [34430][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-20 15:19:34.675 UTC [34430][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.062 user=ssltestuser database=trustdb host=localhost port=51894
2024-03-20 15:19:34.684 UTC [34400][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:34.684 UTC [34400][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:34.691 UTC [34400][postmaster] LOG:  background worker "logical replication launcher" (PID 34416) exited with exit code 1
2024-03-20 15:19:34.692 UTC [34409][checkpointer] LOG:  shutting down
2024-03-20 15:19:34.692 UTC [34409][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:34.693 UTC [34409][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/4188378, redo lsn=0/4188378
2024-03-20 15:19:34.698 UTC [34400][postmaster] LOG:  database system is shut down
2024-03-20 15:19:34.855 UTC [34451][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:34.855 UTC [34451][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:34.856 UTC [34451][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:34.879 UTC [34460][startup] LOG:  database system was shut down at 2024-03-20 15:19:34 UTC
2024-03-20 15:19:34.887 UTC [34451][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:34.943 UTC [34476][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51908
2024-03-20 15:19:34.945 UTC [34476][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-20 15:19:34.945 UTC [34476][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-20 15:19:35.000 UTC [34483][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51924
2024-03-20 15:19:35.013 UTC [34483][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:35.013 UTC [34483][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:35.028 UTC [34483][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-20 15:19:35.037 UTC [34483][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.048 user=ssltestuser database=trustdb host=localhost port=51924
2024-03-20 15:19:35.053 UTC [34451][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:35.053 UTC [34451][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:35.061 UTC [34451][postmaster] LOG:  background worker "logical replication launcher" (PID 34463) exited with exit code 1
2024-03-20 15:19:35.061 UTC [34458][checkpointer] LOG:  shutting down
2024-03-20 15:19:35.061 UTC [34458][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:35.062 UTC [34458][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/41883F0, redo lsn=0/41883F0
2024-03-20 15:19:35.073 UTC [34451][postmaster] LOG:  database system is shut down
2024-03-20 15:19:35.204 UTC [34510][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:35.204 UTC [34510][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:35.204 UTC [34510][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:35.211 UTC [34521][startup] LOG:  database system was shut down at 2024-03-20 15:19:35 UTC
2024-03-20 15:19:35.218 UTC [34510][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:35.288 UTC [34538][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51930
2024-03-20 15:19:35.291 UTC [34538][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-20 15:19:35.291 UTC [34538][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-20 15:19:35.299 UTC [34538][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-20 15:19:35.317 UTC [34548][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51946
2024-03-20 15:19:35.325 UTC [34548][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:35.325 UTC [34548][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:35.346 UTC [34548][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-20 15:19:35.352 UTC [34548][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.040 user=ssltestuser database=trustdb host=localhost port=51946
2024-03-20 15:19:35.362 UTC [34510][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:35.362 UTC [34510][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:35.371 UTC [34510][postmaster] LOG:  background worker "logical replication launcher" (PID 34528) exited with exit code 1
2024-03-20 15:19:35.371 UTC [34519][checkpointer] LOG:  shutting down
2024-03-20 15:19:35.371 UTC [34519][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:35.372 UTC [34519][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/4188468, redo lsn=0/4188468
2024-03-20 15:19:35.378 UTC [34510][postmaster] LOG:  database system is shut down
2024-03-20 15:19:35.504 UTC [34574][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:35.504 UTC [34574][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:35.504 UTC [34574][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:35.521 UTC [34579][startup] LOG:  database system was shut down at 2024-03-20 15:19:35 UTC
2024-03-20 15:19:35.534 UTC [34574][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:35.600 UTC [34596][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51952
2024-03-20 15:19:35.608 UTC [34596][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-20 15:19:35.608 UTC [34596][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-20 15:19:35.631 UTC [34606][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51964
2024-03-20 15:19:35.658 UTC [34606][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:35.658 UTC [34606][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:35.696 UTC [34606][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-20 15:19:35.704 UTC [34606][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.073 user=ssltestuser database=trustdb host=localhost port=51964
2024-03-20 15:19:35.720 UTC [34574][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:35.720 UTC [34574][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:35.724 UTC [34574][postmaster] LOG:  background worker "logical replication launcher" (PID 34587) exited with exit code 1
2024-03-20 15:19:35.738 UTC [34577][checkpointer] LOG:  shutting down
2024-03-20 15:19:35.738 UTC [34577][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:35.739 UTC [34577][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/41884E0, redo lsn=0/41884E0
2024-03-20 15:19:35.744 UTC [34574][postmaster] LOG:  database system is shut down
2024-03-20 15:19:35.879 UTC [34626][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:35.879 UTC [34626][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:35.879 UTC [34626][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:35.893 UTC [34641][startup] LOG:  database system was shut down at 2024-03-20 15:19:35 UTC
2024-03-20 15:19:35.905 UTC [34626][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:35.959 UTC [34660][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51970
2024-03-20 15:19:35.962 UTC [34660][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-20 15:19:35.962 UTC [34660][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-20 15:19:35.965 UTC [34660][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-20 15:19:35.993 UTC [34670][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51980
2024-03-20 15:19:35.999 UTC [34670][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:35.999 UTC [34670][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:36.011 UTC [34670][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-20 15:19:36.017 UTC [34670][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=ssltestuser database=trustdb host=localhost port=51980
2024-03-20 15:19:36.022 UTC [34626][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:36.022 UTC [34626][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:36.026 UTC [34626][postmaster] LOG:  background worker "logical replication launcher" (PID 34649) exited with exit code 1
2024-03-20 15:19:36.029 UTC [34639][checkpointer] LOG:  shutting down
2024-03-20 15:19:36.029 UTC [34639][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:36.030 UTC [34639][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/4188558, redo lsn=0/4188558
2024-03-20 15:19:36.035 UTC [34626][postmaster] LOG:  database system is shut down
2024-03-20 15:19:36.167 UTC [34709][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2024-03-20 15:19:36.167 UTC [34709][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 60027
2024-03-20 15:19:36.167 UTC [34709][postmaster] LOG:  listening on Unix socket "/tmp/R1aHYRw9Ix/.s.PGSQL.60027"
2024-03-20 15:19:36.178 UTC [34722][startup] LOG:  database system was shut down at 2024-03-20 15:19:36 UTC
2024-03-20 15:19:36.187 UTC [34709][postmaster] LOG:  database system is ready to accept connections
2024-03-20 15:19:36.253 UTC [34742][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51992
2024-03-20 15:19:36.256 UTC [34742][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-20 15:19:36.259 UTC [34742][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-20 15:19:36.270 UTC [34742][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert internal error
2024-03-20 15:19:36.301 UTC [34755][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=51998
2024-03-20 15:19:36.313 UTC [34755][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/src/test/ssl/tmp_check/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-20 15:19:36.313 UTC [34755][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-20 15:19:36.345 UTC [34755][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-20 15:19:36.351 UTC [34755][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.052 user=ssltestuser database=trustdb host=localhost port=51998
2024-03-20 15:19:36.353 UTC [34709][postmaster] LOG:  received fast shutdown request
2024-03-20 15:19:36.353 UTC [34709][postmaster] LOG:  aborting any active transactions
2024-03-20 15:19:36.361 UTC [34709][postmaster] LOG:  background worker "logical replication launcher" (PID 34729) exited with exit code 1
2024-03-20 15:19:36.362 UTC [34720][checkpointer] LOG:  shutting down
2024-03-20 15:19:36.362 UTC [34720][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-20 15:19:36.363 UTC [34720][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/41885D0, redo lsn=0/41885D0
2024-03-20 15:19:36.368 UTC [34709][postmaster] LOG:  database system is shut down
2024-03-20 15:19:36.470 UTC [34791][postmaster] FATAL:  could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory
2024-03-20 15:19:36.470 UTC [34791][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[15:19:36.555](0.206s) Bail out!  pg_ctl restart failed
# No postmaster PID for node "primary"