[13:49:45.116](0.038s) # setting up data directory
# Checking port 61964
# Found port 61964
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=61964 host=/tmp/VTtktiSWHO
Log file: /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log
[13:49:45.135](0.019s) # 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 35714
[13:49:45.443](0.308s) 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 35933
[13:49:46.630](1.187s) # 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-22 13:49:45.315 UTC [35714][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-22 13:49:45.315 UTC [35714][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:45.330 UTC [35722][startup] LOG:  database system was shut down at 2024-03-22 13:46:49 UTC
2024-03-22 13:49:45.339 UTC [35714][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:45.424 UTC [35744][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.425 UTC [35744][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-22 13:49:45.425 UTC [35744][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.439 UTC [35744][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-22 13:49:45.441 UTC [35744][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=postgres database=postgres host=[local]
2024-03-22 13:49:45.461 UTC [35756][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.462 UTC [35756][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-22 13:49:45.462 UTC [35756][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.464 UTC [35756][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-22 13:49:45.466 UTC [35756][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-03-22 13:49:45.513 UTC [35766][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.514 UTC [35766][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-22 13:49:45.514 UTC [35766][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.516 UTC [35766][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-22 13:49:45.518 UTC [35766][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local]
2024-03-22 13:49:45.545 UTC [35777][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.546 UTC [35777][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-22 13:49:45.546 UTC [35777][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.548 UTC [35777][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-22 13:49:45.549 UTC [35777][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local]
2024-03-22 13:49:45.587 UTC [35783][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.589 UTC [35783][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-22 13:49:45.589 UTC [35783][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.591 UTC [35783][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-22 13:49:45.594 UTC [35783][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=postgres database=postgres host=[local]
2024-03-22 13:49:45.630 UTC [35793][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.631 UTC [35793][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-22 13:49:45.631 UTC [35793][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.633 UTC [35793][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-22 13:49:45.677 UTC [35793][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.046 user=postgres database=postgres host=[local]
2024-03-22 13:49:45.759 UTC [35826][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.760 UTC [35826][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-22 13:49:45.760 UTC [35826][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.762 UTC [35826][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-22 13:49:45.833 UTC [35826][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.073 user=postgres database=postgres host=[local]
2024-03-22 13:49:45.900 UTC [35847][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.901 UTC [35847][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-22 13:49:45.901 UTC [35847][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.903 UTC [35847][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-22 13:49:45.962 UTC [35847][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.062 user=postgres database=postgres host=[local]
2024-03-22 13:49:46.015 UTC [35868][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:46.015 UTC [35868][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-22 13:49:46.015 UTC [35868][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:46.017 UTC [35868][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-22 13:49:46.067 UTC [35868][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.052 user=postgres database=postgres host=[local]
2024-03-22 13:49:46.160 UTC [35890][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:46.161 UTC [35890][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-22 13:49:46.161 UTC [35890][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:46.163 UTC [35890][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-22 13:49:46.199 UTC [35890][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.039 user=postgres database=postgres host=[local]
2024-03-22 13:49:46.307 UTC [35902][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:46.308 UTC [35902][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-22 13:49:46.308 UTC [35902][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:46.310 UTC [35902][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-22 13:49:46.369 UTC [35902][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.073 user=postgres database=postgres host=[local]
2024-03-22 13:49:46.410 UTC [35714][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:46.410 UTC [35714][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:46.420 UTC [35714][postmaster] LOG:  background worker "logical replication launcher" (PID 35728) exited with exit code 1
2024-03-22 13:49:46.421 UTC [35720][checkpointer] LOG:  shutting down
2024-03-22 13:49:46.421 UTC [35720][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:46.510 UTC [35720][checkpointer] LOG:  checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.089 s, sync=0.001 s, total=0.090 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45238 kB, estimate=45238 kB; lsn=0/414F100, redo lsn=0/414F100
2024-03-22 13:49:46.525 UTC [35714][postmaster] LOG:  database system is shut down
2024-03-22 13:49:46.582 UTC [35933][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-22 13:49:46.582 UTC [35933][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:46.582 UTC [35933][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:46.587 UTC [35942][startup] LOG:  database system was shut down at 2024-03-22 13:49:46 UTC
2024-03-22 13:49:46.598 UTC [35933][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:46.635 UTC [35933][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:46.635 UTC [35933][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:46.640 UTC [35933][postmaster] LOG:  background worker "logical replication launcher" (PID 35948) exited with exit code 1
2024-03-22 13:49:46.644 UTC [35940][checkpointer] LOG:  shutting down
2024-03-22 13:49:46.644 UTC [35940][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:46.645 UTC [35940][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/414F178, redo lsn=0/414F178
2024-03-22 13:49:46.650 UTC [35933][postmaster] LOG:  database system is shut down
2024-03-22 13:49:46.766 UTC [35997][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-22 13:49:46.766 UTC [35997][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[13:49:46.836](0.207s) 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 36014
[13:49:46.942](0.106s) 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-22 13:49:45.315 UTC [35714][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-22 13:49:45.315 UTC [35714][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:45.330 UTC [35722][startup] LOG:  database system was shut down at 2024-03-22 13:46:49 UTC
2024-03-22 13:49:45.339 UTC [35714][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:45.424 UTC [35744][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.425 UTC [35744][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-22 13:49:45.425 UTC [35744][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.439 UTC [35744][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-22 13:49:45.441 UTC [35744][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=postgres database=postgres host=[local]
2024-03-22 13:49:45.461 UTC [35756][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.462 UTC [35756][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-22 13:49:45.462 UTC [35756][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.464 UTC [35756][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-22 13:49:45.466 UTC [35756][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-03-22 13:49:45.513 UTC [35766][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.514 UTC [35766][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-22 13:49:45.514 UTC [35766][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.516 UTC [35766][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-22 13:49:45.518 UTC [35766][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local]
2024-03-22 13:49:45.545 UTC [35777][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.546 UTC [35777][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-22 13:49:45.546 UTC [35777][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.548 UTC [35777][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-22 13:49:45.549 UTC [35777][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local]
2024-03-22 13:49:45.587 UTC [35783][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.589 UTC [35783][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-22 13:49:45.589 UTC [35783][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.591 UTC [35783][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-22 13:49:45.594 UTC [35783][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=postgres database=postgres host=[local]
2024-03-22 13:49:45.630 UTC [35793][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.631 UTC [35793][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-22 13:49:45.631 UTC [35793][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.633 UTC [35793][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-22 13:49:45.677 UTC [35793][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.046 user=postgres database=postgres host=[local]
2024-03-22 13:49:45.759 UTC [35826][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.760 UTC [35826][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-22 13:49:45.760 UTC [35826][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.762 UTC [35826][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-22 13:49:45.833 UTC [35826][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.073 user=postgres database=postgres host=[local]
2024-03-22 13:49:45.900 UTC [35847][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.901 UTC [35847][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-22 13:49:45.901 UTC [35847][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.903 UTC [35847][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-22 13:49:45.962 UTC [35847][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.062 user=postgres database=postgres host=[local]
2024-03-22 13:49:46.015 UTC [35868][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:46.015 UTC [35868][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-22 13:49:46.015 UTC [35868][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:46.017 UTC [35868][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-22 13:49:46.067 UTC [35868][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.052 user=postgres database=postgres host=[local]
2024-03-22 13:49:46.160 UTC [35890][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:46.161 UTC [35890][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-22 13:49:46.161 UTC [35890][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:46.163 UTC [35890][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-22 13:49:46.199 UTC [35890][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.039 user=postgres database=postgres host=[local]
2024-03-22 13:49:46.307 UTC [35902][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:46.308 UTC [35902][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-22 13:49:46.308 UTC [35902][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:46.310 UTC [35902][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-22 13:49:46.369 UTC [35902][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.073 user=postgres database=postgres host=[local]
2024-03-22 13:49:46.410 UTC [35714][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:46.410 UTC [35714][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:46.420 UTC [35714][postmaster] LOG:  background worker "logical replication launcher" (PID 35728) exited with exit code 1
2024-03-22 13:49:46.421 UTC [35720][checkpointer] LOG:  shutting down
2024-03-22 13:49:46.421 UTC [35720][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:46.510 UTC [35720][checkpointer] LOG:  checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.089 s, sync=0.001 s, total=0.090 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45238 kB, estimate=45238 kB; lsn=0/414F100, redo lsn=0/414F100
2024-03-22 13:49:46.525 UTC [35714][postmaster] LOG:  database system is shut down
2024-03-22 13:49:46.582 UTC [35933][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-22 13:49:46.582 UTC [35933][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:46.582 UTC [35933][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:46.587 UTC [35942][startup] LOG:  database system was shut down at 2024-03-22 13:49:46 UTC
2024-03-22 13:49:46.598 UTC [35933][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:46.635 UTC [35933][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:46.635 UTC [35933][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:46.640 UTC [35933][postmaster] LOG:  background worker "logical replication launcher" (PID 35948) exited with exit code 1
2024-03-22 13:49:46.644 UTC [35940][checkpointer] LOG:  shutting down
2024-03-22 13:49:46.644 UTC [35940][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:46.645 UTC [35940][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/414F178, redo lsn=0/414F178
2024-03-22 13:49:46.650 UTC [35933][postmaster] LOG:  database system is shut down
2024-03-22 13:49:46.766 UTC [35997][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-22 13:49:46.766 UTC [35997][postmaster] LOG:  database system is shut down
2024-03-22 13:49:46.874 UTC [36014][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-22 13:49:46.874 UTC [36014][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:46.875 UTC [36014][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:46.882 UTC [36033][startup] LOG:  database system was shut down at 2024-03-22 13:49:46 UTC
2024-03-22 13:49:46.890 UTC [36014][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:46.955 UTC [36014][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:46.955 UTC [36014][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:46.972 UTC [36014][postmaster] LOG:  background worker "logical replication launcher" (PID 36039) exited with exit code 1
2024-03-22 13:49:46.972 UTC [36029][checkpointer] LOG:  shutting down
2024-03-22 13:49:46.972 UTC [36029][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:46.973 UTC [36029][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/414F1F0, redo lsn=0/414F1F0
2024-03-22 13:49:46.978 UTC [36014][postmaster] LOG:  database system is shut down
2024-03-22 13:49:47.082 UTC [36067][postmaster] FATAL:  could not set SSL protocol version range
2024-03-22 13:49:47.082 UTC [36067][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-22 13:49:47.082 UTC [36067][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[13:49:47.159](0.217s) 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 36092
[13:49:47.272](0.113s) ok 5 - restart succeeds with correct SSL protocol bounds
[13:49:47.272](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 36130
[13:49:47.505](0.233s) ok 6 - server doesn't accept non-SSL connections
[13:49:47.505](0.000s) ok 7 - server doesn't accept non-SSL connections: matches
[13:49:47.557](0.052s) ok 8 - connect without server root cert sslmode=require
[13:49:47.557](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr
[13:49:47.588](0.030s) ok 10 - connect without server root cert sslmode=verify-ca
[13:49:47.588](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches
[13:49:47.634](0.046s) ok 12 - connect without server root cert sslmode=verify-full
[13:49:47.635](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches
[13:49:47.657](0.023s) ok 14 - connect with wrong server root cert sslmode=require
[13:49:47.658](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches
[13:49:47.705](0.047s) ok 16 - connect with wrong server root cert sslmode=verify-ca
[13:49:47.705](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches
[13:49:47.728](0.022s) ok 18 - connect with wrong server root cert sslmode=verify-full
[13:49:47.728](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches
[13:49:47.771](0.043s) ok 20 - connect with server CA cert, without root CA
[13:49:47.772](0.000s) ok 21 - connect with server CA cert, without root CA: matches
[13:49:47.818](0.047s) ok 22 - connect with correct server CA cert file sslmode=require
[13:49:47.818](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr
[13:49:47.855](0.036s) ok 24 - connect with correct server CA cert file sslmode=verify-ca
[13:49:47.855](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr
[13:49:47.898](0.043s) ok 26 - connect with correct server CA cert file sslmode=verify-full
[13:49:47.898](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr
[13:49:47.934](0.036s) ok 28 - cert root file that contains two certificates, order 1
[13:49:47.934](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr
[13:49:47.970](0.036s) ok 30 - cert root file that contains two certificates, order 2
[13:49:47.970](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr
[13:49:48.022](0.052s) ok 32 - connect with sslcertmode=disable
[13:49:48.022](0.000s) ok 33 - connect with sslcertmode=disable: no stderr
[13:49:48.075](0.053s) ok 34 - connect with sslcertmode=allow
[13:49:48.075](0.000s) ok 35 - connect with sslcertmode=allow: no stderr
[13:49:48.109](0.034s) ok 36 - connect with sslcertmode=require fails without a client certificate
[13:49:48.110](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches
[13:49:48.166](0.056s) ok 38 - sslcrl option with invalid file name
[13:49:48.166](0.000s) ok 39 - sslcrl option with invalid file name: no stderr
[13:49:48.208](0.042s) ok 40 - CRL belonging to a different CA
[13:49:48.209](0.000s) ok 41 - CRL belonging to a different CA: matches
[13:49:48.243](0.034s) ok 42 - directory CRL belonging to a different CA
[13:49:48.243](0.000s) ok 43 - directory CRL belonging to a different CA: matches
[13:49:48.293](0.050s) ok 44 - CRL with a non-revoked cert
[13:49:48.293](0.000s) ok 45 - CRL with a non-revoked cert: no stderr
[13:49:48.318](0.025s) ok 46 - directory CRL with a non-revoked cert
[13:49:48.319](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr
[13:49:48.344](0.025s) ok 48 - mismatch between host name and server certificate sslmode=require
[13:49:48.344](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr
[13:49:48.401](0.057s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca
[13:49:48.401](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr
[13:49:48.453](0.052s) ok 52 - mismatch between host name and server certificate sslmode=verify-full
[13:49:48.454](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 36361
[13:49:48.716](0.262s) ok 54 - IP address in the Common Name
[13:49:48.716](0.000s) ok 55 - IP address in the Common Name: no stderr
[13:49:48.765](0.049s) ok 56 - mismatch between host name and server certificate IP address
[13:49:48.766](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 36405
[13:49:49.046](0.280s) ok 58 - IP address in a dNSName
[13:49:49.046](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 36450
[13:49:49.309](0.263s) ok 60 - host name matching with X.509 Subject Alternative Names 1
[13:49:49.309](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr
[13:49:49.355](0.046s) ok 62 - host name matching with X.509 Subject Alternative Names 2
[13:49:49.355](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr
[13:49:49.396](0.042s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard
[13:49:49.397](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr
[13:49:49.416](0.019s) ok 66 - host name not matching with X.509 Subject Alternative Names
[13:49:49.416](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches
[13:49:49.441](0.025s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard
[13:49:49.441](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 36505
[13:49:49.693](0.252s) ok 70 - host name matching with a single X.509 Subject Alternative Name
[13:49:49.693](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr
[13:49:49.730](0.037s) ok 72 - host name not matching with a single X.509 Subject Alternative Name
[13:49:49.730](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches
[13:49:49.764](0.034s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard
[13:49:49.764](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 36539
[13:49:49.946](0.182s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1)
[13:49:49.946](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr
[13:49:49.984](0.037s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1)
[13:49:49.984](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr
[13:49:50.013](0.029s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1)
[13:49:50.013](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches
[13:49:50.038](0.024s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2)
[13:49:50.038](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr
[13:49:50.069](0.031s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2)
[13:49:50.069](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr
[13:49:50.099](0.030s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2)
[13:49:50.099](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr
[13:49:50.123](0.024s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2)
[13:49:50.123](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches
[13:49:50.148](0.025s) ok 90 - IPv6 host with CIDR mask does not match
[13:49:50.148](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 36615
[13:49:50.394](0.245s) ok 92 - certificate with both a CN and SANs 1
[13:49:50.394](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr
[13:49:50.418](0.024s) ok 94 - certificate with both a CN and SANs 2
[13:49:50.418](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr
[13:49:50.445](0.027s) ok 96 - certificate with both a CN and SANs ignores CN
[13:49:50.445](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 36662
[13:49:50.697](0.251s) ok 98 - certificate with both a CN and IP SANs matches CN
[13:49:50.697](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr
[13:49:50.720](0.023s) ok 100 - certificate with both a CN and IP SANs matches SAN 1
[13:49:50.720](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr
[13:49:50.745](0.025s) ok 102 - certificate with both a CN and IP SANs matches SAN 2
[13:49:50.745](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 36746
[13:49:50.990](0.245s) ok 104 - certificate with both an IP CN and IP SANs 1
[13:49:50.990](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr
[13:49:51.022](0.032s) ok 106 - certificate with both an IP CN and IP SANs 2
[13:49:51.022](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr
[13:49:51.050](0.028s) ok 108 - certificate with both an IP CN and IP SANs ignores CN
[13:49:51.050](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 36804
[13:49:51.313](0.263s) ok 110 - certificate with both an IP CN and DNS SANs matches CN
[13:49:51.313](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr
[13:49:51.351](0.038s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1
[13:49:51.351](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr
[13:49:51.376](0.025s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2
[13:49:51.376](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 36845
[13:49:51.546](0.170s) ok 116 - server certificate without CN or SANs sslmode=verify-ca
[13:49:51.546](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr
[13:49:51.574](0.028s) ok 118 - server certificate without CN or SANs sslmode=verify-full
[13:49:51.574](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 36936
[13:49:51.812](0.238s) ok 120 - sslrootcert=system does not connect with private CA
[13:49:51.812](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches
[13:49:51.824](0.012s) ok 122 - sslrootcert=system only accepts sslmode=verify-full
[13:49:51.825](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches
[13:49:51.874](0.050s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE
[13:49:51.874](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr
[13:49:51.902](0.028s) ok 126 - sslrootcert=system defaults to sslmode=verify-full
[13:49:51.903](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 37028
[13:49:52.156](0.253s) ok 128 - connects without client-side CRL
[13:49:52.156](0.000s) ok 129 - connects without client-side CRL: no stderr
[13:49:52.194](0.038s) ok 130 - does not connect with client-side CRL file
[13:49:52.194](0.000s) ok 131 - does not connect with client-side CRL file: matches
[13:49:52.221](0.026s) ok 132 - does not connect with client-side CRL directory
[13:49:52.221](0.000s) ok 133 - does not connect with client-side CRL directory: matches
# Running: psql -X -A -F , -P null=_null_ -d sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
[13:49:52.250](0.029s) ok 134 - pg_stat_ssl view without client certificate: exit code 0
[13:49:52.250](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr
[13:49:52.250](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches
[13:49:52.306](0.056s) ok 137 - connection success with correct range of TLS protocol versions
[13:49:52.306](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr
[13:49:52.318](0.011s) ok 139 - connection failure with incorrect range of TLS protocol versions
[13:49:52.318](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches
[13:49:52.329](0.011s) ok 141 - connection failure with an incorrect SSL protocol minimum bound
[13:49:52.330](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches
[13:49:52.346](0.017s) ok 143 - connection failure with an incorrect SSL protocol maximum bound
[13:49:52.347](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches
[13:49:52.347](0.000s) # running server tests
[13:49:52.372](0.025s) ok 145 - certificate authorization fails without client cert
[13:49:52.372](0.000s) ok 146 - certificate authorization fails without client cert: matches
[13:49:52.417](0.044s) ok 147 - certificate authorization succeeds with correct client cert in PEM format
[13:49:52.417](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr
[13:49:52.452](0.035s) ok 149 - certificate authorization succeeds with correct client cert in DER format
[13:49:52.452](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr
[13:49:52.488](0.035s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format
[13:49:52.488](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr
[13:49:52.518](0.030s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format
[13:49:52.518](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr
[13:49:52.554](0.036s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require
[13:49:52.554](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr
[13:49:52.588](0.033s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow
[13:49:52.588](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr
[13:49:52.612](0.024s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable
[13:49:52.612](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches
[13:49:52.640](0.028s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format
[13:49:52.640](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches
[13:49:52.690](0.050s) ok 163 - certificate authorization succeeds with DN mapping
[13:49:52.691](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr
[13:49:52.691](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches
[13:49:52.739](0.048s) ok 166 - certificate authorization succeeds with DN regex mapping
[13:49:52.740](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr
[13:49:52.777](0.038s) ok 168 - certificate authorization succeeds with CN mapping
[13:49:52.778](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr
[13:49:52.778](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches
[13:49:52.778](0.000s) not ok 171 # TODO & SKIP Need Pty support
[13:49:52.778](0.000s) not ok 172 # TODO & SKIP Need Pty support
[13:49:52.778](0.000s) not ok 173 # TODO & SKIP Need Pty support
[13:49:52.779](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_xtlt/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
[13:49:52.811](0.033s) ok 175 - pg_stat_ssl with client certificate: exit code 0
[13:49:52.811](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr
[13:49:52.812](0.000s) ok 177 - pg_stat_ssl with client certificate: matches
[13:49:52.845](0.033s) ok 178 - certificate authorization fails because of file permissions
[13:49:52.845](0.000s) ok 179 - certificate authorization fails because of file permissions: matches
[13:49:52.875](0.030s) ok 180 - certificate authorization fails with client cert belonging to another user
[13:49:52.875](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches
[13:49:52.904](0.029s) ok 182 - certificate authorization fails with revoked client cert
[13:49:52.905](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches
[13:49:52.905](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match
[13:49:52.957](0.052s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name
[13:49:52.957](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr
[13:49:52.957](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches
[13:49:52.991](0.034s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name
[13:49:52.991](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches
[13:49:52.991](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match
[13:49:53.024](0.032s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name
[13:49:53.024](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr
[13:49:53.024](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 37262
[13:49:53.272](0.248s) ok 194 - intermediate client certificate is provided by client
[13:49:53.273](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr
[13:49:53.304](0.031s) ok 196 - intermediate client certificate is missing
[13:49:53.304](0.000s) ok 197 - intermediate client certificate is missing: matches
[13:49:53.327](0.023s) ok 198 - logged client certificate Subjects are truncated if they're too long
[13:49:53.327](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 37332
[13:49:53.571](0.243s) ok 200 - intermediate client certificate is untrusted
[13:49:53.571](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 37377
[13:49:53.809](0.238s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory
[13:49:53.809](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches
[13:49:53.841](0.032s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory
[13:49:53.841](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 37473
[13:49:54.079](0.238s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1
[13:49:54.080](0.000s) 
[13:49:54.080](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1'
#   at t/001_ssltests.pl line 923.
[13:49:54.080](0.000s) #          got: '2'
#     expected: '0'
[13:49:54.080](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[13:49:54.080](0.000s) 
[13:49:54.080](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr'
#   at t/001_ssltests.pl line 923.
[13:49:54.081](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 61964 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: ''
[13:49:54.126](0.046s) ok 208 - connect without requesting ocsp response when sslocspstapling=0
[13:49:54.127](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 37530
[13:49:54.371](0.245s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1
[13:49:54.427](0.056s) ok 211 - connect without requesting ocsp response when sslocspstapling=0
[13:49:54.428](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 37592
[13:49:54.661](0.234s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1
[13:49:54.721](0.060s) ok 214 - connect without requesting ocsp response when sslocspstapling=0
[13:49:54.721](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 37648
[13:49:54.969](0.247s) ok 216 - failed with an expired ocsp response when sslocspstapling=1
[13:49:55.017](0.049s) ok 217 - connect without requesting ocsp response when sslocspstapling=0
[13:49:55.017](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 37700
[13:49:55.269](0.252s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1
[13:49:55.270](0.000s) 
[13:49:55.270](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1'
#   at t/001_ssltests.pl line 1001.
[13:49:55.270](0.000s) #          got: '2'
#     expected: '0'
[13:49:55.270](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[13:49:55.270](0.000s) 
[13:49:55.270](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr'
#   at t/001_ssltests.pl line 1001.
[13:49:55.270](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 61964 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: ''
[13:49:55.320](0.050s) ok 221 - connect without requesting ocsp response when sslocspstapling=0
[13:49:55.320](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 37741
[13:49:55.664](0.344s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1
[13:49:55.700](0.036s) ok 224 - connect without requesting ocsp response when sslocspstapling=0
[13:49:55.700](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 37805
[13:49:55.953](0.253s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1
[13:49:55.999](0.045s) ok 227 - connect without requesting ocsp response when sslocspstapling=0
[13:49:55.999](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 37851
[13:49:56.230](0.231s) ok 229 - failed with an expired ocsp response when sslocspstapling=1
[13:49:56.268](0.038s) ok 230 - connect without requesting ocsp response when sslocspstapling=0
[13:49:56.268](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 37905
[13:49:56.511](0.243s) ok 232 - failed with an expired ocsp response when sslocspstapling=1
[13:49:56.546](0.035s) ok 233 - connect without requesting ocsp response when sslocspstapling=0
[13:49:56.546](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-22 13:49:45.315 UTC [35714][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-22 13:49:45.315 UTC [35714][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:45.330 UTC [35722][startup] LOG:  database system was shut down at 2024-03-22 13:46:49 UTC
2024-03-22 13:49:45.339 UTC [35714][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:45.424 UTC [35744][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.425 UTC [35744][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-22 13:49:45.425 UTC [35744][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.439 UTC [35744][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-22 13:49:45.441 UTC [35744][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=postgres database=postgres host=[local]
2024-03-22 13:49:45.461 UTC [35756][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.462 UTC [35756][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-22 13:49:45.462 UTC [35756][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.464 UTC [35756][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-22 13:49:45.466 UTC [35756][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=postgres database=postgres host=[local]
2024-03-22 13:49:45.513 UTC [35766][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.514 UTC [35766][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-22 13:49:45.514 UTC [35766][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.516 UTC [35766][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-22 13:49:45.518 UTC [35766][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local]
2024-03-22 13:49:45.545 UTC [35777][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.546 UTC [35777][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-22 13:49:45.546 UTC [35777][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.548 UTC [35777][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-22 13:49:45.549 UTC [35777][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local]
2024-03-22 13:49:45.587 UTC [35783][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.589 UTC [35783][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-22 13:49:45.589 UTC [35783][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.591 UTC [35783][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-22 13:49:45.594 UTC [35783][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.007 user=postgres database=postgres host=[local]
2024-03-22 13:49:45.630 UTC [35793][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.631 UTC [35793][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-22 13:49:45.631 UTC [35793][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.633 UTC [35793][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-22 13:49:45.677 UTC [35793][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.046 user=postgres database=postgres host=[local]
2024-03-22 13:49:45.759 UTC [35826][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.760 UTC [35826][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-22 13:49:45.760 UTC [35826][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.762 UTC [35826][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-22 13:49:45.833 UTC [35826][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.073 user=postgres database=postgres host=[local]
2024-03-22 13:49:45.900 UTC [35847][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:45.901 UTC [35847][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-22 13:49:45.901 UTC [35847][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:45.903 UTC [35847][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-22 13:49:45.962 UTC [35847][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.062 user=postgres database=postgres host=[local]
2024-03-22 13:49:46.015 UTC [35868][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:46.015 UTC [35868][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-22 13:49:46.015 UTC [35868][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:46.017 UTC [35868][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-22 13:49:46.067 UTC [35868][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.052 user=postgres database=postgres host=[local]
2024-03-22 13:49:46.160 UTC [35890][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:46.161 UTC [35890][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-22 13:49:46.161 UTC [35890][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:46.163 UTC [35890][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-22 13:49:46.199 UTC [35890][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.039 user=postgres database=postgres host=[local]
2024-03-22 13:49:46.307 UTC [35902][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-22 13:49:46.308 UTC [35902][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-22 13:49:46.308 UTC [35902][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-03-22 13:49:46.310 UTC [35902][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-22 13:49:46.369 UTC [35902][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.073 user=postgres database=postgres host=[local]
2024-03-22 13:49:46.410 UTC [35714][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:46.410 UTC [35714][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:46.420 UTC [35714][postmaster] LOG:  background worker "logical replication launcher" (PID 35728) exited with exit code 1
2024-03-22 13:49:46.421 UTC [35720][checkpointer] LOG:  shutting down
2024-03-22 13:49:46.421 UTC [35720][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:46.510 UTC [35720][checkpointer] LOG:  checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.089 s, sync=0.001 s, total=0.090 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45238 kB, estimate=45238 kB; lsn=0/414F100, redo lsn=0/414F100
2024-03-22 13:49:46.525 UTC [35714][postmaster] LOG:  database system is shut down
2024-03-22 13:49:46.582 UTC [35933][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-22 13:49:46.582 UTC [35933][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:46.582 UTC [35933][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:46.587 UTC [35942][startup] LOG:  database system was shut down at 2024-03-22 13:49:46 UTC
2024-03-22 13:49:46.598 UTC [35933][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:46.635 UTC [35933][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:46.635 UTC [35933][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:46.640 UTC [35933][postmaster] LOG:  background worker "logical replication launcher" (PID 35948) exited with exit code 1
2024-03-22 13:49:46.644 UTC [35940][checkpointer] LOG:  shutting down
2024-03-22 13:49:46.644 UTC [35940][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:46.645 UTC [35940][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/414F178, redo lsn=0/414F178
2024-03-22 13:49:46.650 UTC [35933][postmaster] LOG:  database system is shut down
2024-03-22 13:49:46.766 UTC [35997][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-22 13:49:46.766 UTC [35997][postmaster] LOG:  database system is shut down
2024-03-22 13:49:46.874 UTC [36014][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-22 13:49:46.874 UTC [36014][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:46.875 UTC [36014][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:46.882 UTC [36033][startup] LOG:  database system was shut down at 2024-03-22 13:49:46 UTC
2024-03-22 13:49:46.890 UTC [36014][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:46.955 UTC [36014][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:46.955 UTC [36014][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:46.972 UTC [36014][postmaster] LOG:  background worker "logical replication launcher" (PID 36039) exited with exit code 1
2024-03-22 13:49:46.972 UTC [36029][checkpointer] LOG:  shutting down
2024-03-22 13:49:46.972 UTC [36029][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:46.973 UTC [36029][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/414F1F0, redo lsn=0/414F1F0
2024-03-22 13:49:46.978 UTC [36014][postmaster] LOG:  database system is shut down
2024-03-22 13:49:47.082 UTC [36067][postmaster] FATAL:  could not set SSL protocol version range
2024-03-22 13:49:47.082 UTC [36067][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-22 13:49:47.082 UTC [36067][postmaster] LOG:  database system is shut down
2024-03-22 13:49:47.234 UTC [36092][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-22 13:49:47.234 UTC [36092][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:47.234 UTC [36092][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:47.252 UTC [36102][startup] LOG:  database system was shut down at 2024-03-22 13:49:46 UTC
2024-03-22 13:49:47.263 UTC [36092][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:47.283 UTC [36092][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:47.283 UTC [36092][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:47.290 UTC [36092][postmaster] LOG:  background worker "logical replication launcher" (PID 36108) exited with exit code 1
2024-03-22 13:49:47.291 UTC [36099][checkpointer] LOG:  shutting down
2024-03-22 13:49:47.291 UTC [36099][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:47.292 UTC [36099][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/414F268, redo lsn=0/414F268
2024-03-22 13:49:47.299 UTC [36092][postmaster] LOG:  database system is shut down
2024-03-22 13:49:47.413 UTC [36130][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-22 13:49:47.413 UTC [36130][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:47.413 UTC [36130][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:47.419 UTC [36140][startup] LOG:  database system was shut down at 2024-03-22 13:49:47 UTC
2024-03-22 13:49:47.427 UTC [36130][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:47.500 UTC [36161][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=54382
2024-03-22 13:49:47.502 UTC [36161][client backend] [[unknown]][0/1:0] FATAL:  no pg_hba.conf entry for host "127.0.0.1", user "ssltestuser", database "trustdb", no encryption
2024-03-22 13:49:47.502 UTC [36161][client backend] [[unknown]][0/1:0] DETAIL:  Client IP address resolved to "localhost", forward lookup not checked.
2024-03-22 13:49:47.530 UTC [36169][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=54392
2024-03-22 13:49:47.536 UTC [36169][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-22 13:49:47.536 UTC [36169][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:47.552 UTC [36169][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid sslmode=require$$
2024-03-22 13:49:47.559 UTC [36169][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=ssltestuser database=trustdb host=localhost port=54392
2024-03-22 13:49:47.582 UTC [36178][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=54406
2024-03-22 13:49:47.587 UTC [36178][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-22 13:49:47.629 UTC [36189][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=54412
2024-03-22 13:49:47.636 UTC [36189][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-22 13:49:47.648 UTC [36193][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=54426
2024-03-22 13:49:47.659 UTC [36193][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-22 13:49:47.690 UTC [36199][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=54438
2024-03-22 13:49:47.699 UTC [36199][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-22 13:49:47.720 UTC [36206][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=54452
2024-03-22 13:49:47.727 UTC [36206][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-22 13:49:47.763 UTC [36211][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=54468
2024-03-22 13:49:47.775 UTC [36211][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-22 13:49:47.801 UTC [36220][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37410
2024-03-22 13:49:47.811 UTC [36220][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-22 13:49:47.811 UTC [36220][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:47.814 UTC [36220][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require$$
2024-03-22 13:49:47.818 UTC [36220][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=37410
2024-03-22 13:49:47.836 UTC [36229][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37412
2024-03-22 13:49:47.847 UTC [36229][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-22 13:49:47.847 UTC [36229][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:47.849 UTC [36229][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$
2024-03-22 13:49:47.850 UTC [36229][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=37412
2024-03-22 13:49:47.884 UTC [36240][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37416
2024-03-22 13:49:47.890 UTC [36240][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-22 13:49:47.890 UTC [36240][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:47.891 UTC [36240][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-full$$
2024-03-22 13:49:47.893 UTC [36240][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=37416
2024-03-22 13:49:47.919 UTC [36247][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37430
2024-03-22 13:49:47.927 UTC [36247][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-22 13:49:47.927 UTC [36247][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:47.929 UTC [36247][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-1.crt sslmode=verify-ca$$
2024-03-22 13:49:47.933 UTC [36247][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=37430
2024-03-22 13:49:47.950 UTC [36258][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37440
2024-03-22 13:49:47.958 UTC [36258][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-22 13:49:47.958 UTC [36258][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:47.960 UTC [36258][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-2.crt sslmode=verify-ca$$
2024-03-22 13:49:47.964 UTC [36258][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=37440
2024-03-22 13:49:47.988 UTC [36265][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37444
2024-03-22 13:49:47.996 UTC [36265][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-22 13:49:47.996 UTC [36265][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:47.998 UTC [36265][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=disable$$
2024-03-22 13:49:48.002 UTC [36265][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=37444
2024-03-22 13:49:48.052 UTC [36276][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37460
2024-03-22 13:49:48.068 UTC [36276][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-22 13:49:48.068 UTC [36276][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:48.070 UTC [36276][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=allow$$
2024-03-22 13:49:48.074 UTC [36276][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.022 user=ssltestuser database=trustdb host=localhost port=37460
2024-03-22 13:49:48.095 UTC [36283][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37474
2024-03-22 13:49:48.104 UTC [36283][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-22 13:49:48.104 UTC [36283][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:48.109 UTC [36283][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=37474
2024-03-22 13:49:48.142 UTC [36286][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37482
2024-03-22 13:49:48.155 UTC [36286][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-22 13:49:48.155 UTC [36286][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:48.157 UTC [36286][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=invalid$$
2024-03-22 13:49:48.160 UTC [36286][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.028 user=ssltestuser database=trustdb host=localhost port=37482
2024-03-22 13:49:48.200 UTC [36291][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37496
2024-03-22 13:49:48.205 UTC [36291][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-22 13:49:48.232 UTC [36300][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37498
2024-03-22 13:49:48.247 UTC [36300][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-22 13:49:48.272 UTC [36313][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37510
2024-03-22 13:49:48.279 UTC [36313][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-22 13:49:48.279 UTC [36313][client backend] [[unknown]][11/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:48.289 UTC [36313][client backend] [001_ssltests.pl][11/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=ssl/root+server.crl$$
2024-03-22 13:49:48.292 UTC [36313][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=trustdb host=localhost port=37510
2024-03-22 13:49:48.306 UTC [36321][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37524
2024-03-22 13:49:48.312 UTC [36321][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-22 13:49:48.312 UTC [36321][client backend] [[unknown]][12/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:48.314 UTC [36321][client backend] [001_ssltests.pl][12/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrldir=ssl/root+server-crldir$$
2024-03-22 13:49:48.318 UTC [36321][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=37524
2024-03-22 13:49:48.331 UTC [36331][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37536
2024-03-22 13:49:48.338 UTC [36331][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-22 13:49:48.338 UTC [36331][client backend] [[unknown]][13/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:48.339 UTC [36331][client backend] [001_ssltests.pl][13/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=require host=wronghost.test$$
2024-03-22 13:49:48.343 UTC [36331][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=37536
2024-03-22 13:49:48.378 UTC [36340][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37538
2024-03-22 13:49:48.395 UTC [36340][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-22 13:49:48.395 UTC [36340][client backend] [[unknown]][14/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:48.397 UTC [36340][client backend] [001_ssltests.pl][14/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=wronghost.test$$
2024-03-22 13:49:48.400 UTC [36340][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=ssltestuser database=trustdb host=localhost port=37538
2024-03-22 13:49:48.432 UTC [36349][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37546
2024-03-22 13:49:48.464 UTC [36130][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:48.464 UTC [36130][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:48.469 UTC [36130][postmaster] LOG:  background worker "logical replication launcher" (PID 36143) exited with exit code 1
2024-03-22 13:49:48.474 UTC [36137][checkpointer] LOG:  shutting down
2024-03-22 13:49:48.475 UTC [36137][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:48.475 UTC [36137][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/414F2E0, redo lsn=0/414F2E0
2024-03-22 13:49:48.481 UTC [36130][postmaster] LOG:  database system is shut down
2024-03-22 13:49:48.607 UTC [36361][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-22 13:49:48.607 UTC [36361][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:48.607 UTC [36361][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:48.612 UTC [36371][startup] LOG:  database system was shut down at 2024-03-22 13:49:48 UTC
2024-03-22 13:49:48.620 UTC [36361][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:48.686 UTC [36387][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37560
2024-03-22 13:49:48.693 UTC [36387][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-22 13:49:48.693 UTC [36387][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:48.711 UTC [36387][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-22 13:49:48.717 UTC [36387][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=ssltestuser database=trustdb host=localhost port=37560
2024-03-22 13:49:48.741 UTC [36395][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37566
2024-03-22 13:49:48.771 UTC [36361][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:48.771 UTC [36361][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:48.780 UTC [36361][postmaster] LOG:  background worker "logical replication launcher" (PID 36374) exited with exit code 1
2024-03-22 13:49:48.780 UTC [36369][checkpointer] LOG:  shutting down
2024-03-22 13:49:48.780 UTC [36369][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:48.781 UTC [36369][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/414F358, redo lsn=0/414F358
2024-03-22 13:49:48.788 UTC [36361][postmaster] LOG:  database system is shut down
2024-03-22 13:49:48.938 UTC [36405][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-22 13:49:48.938 UTC [36405][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:48.938 UTC [36405][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:48.951 UTC [36412][startup] LOG:  database system was shut down at 2024-03-22 13:49:48 UTC
2024-03-22 13:49:48.959 UTC [36405][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:49.004 UTC [36426][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37570
2024-03-22 13:49:49.023 UTC [36426][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-22 13:49:49.023 UTC [36426][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:49.040 UTC [36426][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-22 13:49:49.050 UTC [36426][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.046 user=ssltestuser database=trustdb host=localhost port=37570
2024-03-22 13:49:49.066 UTC [36405][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:49.066 UTC [36405][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:49.071 UTC [36405][postmaster] LOG:  background worker "logical replication launcher" (PID 36417) exited with exit code 1
2024-03-22 13:49:49.072 UTC [36410][checkpointer] LOG:  shutting down
2024-03-22 13:49:49.072 UTC [36410][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:49.073 UTC [36410][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/414F3D0, redo lsn=0/414F3D0
2024-03-22 13:49:49.079 UTC [36405][postmaster] LOG:  database system is shut down
2024-03-22 13:49:49.239 UTC [36450][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-22 13:49:49.239 UTC [36450][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:49.239 UTC [36450][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:49.248 UTC [36466][startup] LOG:  database system was shut down at 2024-03-22 13:49:49 UTC
2024-03-22 13:49:49.255 UTC [36450][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:49.286 UTC [36480][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37584
2024-03-22 13:49:49.292 UTC [36480][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-22 13:49:49.292 UTC [36480][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:49.305 UTC [36480][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$
2024-03-22 13:49:49.310 UTC [36480][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=localhost port=37584
2024-03-22 13:49:49.337 UTC [36486][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37594
2024-03-22 13:49:49.347 UTC [36486][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-22 13:49:49.347 UTC [36486][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:49.350 UTC [36486][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$
2024-03-22 13:49:49.352 UTC [36486][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=37594
2024-03-22 13:49:49.383 UTC [36492][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37598
2024-03-22 13:49:49.390 UTC [36492][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-22 13:49:49.390 UTC [36492][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:49.392 UTC [36492][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=foo.wildcard.pg-ssltest.test$$
2024-03-22 13:49:49.396 UTC [36492][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=37598
2024-03-22 13:49:49.407 UTC [36494][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37614
2024-03-22 13:49:49.432 UTC [36496][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37624
2024-03-22 13:49:49.450 UTC [36450][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:49.450 UTC [36450][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:49.454 UTC [36450][postmaster] LOG:  background worker "logical replication launcher" (PID 36469) exited with exit code 1
2024-03-22 13:49:49.457 UTC [36464][checkpointer] LOG:  shutting down
2024-03-22 13:49:49.457 UTC [36464][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:49.457 UTC [36464][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/414F448, redo lsn=0/414F448
2024-03-22 13:49:49.465 UTC [36450][postmaster] LOG:  database system is shut down
2024-03-22 13:49:49.576 UTC [36505][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-22 13:49:49.576 UTC [36505][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:49.576 UTC [36505][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:49.580 UTC [36510][startup] LOG:  database system was shut down at 2024-03-22 13:49:49 UTC
2024-03-22 13:49:49.588 UTC [36505][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:49.664 UTC [36524][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37640
2024-03-22 13:49:49.672 UTC [36524][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-22 13:49:49.672 UTC [36524][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:49.688 UTC [36524][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=single.alt-name.pg-ssltest.test$$
2024-03-22 13:49:49.695 UTC [36524][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=ssltestuser database=trustdb host=localhost port=37640
2024-03-22 13:49:49.712 UTC [36528][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37652
2024-03-22 13:49:49.753 UTC [36532][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37664
2024-03-22 13:49:49.785 UTC [36505][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:49.785 UTC [36505][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:49.794 UTC [36505][postmaster] LOG:  background worker "logical replication launcher" (PID 36513) exited with exit code 1
2024-03-22 13:49:49.795 UTC [36508][checkpointer] LOG:  shutting down
2024-03-22 13:49:49.795 UTC [36508][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:49.796 UTC [36508][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/414F4C0, redo lsn=0/414F4C0
2024-03-22 13:49:49.802 UTC [36505][postmaster] LOG:  database system is shut down
2024-03-22 13:49:49.833 UTC [36539][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-22 13:49:49.833 UTC [36539][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:49.833 UTC [36539][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:49.846 UTC [36542][startup] LOG:  database system was shut down at 2024-03-22 13:49:49 UTC
2024-03-22 13:49:49.856 UTC [36539][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:49.920 UTC [36552][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37670
2024-03-22 13:49:49.927 UTC [36552][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-22 13:49:49.927 UTC [36552][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:49.941 UTC [36552][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-22 13:49:49.948 UTC [36552][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.028 user=ssltestuser database=trustdb host=localhost port=37670
2024-03-22 13:49:49.970 UTC [36561][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37686
2024-03-22 13:49:49.978 UTC [36561][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-22 13:49:49.978 UTC [36561][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:49.979 UTC [36561][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.000.002.001$$
2024-03-22 13:49:49.986 UTC [36561][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=37686
2024-03-22 13:49:50.002 UTC [36570][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37696
2024-03-22 13:49:50.024 UTC [36576][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37712
2024-03-22 13:49:50.030 UTC [36576][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-22 13:49:50.030 UTC [36576][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:50.032 UTC [36576][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:DB8::1$$
2024-03-22 13:49:50.037 UTC [36576][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=37712
2024-03-22 13:49:50.055 UTC [36580][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37714
2024-03-22 13:49:50.062 UTC [36580][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-22 13:49:50.062 UTC [36580][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:50.064 UTC [36580][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8:0:0:0:0:0:1$$
2024-03-22 13:49:50.068 UTC [36580][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=37714
2024-03-22 13:49:50.085 UTC [36589][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37724
2024-03-22 13:49:50.092 UTC [36589][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-22 13:49:50.092 UTC [36589][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:50.094 UTC [36589][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::0.0.0.1$$
2024-03-22 13:49:50.099 UTC [36589][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=37724
2024-03-22 13:49:50.113 UTC [36596][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37736
2024-03-22 13:49:50.137 UTC [36604][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37744
2024-03-22 13:49:50.152 UTC [36539][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:50.152 UTC [36539][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:50.157 UTC [36539][postmaster] LOG:  background worker "logical replication launcher" (PID 36545) exited with exit code 1
2024-03-22 13:49:50.161 UTC [36540][checkpointer] LOG:  shutting down
2024-03-22 13:49:50.161 UTC [36540][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:50.162 UTC [36540][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/414F538, redo lsn=0/414F538
2024-03-22 13:49:50.169 UTC [36539][postmaster] LOG:  database system is shut down
2024-03-22 13:49:50.278 UTC [36615][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-22 13:49:50.278 UTC [36615][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:50.278 UTC [36615][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:50.283 UTC [36620][startup] LOG:  database system was shut down at 2024-03-22 13:49:50 UTC
2024-03-22 13:49:50.291 UTC [36615][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:50.368 UTC [36632][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37752
2024-03-22 13:49:50.376 UTC [36632][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-22 13:49:50.376 UTC [36632][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:50.389 UTC [36632][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$
2024-03-22 13:49:50.390 UTC [36632][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.022 user=ssltestuser database=trustdb host=localhost port=37752
2024-03-22 13:49:50.405 UTC [36637][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37766
2024-03-22 13:49:50.412 UTC [36637][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-22 13:49:50.412 UTC [36637][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:50.414 UTC [36637][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$
2024-03-22 13:49:50.420 UTC [36637][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=37766
2024-03-22 13:49:50.436 UTC [36641][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37776
2024-03-22 13:49:50.454 UTC [36615][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:50.454 UTC [36615][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:50.459 UTC [36615][postmaster] LOG:  background worker "logical replication launcher" (PID 36623) exited with exit code 1
2024-03-22 13:49:50.461 UTC [36618][checkpointer] LOG:  shutting down
2024-03-22 13:49:50.461 UTC [36618][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:50.462 UTC [36618][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/414F5B0, redo lsn=0/414F5B0
2024-03-22 13:49:50.468 UTC [36615][postmaster] LOG:  database system is shut down
2024-03-22 13:49:50.581 UTC [36662][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-22 13:49:50.581 UTC [36662][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:50.581 UTC [36662][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:50.586 UTC [36682][startup] LOG:  database system was shut down at 2024-03-22 13:49:50 UTC
2024-03-22 13:49:50.593 UTC [36662][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:50.668 UTC [36718][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37782
2024-03-22 13:49:50.675 UTC [36718][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-22 13:49:50.675 UTC [36718][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:50.692 UTC [36718][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$
2024-03-22 13:49:50.694 UTC [36718][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=localhost port=37782
2024-03-22 13:49:50.708 UTC [36727][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37790
2024-03-22 13:49:50.715 UTC [36727][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-22 13:49:50.715 UTC [36727][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:50.716 UTC [36727][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-22 13:49:50.717 UTC [36727][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=37790
2024-03-22 13:49:50.731 UTC [36729][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37798
2024-03-22 13:49:50.738 UTC [36729][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-22 13:49:50.738 UTC [36729][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:50.740 UTC [36729][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$
2024-03-22 13:49:50.741 UTC [36729][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=37798
2024-03-22 13:49:50.748 UTC [36662][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:50.748 UTC [36662][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:50.760 UTC [36662][postmaster] LOG:  background worker "logical replication launcher" (PID 36686) exited with exit code 1
2024-03-22 13:49:50.760 UTC [36679][checkpointer] LOG:  shutting down
2024-03-22 13:49:50.760 UTC [36679][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:50.761 UTC [36679][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/414F628, redo lsn=0/414F628
2024-03-22 13:49:50.767 UTC [36662][postmaster] LOG:  database system is shut down
2024-03-22 13:49:50.879 UTC [36746][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-22 13:49:50.879 UTC [36746][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:50.880 UTC [36746][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:50.891 UTC [36752][startup] LOG:  database system was shut down at 2024-03-22 13:49:50 UTC
2024-03-22 13:49:50.900 UTC [36746][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:50.964 UTC [36767][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37814
2024-03-22 13:49:50.972 UTC [36767][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-22 13:49:50.972 UTC [36767][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:50.986 UTC [36767][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.2$$
2024-03-22 13:49:50.992 UTC [36767][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.028 user=ssltestuser database=trustdb host=localhost port=37814
2024-03-22 13:49:51.009 UTC [36777][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37828
2024-03-22 13:49:51.016 UTC [36777][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-22 13:49:51.016 UTC [36777][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:51.018 UTC [36777][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$
2024-03-22 13:49:51.024 UTC [36777][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=37828
2024-03-22 13:49:51.041 UTC [36784][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37830
2024-03-22 13:49:51.059 UTC [36746][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:51.059 UTC [36746][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:51.064 UTC [36746][postmaster] LOG:  background worker "logical replication launcher" (PID 36755) exited with exit code 1
2024-03-22 13:49:51.073 UTC [36750][checkpointer] LOG:  shutting down
2024-03-22 13:49:51.073 UTC [36750][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:51.074 UTC [36750][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/414F6A0, redo lsn=0/414F6A0
2024-03-22 13:49:51.081 UTC [36746][postmaster] LOG:  database system is shut down
2024-03-22 13:49:51.214 UTC [36804][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-22 13:49:51.215 UTC [36804][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:51.215 UTC [36804][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:51.220 UTC [36808][startup] LOG:  database system was shut down at 2024-03-22 13:49:51 UTC
2024-03-22 13:49:51.230 UTC [36804][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:51.289 UTC [36820][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37840
2024-03-22 13:49:51.296 UTC [36820][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-22 13:49:51.296 UTC [36820][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:51.309 UTC [36820][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-22 13:49:51.314 UTC [36820][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=localhost port=37840
2024-03-22 13:49:51.331 UTC [36827][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37854
2024-03-22 13:49:51.338 UTC [36827][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-22 13:49:51.338 UTC [36827][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:51.340 UTC [36827][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$
2024-03-22 13:49:51.344 UTC [36827][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=37854
2024-03-22 13:49:51.362 UTC [36838][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37858
2024-03-22 13:49:51.370 UTC [36838][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-22 13:49:51.370 UTC [36838][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:51.372 UTC [36838][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$
2024-03-22 13:49:51.377 UTC [36838][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=37858
2024-03-22 13:49:51.384 UTC [36804][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:51.384 UTC [36804][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:51.397 UTC [36804][postmaster] LOG:  background worker "logical replication launcher" (PID 36811) exited with exit code 1
2024-03-22 13:49:51.397 UTC [36806][checkpointer] LOG:  shutting down
2024-03-22 13:49:51.397 UTC [36806][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:51.397 UTC [36806][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/414F718, redo lsn=0/414F718
2024-03-22 13:49:51.404 UTC [36804][postmaster] LOG:  database system is shut down
2024-03-22 13:49:51.433 UTC [36845][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-22 13:49:51.433 UTC [36845][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:51.434 UTC [36845][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:51.443 UTC [36852][startup] LOG:  database system was shut down at 2024-03-22 13:49:51 UTC
2024-03-22 13:49:51.452 UTC [36845][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:51.521 UTC [36866][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37874
2024-03-22 13:49:51.527 UTC [36866][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-22 13:49:51.527 UTC [36866][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:51.541 UTC [36866][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=common-name.pg-ssltest.test$$
2024-03-22 13:49:51.547 UTC [36866][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=ssltestuser database=trustdb host=localhost port=37874
2024-03-22 13:49:51.564 UTC [36880][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37890
2024-03-22 13:49:51.578 UTC [36845][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:51.578 UTC [36845][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:51.591 UTC [36845][postmaster] LOG:  background worker "logical replication launcher" (PID 36855) exited with exit code 1
2024-03-22 13:49:51.593 UTC [36849][checkpointer] LOG:  shutting down
2024-03-22 13:49:51.593 UTC [36849][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:51.594 UTC [36849][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/414F790, redo lsn=0/414F790
2024-03-22 13:49:51.600 UTC [36845][postmaster] LOG:  database system is shut down
2024-03-22 13:49:51.725 UTC [36936][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-22 13:49:51.725 UTC [36936][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:51.725 UTC [36936][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:51.732 UTC [36950][startup] LOG:  database system was shut down at 2024-03-22 13:49:51 UTC
2024-03-22 13:49:51.741 UTC [36936][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:51.801 UTC [36974][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37892
2024-03-22 13:49:51.810 UTC [36974][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-22 13:49:51.839 UTC [36988][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37904
2024-03-22 13:49:51.847 UTC [36988][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-22 13:49:51.847 UTC [36988][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:51.869 UTC [36988][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=system hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$
2024-03-22 13:49:51.876 UTC [36988][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.037 user=ssltestuser database=trustdb host=localhost port=37904
2024-03-22 13:49:51.893 UTC [36998][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37906
2024-03-22 13:49:51.911 UTC [36936][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:51.911 UTC [36936][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:51.916 UTC [36936][postmaster] LOG:  background worker "logical replication launcher" (PID 36958) exited with exit code 1
2024-03-22 13:49:51.916 UTC [36948][checkpointer] LOG:  shutting down
2024-03-22 13:49:51.916 UTC [36948][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:51.917 UTC [36948][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/414F808, redo lsn=0/414F808
2024-03-22 13:49:51.924 UTC [36936][postmaster] LOG:  database system is shut down
2024-03-22 13:49:52.046 UTC [37028][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-22 13:49:52.046 UTC [37028][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:52.046 UTC [37028][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:52.052 UTC [37036][startup] LOG:  database system was shut down at 2024-03-22 13:49:51 UTC
2024-03-22 13:49:52.060 UTC [37028][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:52.129 UTC [37055][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37918
2024-03-22 13:49:52.138 UTC [37055][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-22 13:49:52.138 UTC [37055][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:52.152 UTC [37055][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$
2024-03-22 13:49:52.157 UTC [37055][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.028 user=ssltestuser database=trustdb host=localhost port=37918
2024-03-22 13:49:52.180 UTC [37069][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37930
2024-03-22 13:49:52.191 UTC [37069][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-22 13:49:52.206 UTC [37075][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37940
2024-03-22 13:49:52.212 UTC [37075][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-22 13:49:52.235 UTC [37084][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37950
2024-03-22 13:49:52.242 UTC [37084][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-22 13:49:52.242 UTC [37084][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:52.244 UTC [37084][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-22 13:49:52.265 UTC [37084][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=ssltestuser database=trustdb host=localhost port=37950
2024-03-22 13:49:52.293 UTC [37099][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37958
2024-03-22 13:49:52.300 UTC [37099][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-22 13:49:52.300 UTC [37099][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.2, cipher=ECDHE-RSA-AES256-GCM-SHA384, bits=256)
2024-03-22 13:49:52.302 UTC [37099][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require ssl_min_protocol_version=TLSv1.2 ssl_max_protocol_version=TLSv1.2$$
2024-03-22 13:49:52.305 UTC [37099][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=37958
2024-03-22 13:49:52.360 UTC [37113][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37968
2024-03-22 13:49:52.368 UTC [37113][client backend] [[unknown]][3/1:0] FATAL:  connection requires a valid client certificate
2024-03-22 13:49:52.391 UTC [37120][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37980
2024-03-22 13:49:52.399 UTC [37120][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-22 13:49:52.399 UTC [37120][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:52.412 UTC [37120][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_xtlt/client.key$$
2024-03-22 13:49:52.416 UTC [37120][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=certdb host=localhost port=37980
2024-03-22 13:49:52.436 UTC [37133][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=37988
2024-03-22 13:49:52.446 UTC [37133][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-22 13:49:52.446 UTC [37133][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:52.448 UTC [37133][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_xtlt/client-der.key$$
2024-03-22 13:49:52.454 UTC [37133][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.019 user=ssltestuser database=certdb host=localhost port=37988
2024-03-22 13:49:52.472 UTC [37139][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38002
2024-03-22 13:49:52.482 UTC [37139][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-22 13:49:52.482 UTC [37139][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:52.484 UTC [37139][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_xtlt/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$
2024-03-22 13:49:52.487 UTC [37139][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=ssltestuser database=certdb host=localhost port=38002
2024-03-22 13:49:52.501 UTC [37144][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38004
2024-03-22 13:49:52.511 UTC [37144][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-22 13:49:52.511 UTC [37144][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:52.514 UTC [37144][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_xtlt/client-encrypted-der.key sslpassword='dUmmyP^#+'$$
2024-03-22 13:49:52.521 UTC [37144][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=ssltestuser database=certdb host=localhost port=38004
2024-03-22 13:49:52.537 UTC [37148][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38012
2024-03-22 13:49:52.547 UTC [37148][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-22 13:49:52.547 UTC [37148][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:52.549 UTC [37148][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_xtlt/client.key$$
2024-03-22 13:49:52.553 UTC [37148][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.016 user=ssltestuser database=certdb host=localhost port=38012
2024-03-22 13:49:52.573 UTC [37151][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38022
2024-03-22 13:49:52.581 UTC [37151][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-22 13:49:52.581 UTC [37151][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:52.583 UTC [37151][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_xtlt/client.key$$
2024-03-22 13:49:52.584 UTC [37151][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb host=localhost port=38022
2024-03-22 13:49:52.600 UTC [37156][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38028
2024-03-22 13:49:52.607 UTC [37156][client backend] [[unknown]][10/1:0] FATAL:  connection requires a valid client certificate
2024-03-22 13:49:52.631 UTC [37160][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38036
2024-03-22 13:49:52.642 UTC [37160][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-22 13:49:52.663 UTC [37165][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38044
2024-03-22 13:49:52.673 UTC [37165][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-22 13:49:52.673 UTC [37165][client backend] [[unknown]][11/1:0] LOG:  connection authorized: user=ssltestuser database=certdb_dn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:52.685 UTC [37165][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_xtlt/client-dn.key$$
2024-03-22 13:49:52.693 UTC [37165][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=ssltestuser database=certdb_dn host=localhost port=38044
2024-03-22 13:49:52.711 UTC [37178][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38058
2024-03-22 13:49:52.720 UTC [37178][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-22 13:49:52.720 UTC [37178][client backend] [[unknown]][12/1:0] LOG:  connection authorized: user=ssltestuser database=certdb_dn_re application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:52.734 UTC [37178][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_xtlt/client-dn.key$$
2024-03-22 13:49:52.739 UTC [37178][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.028 user=ssltestuser database=certdb_dn_re host=localhost port=38058
2024-03-22 13:49:52.751 UTC [37194][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38060
2024-03-22 13:49:52.761 UTC [37194][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-22 13:49:52.761 UTC [37194][client backend] [[unknown]][13/1:0] LOG:  connection authorized: user=ssltestuser database=certdb_cn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:52.773 UTC [37194][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_xtlt/client-dn.key$$
2024-03-22 13:49:52.773 UTC [37194][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.022 user=ssltestuser database=certdb_cn host=localhost port=38060
2024-03-22 13:49:52.793 UTC [37198][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38064
2024-03-22 13:49:52.803 UTC [37198][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-22 13:49:52.803 UTC [37198][client backend] [[unknown]][14/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:52.805 UTC [37198][client backend] [001_ssltests.pl][14/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-22 13:49:52.813 UTC [37198][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=ssltestuser database=certdb host=localhost port=38064
2024-03-22 13:49:52.839 UTC [37204][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38078
2024-03-22 13:49:52.847 UTC [37204][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-22 13:49:52.862 UTC [37206][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38082
2024-03-22 13:49:52.871 UTC [37206][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-22 13:49:52.871 UTC [37206][client backend] [[unknown]][15/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-22 13:49:52.871 UTC [37206][client backend] [[unknown]][15/1:0] FATAL:  certificate authentication failed for user "anotheruser"
2024-03-22 13:49:52.871 UTC [37206][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-22 13:49:52.893 UTC [37213][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38088
2024-03-22 13:49:52.903 UTC [37213][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-22 13:49:52.903 UTC [37213][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: certificate revoked.
	Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-22 13:49:52.927 UTC [37220][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38090
2024-03-22 13:49:52.940 UTC [37220][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-22 13:49:52.940 UTC [37220][client backend] [[unknown]][16/1:0] LOG:  connection authorized: user=ssltestuser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:52.952 UTC [37220][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_xtlt/client.key$$
2024-03-22 13:49:52.956 UTC [37220][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=ssltestuser database=verifydb host=localhost port=38090
2024-03-22 13:49:52.973 UTC [37231][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38100
2024-03-22 13:49:52.983 UTC [37231][client backend] [[unknown]][17/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-22 13:49:52.983 UTC [37231][client backend] [[unknown]][17/1:0] LOG:  certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch
2024-03-22 13:49:52.983 UTC [37231][client backend] [[unknown]][17/1:0] FATAL:  "trust" authentication failed for user "anotheruser"
2024-03-22 13:49:52.983 UTC [37231][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-22 13:49:53.003 UTC [37239][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38106
2024-03-22 13:49:53.012 UTC [37239][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-22 13:49:53.012 UTC [37239][client backend] [[unknown]][18/1:0] LOG:  connection authorized: user=yetanotheruser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:53.014 UTC [37239][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_xtlt/client.key$$
2024-03-22 13:49:53.017 UTC [37239][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=yetanotheruser database=verifydb host=localhost port=38106
2024-03-22 13:49:53.028 UTC [37028][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:53.028 UTC [37028][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:53.037 UTC [37028][postmaster] LOG:  background worker "logical replication launcher" (PID 37041) exited with exit code 1
2024-03-22 13:49:53.037 UTC [37034][checkpointer] LOG:  shutting down
2024-03-22 13:49:53.037 UTC [37034][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:53.038 UTC [37034][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/414F880, redo lsn=0/414F880
2024-03-22 13:49:53.046 UTC [37028][postmaster] LOG:  database system is shut down
2024-03-22 13:49:53.156 UTC [37262][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-22 13:49:53.156 UTC [37262][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:53.156 UTC [37262][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:53.161 UTC [37270][startup] LOG:  database system was shut down at 2024-03-22 13:49:53 UTC
2024-03-22 13:49:53.171 UTC [37262][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:53.246 UTC [37290][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38108
2024-03-22 13:49:53.255 UTC [37290][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-22 13:49:53.255 UTC [37290][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:53.268 UTC [37290][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_xtlt/client.key sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 host=localhost sslmode=require sslcert=ssl/client+client_ca.crt$$
2024-03-22 13:49:53.274 UTC [37290][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=ssltestuser database=certdb host=localhost port=38108
2024-03-22 13:49:53.288 UTC [37299][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38120
2024-03-22 13:49:53.295 UTC [37299][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-22 13:49:53.295 UTC [37299][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: unable to get local issuer certificate.
	Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921344, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-22 13:49:53.315 UTC [37305][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38130
2024-03-22 13:49:53.323 UTC [37305][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-22 13:49:53.323 UTC [37305][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: unable to get local issuer certificate.
	Failed certificate data (unverified): subject ".../CN=ssl-123456789012345678901234567890123456789012345678901234567890", serial number 2315702411956921348, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-22 13:49:53.331 UTC [37262][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:53.331 UTC [37262][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:53.343 UTC [37262][postmaster] LOG:  background worker "logical replication launcher" (PID 37274) exited with exit code 1
2024-03-22 13:49:53.346 UTC [37267][checkpointer] LOG:  shutting down
2024-03-22 13:49:53.346 UTC [37267][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:53.347 UTC [37267][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/414F8F8, redo lsn=0/414F8F8
2024-03-22 13:49:53.354 UTC [37262][postmaster] LOG:  database system is shut down
2024-03-22 13:49:53.477 UTC [37332][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-22 13:49:53.477 UTC [37332][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:53.477 UTC [37332][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:53.483 UTC [37342][startup] LOG:  database system was shut down at 2024-03-22 13:49:53 UTC
2024-03-22 13:49:53.492 UTC [37332][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:53.559 UTC [37357][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38138
2024-03-22 13:49:53.570 UTC [37357][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-22 13:49:53.570 UTC [37357][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 1: unable to get local issuer certificate.
	Failed certificate data (unverified): subject "/CN=Test CA for PostgreSQL SSL regression test client certs", serial number 2315702411956921089, issuer "/CN=Test root CA for PostgreSQL SSL regression test suite".
2024-03-22 13:49:53.575 UTC [37332][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:53.575 UTC [37332][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:53.580 UTC [37332][postmaster] LOG:  background worker "logical replication launcher" (PID 37346) exited with exit code 1
2024-03-22 13:49:53.586 UTC [37340][checkpointer] LOG:  shutting down
2024-03-22 13:49:53.586 UTC [37340][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:53.587 UTC [37340][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/414F970, redo lsn=0/414F970
2024-03-22 13:49:53.595 UTC [37332][postmaster] LOG:  database system is shut down
2024-03-22 13:49:53.701 UTC [37377][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-22 13:49:53.701 UTC [37377][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:53.701 UTC [37377][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:53.707 UTC [37391][startup] LOG:  database system was shut down at 2024-03-22 13:49:53 UTC
2024-03-22 13:49:53.716 UTC [37377][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:53.797 UTC [37426][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38152
2024-03-22 13:49:53.808 UTC [37426][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-22 13:49:53.808 UTC [37426][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: certificate revoked.
	Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-22 13:49:53.828 UTC [37438][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38168
2024-03-22 13:49:53.840 UTC [37438][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-22 13:49:53.840 UTC [37438][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: certificate revoked.
	Failed certificate data (unverified): subject "/CN=\xce\x9f\xce\xb4\xcf\x85\xcf\x83\xcf\x83\xce\xad\xce\xb1\xcf\x82", serial number 2315702411956921349, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-22 13:49:53.845 UTC [37377][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:53.845 UTC [37377][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:53.851 UTC [37377][postmaster] LOG:  background worker "logical replication launcher" (PID 37395) exited with exit code 1
2024-03-22 13:49:53.856 UTC [37389][checkpointer] LOG:  shutting down
2024-03-22 13:49:53.856 UTC [37389][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:53.857 UTC [37389][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/414F9E8, redo lsn=0/414F9E8
2024-03-22 13:49:53.864 UTC [37377][postmaster] LOG:  database system is shut down
2024-03-22 13:49:53.985 UTC [37473][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-22 13:49:53.985 UTC [37473][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:53.985 UTC [37473][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:53.992 UTC [37481][startup] LOG:  database system was shut down at 2024-03-22 13:49:53 UTC
2024-03-22 13:49:54.001 UTC [37473][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:54.068 UTC [37496][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38178
2024-03-22 13:49:54.070 UTC [37496][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-22 13:49:54.070 UTC [37496][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-22 13:49:54.102 UTC [37501][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38192
2024-03-22 13:49:54.108 UTC [37501][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-22 13:49:54.108 UTC [37501][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:54.122 UTC [37501][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-22 13:49:54.128 UTC [37501][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=ssltestuser database=trustdb host=localhost port=38192
2024-03-22 13:49:54.144 UTC [37473][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:54.144 UTC [37473][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:54.152 UTC [37473][postmaster] LOG:  background worker "logical replication launcher" (PID 37484) exited with exit code 1
2024-03-22 13:49:54.154 UTC [37479][checkpointer] LOG:  shutting down
2024-03-22 13:49:54.154 UTC [37479][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:54.155 UTC [37479][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/414FA60, redo lsn=0/414FA60
2024-03-22 13:49:54.162 UTC [37473][postmaster] LOG:  database system is shut down
2024-03-22 13:49:54.281 UTC [37530][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-22 13:49:54.281 UTC [37530][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:54.281 UTC [37530][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:54.296 UTC [37540][startup] LOG:  database system was shut down at 2024-03-22 13:49:54 UTC
2024-03-22 13:49:54.305 UTC [37530][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:54.361 UTC [37557][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38208
2024-03-22 13:49:54.364 UTC [37557][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-22 13:49:54.365 UTC [37557][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-22 13:49:54.371 UTC [37557][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert internal error
2024-03-22 13:49:54.398 UTC [37565][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38212
2024-03-22 13:49:54.409 UTC [37565][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-22 13:49:54.409 UTC [37565][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:54.423 UTC [37565][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-22 13:49:54.425 UTC [37565][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=ssltestuser database=trustdb host=localhost port=38212
2024-03-22 13:49:54.432 UTC [37530][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:54.432 UTC [37530][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:54.437 UTC [37530][postmaster] LOG:  background worker "logical replication launcher" (PID 37549) exited with exit code 1
2024-03-22 13:49:54.442 UTC [37538][checkpointer] LOG:  shutting down
2024-03-22 13:49:54.442 UTC [37538][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:54.443 UTC [37538][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/414FAD8, redo lsn=0/414FAD8
2024-03-22 13:49:54.450 UTC [37530][postmaster] LOG:  database system is shut down
2024-03-22 13:49:54.565 UTC [37592][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-22 13:49:54.565 UTC [37592][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:54.565 UTC [37592][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:54.579 UTC [37601][startup] LOG:  database system was shut down at 2024-03-22 13:49:54 UTC
2024-03-22 13:49:54.588 UTC [37592][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:54.651 UTC [37616][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38214
2024-03-22 13:49:54.654 UTC [37616][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-22 13:49:54.654 UTC [37616][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-22 13:49:54.663 UTC [37616][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert internal error
2024-03-22 13:49:54.695 UTC [37623][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38230
2024-03-22 13:49:54.702 UTC [37623][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-22 13:49:54.702 UTC [37623][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:54.716 UTC [37623][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-22 13:49:54.723 UTC [37623][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=ssltestuser database=trustdb host=localhost port=38230
2024-03-22 13:49:54.733 UTC [37592][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:54.733 UTC [37592][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:54.738 UTC [37592][postmaster] LOG:  background worker "logical replication launcher" (PID 37606) exited with exit code 1
2024-03-22 13:49:54.751 UTC [37599][checkpointer] LOG:  shutting down
2024-03-22 13:49:54.751 UTC [37599][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:54.752 UTC [37599][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/414FB50, redo lsn=0/414FB50
2024-03-22 13:49:54.759 UTC [37592][postmaster] LOG:  database system is shut down
2024-03-22 13:49:54.881 UTC [37648][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-22 13:49:54.881 UTC [37648][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:54.881 UTC [37648][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:54.888 UTC [37659][startup] LOG:  database system was shut down at 2024-03-22 13:49:54 UTC
2024-03-22 13:49:54.896 UTC [37648][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:54.956 UTC [37671][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38240
2024-03-22 13:49:54.959 UTC [37671][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-22 13:49:54.959 UTC [37671][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-22 13:49:54.991 UTC [37676][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38248
2024-03-22 13:49:55.000 UTC [37676][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-22 13:49:55.000 UTC [37676][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:55.013 UTC [37676][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-22 13:49:55.018 UTC [37676][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=ssltestuser database=trustdb host=localhost port=38248
2024-03-22 13:49:55.024 UTC [37648][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:55.024 UTC [37648][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:55.037 UTC [37648][postmaster] LOG:  background worker "logical replication launcher" (PID 37663) exited with exit code 1
2024-03-22 13:49:55.037 UTC [37657][checkpointer] LOG:  shutting down
2024-03-22 13:49:55.037 UTC [37657][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:55.038 UTC [37657][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/414FBC8, redo lsn=0/414FBC8
2024-03-22 13:49:55.045 UTC [37648][postmaster] LOG:  database system is shut down
2024-03-22 13:49:55.174 UTC [37700][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-22 13:49:55.174 UTC [37700][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:55.174 UTC [37700][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:55.191 UTC [37709][startup] LOG:  database system was shut down at 2024-03-22 13:49:55 UTC
2024-03-22 13:49:55.199 UTC [37700][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:55.256 UTC [37717][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38258
2024-03-22 13:49:55.259 UTC [37717][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-22 13:49:55.259 UTC [37717][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-22 13:49:55.295 UTC [37724][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38264
2024-03-22 13:49:55.301 UTC [37724][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-22 13:49:55.301 UTC [37724][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:55.315 UTC [37724][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-22 13:49:55.327 UTC [37724][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.033 user=ssltestuser database=trustdb host=localhost port=38264
2024-03-22 13:49:55.337 UTC [37700][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:55.337 UTC [37700][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:55.343 UTC [37700][postmaster] LOG:  background worker "logical replication launcher" (PID 37713) exited with exit code 1
2024-03-22 13:49:55.347 UTC [37707][checkpointer] LOG:  shutting down
2024-03-22 13:49:55.347 UTC [37707][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:55.348 UTC [37707][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/414FC40, redo lsn=0/414FC40
2024-03-22 13:49:55.355 UTC [37700][postmaster] LOG:  database system is shut down
2024-03-22 13:49:55.523 UTC [37741][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-22 13:49:55.523 UTC [37741][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:55.523 UTC [37741][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:55.537 UTC [37752][startup] LOG:  database system was shut down at 2024-03-22 13:49:55 UTC
2024-03-22 13:49:55.553 UTC [37741][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:55.652 UTC [37774][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38278
2024-03-22 13:49:55.655 UTC [37774][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-22 13:49:55.655 UTC [37774][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-22 13:49:55.676 UTC [37779][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38286
2024-03-22 13:49:55.683 UTC [37779][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-22 13:49:55.683 UTC [37779][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:55.695 UTC [37779][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-22 13:49:55.701 UTC [37779][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=trustdb host=localhost port=38286
2024-03-22 13:49:55.710 UTC [37741][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:55.710 UTC [37741][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:55.716 UTC [37741][postmaster] LOG:  background worker "logical replication launcher" (PID 37758) exited with exit code 1
2024-03-22 13:49:55.716 UTC [37750][checkpointer] LOG:  shutting down
2024-03-22 13:49:55.717 UTC [37750][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:55.717 UTC [37750][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/414FCB8, redo lsn=0/414FCB8
2024-03-22 13:49:55.723 UTC [37741][postmaster] LOG:  database system is shut down
2024-03-22 13:49:55.842 UTC [37805][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-22 13:49:55.842 UTC [37805][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:55.842 UTC [37805][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:55.847 UTC [37811][startup] LOG:  database system was shut down at 2024-03-22 13:49:55 UTC
2024-03-22 13:49:55.856 UTC [37805][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:55.940 UTC [37827][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38298
2024-03-22 13:49:55.943 UTC [37827][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-22 13:49:55.943 UTC [37827][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-22 13:49:55.974 UTC [37833][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38312
2024-03-22 13:49:55.981 UTC [37833][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-22 13:49:55.981 UTC [37833][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:55.994 UTC [37833][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-22 13:49:56.000 UTC [37833][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=ssltestuser database=trustdb host=localhost port=38312
2024-03-22 13:49:56.007 UTC [37805][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:56.007 UTC [37805][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:56.024 UTC [37805][postmaster] LOG:  background worker "logical replication launcher" (PID 37815) exited with exit code 1
2024-03-22 13:49:56.024 UTC [37809][checkpointer] LOG:  shutting down
2024-03-22 13:49:56.024 UTC [37809][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:56.025 UTC [37809][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/414FD30, redo lsn=0/414FD30
2024-03-22 13:49:56.031 UTC [37805][postmaster] LOG:  database system is shut down
2024-03-22 13:49:56.149 UTC [37851][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-22 13:49:56.149 UTC [37851][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:56.149 UTC [37851][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:56.172 UTC [37856][startup] LOG:  database system was shut down at 2024-03-22 13:49:56 UTC
2024-03-22 13:49:56.180 UTC [37851][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:56.220 UTC [37870][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38314
2024-03-22 13:49:56.223 UTC [37870][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-22 13:49:56.224 UTC [37870][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-22 13:49:56.229 UTC [37870][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-22 13:49:56.243 UTC [37875][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38330
2024-03-22 13:49:56.251 UTC [37875][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-22 13:49:56.251 UTC [37875][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:56.263 UTC [37875][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-22 13:49:56.267 UTC [37875][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=trustdb host=localhost port=38330
2024-03-22 13:49:56.275 UTC [37851][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:56.275 UTC [37851][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:56.280 UTC [37851][postmaster] LOG:  background worker "logical replication launcher" (PID 37861) exited with exit code 1
2024-03-22 13:49:56.283 UTC [37854][checkpointer] LOG:  shutting down
2024-03-22 13:49:56.283 UTC [37854][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:56.284 UTC [37854][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/414FDA8, redo lsn=0/414FDA8
2024-03-22 13:49:56.290 UTC [37851][postmaster] LOG:  database system is shut down
2024-03-22 13:49:56.399 UTC [37905][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-22 13:49:56.399 UTC [37905][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 61964
2024-03-22 13:49:56.399 UTC [37905][postmaster] LOG:  listening on Unix socket "/tmp/VTtktiSWHO/.s.PGSQL.61964"
2024-03-22 13:49:56.404 UTC [37911][startup] LOG:  database system was shut down at 2024-03-22 13:49:56 UTC
2024-03-22 13:49:56.411 UTC [37905][postmaster] LOG:  database system is ready to accept connections
2024-03-22 13:49:56.500 UTC [37928][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38340
2024-03-22 13:49:56.502 UTC [37928][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-22 13:49:56.502 UTC [37928][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-03-22 13:49:56.523 UTC [37933][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=38356
2024-03-22 13:49:56.529 UTC [37933][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-22 13:49:56.529 UTC [37933][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-22 13:49:56.541 UTC [37933][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-22 13:49:56.547 UTC [37933][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=localhost port=38356
2024-03-22 13:49:56.556 UTC [37905][postmaster] LOG:  received fast shutdown request
2024-03-22 13:49:56.556 UTC [37905][postmaster] LOG:  aborting any active transactions
2024-03-22 13:49:56.571 UTC [37905][postmaster] LOG:  background worker "logical replication launcher" (PID 37915) exited with exit code 1
2024-03-22 13:49:56.571 UTC [37909][checkpointer] LOG:  shutting down
2024-03-22 13:49:56.572 UTC [37909][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-22 13:49:56.572 UTC [37909][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/414FE20, redo lsn=0/414FE20
2024-03-22 13:49:56.578 UTC [37905][postmaster] LOG:  database system is shut down
2024-03-22 13:49:56.623 UTC [37941][postmaster] FATAL:  could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory
2024-03-22 13:49:56.623 UTC [37941][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[13:49:56.684](0.138s) Bail out!  pg_ctl restart failed
# No postmaster PID for node "primary"