[10:19:35.356](0.044s) # setting up data directory
# Checking port 63232
# Found port 63232
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=63232 host=/tmp/35INN_KzIs
Log file: /tmp/cirrus-ci-build/src/test/ssl/tmp_check/log/001_ssltests_primary.log
[10:19:35.364](0.009s) # 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 30328
[10:19:35.658](0.294s) 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 30598
[10:19:37.060](1.402s) # 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-04-01 10:19:35.539 UTC [30328][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-04-01 10:19:35.539 UTC [30328][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:35.553 UTC [30340][startup] LOG:  database system was shut down at 2024-04-01 10:17:38 UTC
2024-04-01 10:19:35.565 UTC [30328][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:35.639 UTC [30364][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:35.640 UTC [30364][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-04-01 10:19:35.640 UTC [30364][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:35.655 UTC [30364][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-04-01 10:19:35.659 UTC [30364][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=postgres database=postgres host=[local]
2024-04-01 10:19:35.673 UTC [30375][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:35.674 UTC [30375][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-04-01 10:19:35.674 UTC [30375][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:35.676 UTC [30375][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-04-01 10:19:35.678 UTC [30375][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-04-01 10:19:35.732 UTC [30389][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:35.732 UTC [30389][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-04-01 10:19:35.732 UTC [30389][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:35.734 UTC [30389][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-04-01 10:19:35.746 UTC [30389][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=postgres database=postgres host=[local]
2024-04-01 10:19:35.788 UTC [30403][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:35.789 UTC [30403][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-04-01 10:19:35.789 UTC [30403][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:35.791 UTC [30403][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-04-01 10:19:35.793 UTC [30403][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-04-01 10:19:35.855 UTC [30413][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:35.856 UTC [30413][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-04-01 10:19:35.856 UTC [30413][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:35.857 UTC [30413][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-04-01 10:19:35.859 UTC [30413][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=postgres database=postgres host=[local]
2024-04-01 10:19:35.908 UTC [30420][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:35.909 UTC [30420][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-04-01 10:19:35.909 UTC [30420][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:35.913 UTC [30420][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-04-01 10:19:35.993 UTC [30420][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.085 user=postgres database=postgres host=[local]
2024-04-01 10:19:36.021 UTC [30442][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:36.022 UTC [30442][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-04-01 10:19:36.022 UTC [30442][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:36.023 UTC [30442][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-04-01 10:19:36.081 UTC [30442][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.060 user=postgres database=postgres host=[local]
2024-04-01 10:19:36.152 UTC [30467][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:36.153 UTC [30467][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-04-01 10:19:36.153 UTC [30467][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:36.154 UTC [30467][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-04-01 10:19:36.198 UTC [30467][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.046 user=postgres database=postgres host=[local]
2024-04-01 10:19:36.306 UTC [30494][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:36.307 UTC [30494][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-04-01 10:19:36.307 UTC [30494][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:36.308 UTC [30494][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-04-01 10:19:36.401 UTC [30494][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.103 user=postgres database=postgres host=[local]
2024-04-01 10:19:36.590 UTC [30539][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:36.591 UTC [30539][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-04-01 10:19:36.591 UTC [30539][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:36.592 UTC [30539][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-04-01 10:19:36.629 UTC [30539][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.039 user=postgres database=postgres host=[local]
2024-04-01 10:19:36.721 UTC [30554][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:36.722 UTC [30554][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-04-01 10:19:36.722 UTC [30554][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:36.724 UTC [30554][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-04-01 10:19:36.814 UTC [30554][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.093 user=postgres database=postgres host=[local]
2024-04-01 10:19:36.831 UTC [30328][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:36.831 UTC [30328][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:36.839 UTC [30328][postmaster] LOG:  background worker "logical replication launcher" (PID 30350) exited with exit code 1
2024-04-01 10:19:36.839 UTC [30338][checkpointer] LOG:  shutting down
2024-04-01 10:19:36.839 UTC [30338][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:36.884 UTC [30338][checkpointer] LOG:  checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.045 s, sync=0.001 s, total=0.046 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45238 kB, estimate=45238 kB; lsn=0/414C170, redo lsn=0/414C170
2024-04-01 10:19:36.900 UTC [30328][postmaster] LOG:  database system is shut down
2024-04-01 10:19:37.000 UTC [30598][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-04-01 10:19:37.000 UTC [30598][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:37.000 UTC [30598][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:37.024 UTC [30612][startup] LOG:  database system was shut down at 2024-04-01 10:19:36 UTC
2024-04-01 10:19:37.032 UTC [30598][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:37.070 UTC [30598][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:37.070 UTC [30598][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:37.078 UTC [30598][postmaster] LOG:  background worker "logical replication launcher" (PID 30620) exited with exit code 1
2024-04-01 10:19:37.078 UTC [30609][checkpointer] LOG:  shutting down
2024-04-01 10:19:37.078 UTC [30609][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:37.079 UTC [30609][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/414C1E8, redo lsn=0/414C1E8
2024-04-01 10:19:37.084 UTC [30598][postmaster] LOG:  database system is shut down
2024-04-01 10:19:37.235 UTC [30651][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-04-01 10:19:37.235 UTC [30651][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[10:19:37.272](0.212s) 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 30680
[10:19:37.384](0.112s) 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-04-01 10:19:35.539 UTC [30328][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-04-01 10:19:35.539 UTC [30328][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:35.553 UTC [30340][startup] LOG:  database system was shut down at 2024-04-01 10:17:38 UTC
2024-04-01 10:19:35.565 UTC [30328][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:35.639 UTC [30364][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:35.640 UTC [30364][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-04-01 10:19:35.640 UTC [30364][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:35.655 UTC [30364][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-04-01 10:19:35.659 UTC [30364][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=postgres database=postgres host=[local]
2024-04-01 10:19:35.673 UTC [30375][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:35.674 UTC [30375][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-04-01 10:19:35.674 UTC [30375][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:35.676 UTC [30375][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-04-01 10:19:35.678 UTC [30375][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-04-01 10:19:35.732 UTC [30389][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:35.732 UTC [30389][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-04-01 10:19:35.732 UTC [30389][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:35.734 UTC [30389][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-04-01 10:19:35.746 UTC [30389][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=postgres database=postgres host=[local]
2024-04-01 10:19:35.788 UTC [30403][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:35.789 UTC [30403][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-04-01 10:19:35.789 UTC [30403][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:35.791 UTC [30403][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-04-01 10:19:35.793 UTC [30403][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-04-01 10:19:35.855 UTC [30413][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:35.856 UTC [30413][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-04-01 10:19:35.856 UTC [30413][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:35.857 UTC [30413][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-04-01 10:19:35.859 UTC [30413][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=postgres database=postgres host=[local]
2024-04-01 10:19:35.908 UTC [30420][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:35.909 UTC [30420][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-04-01 10:19:35.909 UTC [30420][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:35.913 UTC [30420][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-04-01 10:19:35.993 UTC [30420][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.085 user=postgres database=postgres host=[local]
2024-04-01 10:19:36.021 UTC [30442][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:36.022 UTC [30442][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-04-01 10:19:36.022 UTC [30442][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:36.023 UTC [30442][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-04-01 10:19:36.081 UTC [30442][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.060 user=postgres database=postgres host=[local]
2024-04-01 10:19:36.152 UTC [30467][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:36.153 UTC [30467][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-04-01 10:19:36.153 UTC [30467][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:36.154 UTC [30467][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-04-01 10:19:36.198 UTC [30467][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.046 user=postgres database=postgres host=[local]
2024-04-01 10:19:36.306 UTC [30494][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:36.307 UTC [30494][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-04-01 10:19:36.307 UTC [30494][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:36.308 UTC [30494][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-04-01 10:19:36.401 UTC [30494][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.103 user=postgres database=postgres host=[local]
2024-04-01 10:19:36.590 UTC [30539][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:36.591 UTC [30539][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-04-01 10:19:36.591 UTC [30539][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:36.592 UTC [30539][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-04-01 10:19:36.629 UTC [30539][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.039 user=postgres database=postgres host=[local]
2024-04-01 10:19:36.721 UTC [30554][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:36.722 UTC [30554][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-04-01 10:19:36.722 UTC [30554][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:36.724 UTC [30554][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-04-01 10:19:36.814 UTC [30554][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.093 user=postgres database=postgres host=[local]
2024-04-01 10:19:36.831 UTC [30328][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:36.831 UTC [30328][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:36.839 UTC [30328][postmaster] LOG:  background worker "logical replication launcher" (PID 30350) exited with exit code 1
2024-04-01 10:19:36.839 UTC [30338][checkpointer] LOG:  shutting down
2024-04-01 10:19:36.839 UTC [30338][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:36.884 UTC [30338][checkpointer] LOG:  checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.045 s, sync=0.001 s, total=0.046 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45238 kB, estimate=45238 kB; lsn=0/414C170, redo lsn=0/414C170
2024-04-01 10:19:36.900 UTC [30328][postmaster] LOG:  database system is shut down
2024-04-01 10:19:37.000 UTC [30598][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-04-01 10:19:37.000 UTC [30598][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:37.000 UTC [30598][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:37.024 UTC [30612][startup] LOG:  database system was shut down at 2024-04-01 10:19:36 UTC
2024-04-01 10:19:37.032 UTC [30598][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:37.070 UTC [30598][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:37.070 UTC [30598][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:37.078 UTC [30598][postmaster] LOG:  background worker "logical replication launcher" (PID 30620) exited with exit code 1
2024-04-01 10:19:37.078 UTC [30609][checkpointer] LOG:  shutting down
2024-04-01 10:19:37.078 UTC [30609][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:37.079 UTC [30609][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/414C1E8, redo lsn=0/414C1E8
2024-04-01 10:19:37.084 UTC [30598][postmaster] LOG:  database system is shut down
2024-04-01 10:19:37.235 UTC [30651][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-04-01 10:19:37.235 UTC [30651][postmaster] LOG:  database system is shut down
2024-04-01 10:19:37.341 UTC [30680][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-04-01 10:19:37.341 UTC [30680][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:37.341 UTC [30680][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:37.364 UTC [30700][startup] LOG:  database system was shut down at 2024-04-01 10:19:37 UTC
2024-04-01 10:19:37.381 UTC [30680][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:37.399 UTC [30680][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:37.399 UTC [30680][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:37.409 UTC [30680][postmaster] LOG:  background worker "logical replication launcher" (PID 30706) exited with exit code 1
2024-04-01 10:19:37.438 UTC [30697][checkpointer] LOG:  shutting down
2024-04-01 10:19:37.438 UTC [30697][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:37.521 UTC [30697][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.083 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414C260, redo lsn=0/414C260
2024-04-01 10:19:37.528 UTC [30680][postmaster] LOG:  database system is shut down
2024-04-01 10:19:37.664 UTC [30744][postmaster] FATAL:  could not set SSL protocol version range
2024-04-01 10:19:37.664 UTC [30744][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-04-01 10:19:37.664 UTC [30744][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[10:19:37.707](0.323s) 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 30760
[10:19:37.934](0.227s) ok 5 - restart succeeds with correct SSL protocol bounds
[10:19:37.934](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 30821
[10:19:38.172](0.238s) ok 6 - server doesn't accept non-SSL connections
[10:19:38.172](0.000s) ok 7 - server doesn't accept non-SSL connections: matches
[10:19:38.221](0.049s) ok 8 - connect without server root cert sslmode=require
[10:19:38.221](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr
[10:19:38.272](0.051s) ok 10 - connect without server root cert sslmode=verify-ca
[10:19:38.272](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches
[10:19:38.328](0.056s) ok 12 - connect without server root cert sslmode=verify-full
[10:19:38.329](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches
[10:19:38.360](0.032s) ok 14 - connect with wrong server root cert sslmode=require
[10:19:38.361](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches
[10:19:38.411](0.051s) ok 16 - connect with wrong server root cert sslmode=verify-ca
[10:19:38.411](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches
[10:19:38.446](0.034s) ok 18 - connect with wrong server root cert sslmode=verify-full
[10:19:38.446](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches
[10:19:38.503](0.057s) ok 20 - connect with server CA cert, without root CA
[10:19:38.504](0.000s) ok 21 - connect with server CA cert, without root CA: matches
[10:19:38.562](0.058s) ok 22 - connect with correct server CA cert file sslmode=require
[10:19:38.562](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr
[10:19:38.604](0.042s) ok 24 - connect with correct server CA cert file sslmode=verify-ca
[10:19:38.605](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr
[10:19:38.659](0.055s) ok 26 - connect with correct server CA cert file sslmode=verify-full
[10:19:38.660](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr
[10:19:38.702](0.043s) ok 28 - cert root file that contains two certificates, order 1
[10:19:38.703](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr
[10:19:38.746](0.044s) ok 30 - cert root file that contains two certificates, order 2
[10:19:38.746](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr
[10:19:38.770](0.023s) ok 32 - connect with sslcertmode=disable
[10:19:38.770](0.000s) ok 33 - connect with sslcertmode=disable: no stderr
[10:19:38.805](0.035s) ok 34 - connect with sslcertmode=allow
[10:19:38.807](0.002s) ok 35 - connect with sslcertmode=allow: no stderr
[10:19:38.847](0.040s) ok 36 - connect with sslcertmode=require fails without a client certificate
[10:19:38.847](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches
[10:19:38.899](0.052s) ok 38 - sslcrl option with invalid file name
[10:19:38.899](0.000s) ok 39 - sslcrl option with invalid file name: no stderr
[10:19:38.956](0.057s) ok 40 - CRL belonging to a different CA
[10:19:38.957](0.000s) ok 41 - CRL belonging to a different CA: matches
[10:19:38.997](0.040s) ok 42 - directory CRL belonging to a different CA
[10:19:38.997](0.000s) ok 43 - directory CRL belonging to a different CA: matches
[10:19:39.030](0.033s) ok 44 - CRL with a non-revoked cert
[10:19:39.030](0.000s) ok 45 - CRL with a non-revoked cert: no stderr
[10:19:39.087](0.056s) ok 46 - directory CRL with a non-revoked cert
[10:19:39.087](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr
[10:19:39.131](0.044s) ok 48 - mismatch between host name and server certificate sslmode=require
[10:19:39.131](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr
[10:19:39.181](0.050s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca
[10:19:39.182](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr
[10:19:39.203](0.021s) ok 52 - mismatch between host name and server certificate sslmode=verify-full
[10:19:39.203](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 31096
[10:19:39.515](0.312s) ok 54 - IP address in the Common Name
[10:19:39.515](0.000s) ok 55 - IP address in the Common Name: no stderr
[10:19:39.580](0.064s) ok 56 - mismatch between host name and server certificate IP address
[10:19:39.580](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 31203
[10:19:39.968](0.388s) ok 58 - IP address in a dNSName
[10:19:39.968](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 31262
[10:19:40.248](0.279s) ok 60 - host name matching with X.509 Subject Alternative Names 1
[10:19:40.248](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr
[10:19:40.293](0.045s) ok 62 - host name matching with X.509 Subject Alternative Names 2
[10:19:40.293](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr
[10:19:40.328](0.035s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard
[10:19:40.328](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr
[10:19:40.392](0.064s) ok 66 - host name not matching with X.509 Subject Alternative Names
[10:19:40.392](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches
[10:19:40.442](0.050s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard
[10:19:40.443](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 31325
[10:19:40.734](0.292s) ok 70 - host name matching with a single X.509 Subject Alternative Name
[10:19:40.735](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr
[10:19:40.759](0.024s) ok 72 - host name not matching with a single X.509 Subject Alternative Name
[10:19:40.759](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches
[10:19:40.779](0.020s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard
[10:19:40.779](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 31378
[10:19:41.088](0.309s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1)
[10:19:41.088](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr
[10:19:41.150](0.062s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1)
[10:19:41.150](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr
[10:19:41.204](0.054s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1)
[10:19:41.205](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches
[10:19:41.238](0.034s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2)
[10:19:41.239](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr
[10:19:41.288](0.050s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2)
[10:19:41.288](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr
[10:19:41.318](0.030s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2)
[10:19:41.318](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr
[10:19:41.370](0.052s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2)
[10:19:41.370](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches
[10:19:41.436](0.066s) ok 90 - IPv6 host with CIDR mask does not match
[10:19:41.436](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 31449
[10:19:41.776](0.340s) ok 92 - certificate with both a CN and SANs 1
[10:19:41.776](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr
[10:19:41.819](0.043s) ok 94 - certificate with both a CN and SANs 2
[10:19:41.819](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr
[10:19:41.899](0.080s) ok 96 - certificate with both a CN and SANs ignores CN
[10:19:41.900](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 31520
[10:19:42.287](0.388s) ok 98 - certificate with both a CN and IP SANs matches CN
[10:19:42.287](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr
[10:19:42.329](0.041s) ok 100 - certificate with both a CN and IP SANs matches SAN 1
[10:19:42.329](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr
[10:19:42.390](0.061s) ok 102 - certificate with both a CN and IP SANs matches SAN 2
[10:19:42.390](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 31604
[10:19:42.831](0.441s) ok 104 - certificate with both an IP CN and IP SANs 1
[10:19:42.831](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr
[10:19:42.912](0.081s) ok 106 - certificate with both an IP CN and IP SANs 2
[10:19:42.913](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr
[10:19:42.980](0.067s) ok 108 - certificate with both an IP CN and IP SANs ignores CN
[10:19:42.980](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 31699
[10:19:43.380](0.401s) ok 110 - certificate with both an IP CN and DNS SANs matches CN
[10:19:43.381](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr
[10:19:43.455](0.074s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1
[10:19:43.455](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr
[10:19:43.504](0.049s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2
[10:19:43.504](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 31773
[10:19:43.890](0.386s) ok 116 - server certificate without CN or SANs sslmode=verify-ca
[10:19:43.890](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr
[10:19:43.944](0.054s) ok 118 - server certificate without CN or SANs sslmode=verify-full
[10:19:43.944](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 31835
[10:19:44.318](0.374s) ok 120 - sslrootcert=system does not connect with private CA
[10:19:44.318](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches
[10:19:44.368](0.050s) ok 122 - sslrootcert=system only accepts sslmode=verify-full
[10:19:44.369](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches
[10:19:44.454](0.085s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE
[10:19:44.454](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr
[10:19:44.520](0.066s) ok 126 - sslrootcert=system defaults to sslmode=verify-full
[10:19:44.520](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 31898
[10:19:44.905](0.385s) ok 128 - connects without client-side CRL
[10:19:44.905](0.000s) ok 129 - connects without client-side CRL: no stderr
[10:19:44.951](0.046s) ok 130 - does not connect with client-side CRL file
[10:19:44.952](0.000s) ok 131 - does not connect with client-side CRL file: matches
[10:19:45.010](0.058s) ok 132 - does not connect with client-side CRL directory
[10:19:45.010](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()
[10:19:45.061](0.051s) ok 134 - pg_stat_ssl view without client certificate: exit code 0
[10:19:45.062](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr
[10:19:45.062](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches
[10:19:45.113](0.052s) ok 137 - connection success with correct range of TLS protocol versions
[10:19:45.114](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr
[10:19:45.139](0.025s) ok 139 - connection failure with incorrect range of TLS protocol versions
[10:19:45.139](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches
[10:19:45.170](0.031s) ok 141 - connection failure with an incorrect SSL protocol minimum bound
[10:19:45.170](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches
[10:19:45.200](0.029s) ok 143 - connection failure with an incorrect SSL protocol maximum bound
[10:19:45.200](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches
[10:19:45.200](0.000s) # running server tests
[10:19:45.247](0.047s) ok 145 - certificate authorization fails without client cert
[10:19:45.247](0.000s) ok 146 - certificate authorization fails without client cert: matches
[10:19:45.348](0.101s) ok 147 - certificate authorization succeeds with correct client cert in PEM format
[10:19:45.349](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr
[10:19:45.384](0.035s) ok 149 - certificate authorization succeeds with correct client cert in DER format
[10:19:45.384](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr
[10:19:45.430](0.046s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format
[10:19:45.430](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr
[10:19:45.478](0.048s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format
[10:19:45.478](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr
[10:19:45.541](0.063s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require
[10:19:45.541](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr
[10:19:45.630](0.088s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow
[10:19:45.630](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr
[10:19:45.686](0.057s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable
[10:19:45.687](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches
[10:19:45.716](0.029s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format
[10:19:45.716](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches
[10:19:45.764](0.048s) ok 163 - certificate authorization succeeds with DN mapping
[10:19:45.764](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr
[10:19:45.764](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches
[10:19:45.834](0.069s) ok 166 - certificate authorization succeeds with DN regex mapping
[10:19:45.834](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr
[10:19:45.889](0.055s) ok 168 - certificate authorization succeeds with CN mapping
[10:19:45.889](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr
[10:19:45.890](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches
[10:19:45.890](0.000s) not ok 171 # TODO & SKIP Need Pty support
[10:19:45.890](0.000s) not ok 172 # TODO & SKIP Need Pty support
[10:19:45.890](0.000s) not ok 173 # TODO & SKIP Need Pty support
[10:19:45.890](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_NxvD/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
[10:19:45.973](0.083s) ok 175 - pg_stat_ssl with client certificate: exit code 0
[10:19:45.973](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr
[10:19:45.973](0.000s) ok 177 - pg_stat_ssl with client certificate: matches
[10:19:46.019](0.046s) ok 178 - certificate authorization fails because of file permissions
[10:19:46.020](0.000s) ok 179 - certificate authorization fails because of file permissions: matches
[10:19:46.093](0.073s) ok 180 - certificate authorization fails with client cert belonging to another user
[10:19:46.093](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches
[10:19:46.140](0.047s) ok 182 - certificate authorization fails with revoked client cert
[10:19:46.140](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches
[10:19:46.140](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match
[10:19:46.218](0.078s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name
[10:19:46.218](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr
[10:19:46.218](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches
[10:19:46.245](0.027s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name
[10:19:46.246](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches
[10:19:46.246](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match
[10:19:46.281](0.035s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name
[10:19:46.281](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr
[10:19:46.281](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 32152
[10:19:46.602](0.321s) ok 194 - intermediate client certificate is provided by client
[10:19:46.602](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr
[10:19:46.644](0.042s) ok 196 - intermediate client certificate is missing
[10:19:46.645](0.000s) ok 197 - intermediate client certificate is missing: matches
[10:19:46.685](0.040s) ok 198 - logged client certificate Subjects are truncated if they're too long
[10:19:46.685](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 32237
[10:19:46.943](0.258s) ok 200 - intermediate client certificate is untrusted
[10:19:46.943](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 32289
[10:19:47.190](0.247s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory
[10:19:47.190](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches
[10:19:47.225](0.035s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory
[10:19:47.225](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 32339
[10:19:47.472](0.246s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1
[10:19:47.472](0.000s) 
[10:19:47.472](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1'
#   at t/001_ssltests.pl line 923.
[10:19:47.472](0.000s) #          got: '2'
#     expected: '0'
[10:19:47.472](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[10:19:47.472](0.000s) 
[10:19:47.472](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr'
#   at t/001_ssltests.pl line 923.
[10:19:47.472](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 63232 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: ''
[10:19:47.551](0.078s) ok 208 - connect without requesting ocsp response when sslocspstapling=0
[10:19:47.551](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 32401
[10:19:47.807](0.256s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1
[10:19:47.907](0.100s) ok 211 - connect without requesting ocsp response when sslocspstapling=0
[10:19:47.907](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 32478
[10:19:48.171](0.264s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1
[10:19:48.230](0.058s) ok 214 - connect without requesting ocsp response when sslocspstapling=0
[10:19:48.230](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 32556
[10:19:48.476](0.246s) ok 216 - failed with an expired ocsp response when sslocspstapling=1
[10:19:48.531](0.054s) ok 217 - connect without requesting ocsp response when sslocspstapling=0
[10:19:48.531](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 32597
[10:19:48.713](0.182s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1
[10:19:48.713](0.000s) 
[10:19:48.713](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1'
#   at t/001_ssltests.pl line 1001.
[10:19:48.714](0.000s) #          got: '2'
#     expected: '0'
[10:19:48.714](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[10:19:48.714](0.000s) 
[10:19:48.714](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr'
#   at t/001_ssltests.pl line 1001.
[10:19:48.714](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 63232 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: ''
[10:19:48.768](0.054s) ok 221 - connect without requesting ocsp response when sslocspstapling=0
[10:19:48.768](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 32655
[10:19:49.043](0.275s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1
[10:19:49.126](0.082s) ok 224 - connect without requesting ocsp response when sslocspstapling=0
[10:19:49.126](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 32715
[10:19:49.365](0.239s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1
[10:19:49.425](0.060s) ok 227 - connect without requesting ocsp response when sslocspstapling=0
[10:19:49.426](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 32773
[10:19:49.679](0.253s) ok 229 - failed with an expired ocsp response when sslocspstapling=1
[10:19:49.729](0.050s) ok 230 - connect without requesting ocsp response when sslocspstapling=0
[10:19:49.729](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 32801
[10:19:50.090](0.361s) ok 232 - failed with an expired ocsp response when sslocspstapling=1
[10:19:50.181](0.091s) ok 233 - connect without requesting ocsp response when sslocspstapling=0
[10:19:50.181](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-04-01 10:19:35.539 UTC [30328][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-04-01 10:19:35.539 UTC [30328][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:35.553 UTC [30340][startup] LOG:  database system was shut down at 2024-04-01 10:17:38 UTC
2024-04-01 10:19:35.565 UTC [30328][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:35.639 UTC [30364][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:35.640 UTC [30364][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-04-01 10:19:35.640 UTC [30364][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:35.655 UTC [30364][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-04-01 10:19:35.659 UTC [30364][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=postgres database=postgres host=[local]
2024-04-01 10:19:35.673 UTC [30375][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:35.674 UTC [30375][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-04-01 10:19:35.674 UTC [30375][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:35.676 UTC [30375][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-04-01 10:19:35.678 UTC [30375][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-04-01 10:19:35.732 UTC [30389][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:35.732 UTC [30389][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-04-01 10:19:35.732 UTC [30389][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:35.734 UTC [30389][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-04-01 10:19:35.746 UTC [30389][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=postgres database=postgres host=[local]
2024-04-01 10:19:35.788 UTC [30403][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:35.789 UTC [30403][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-04-01 10:19:35.789 UTC [30403][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:35.791 UTC [30403][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-04-01 10:19:35.793 UTC [30403][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-04-01 10:19:35.855 UTC [30413][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:35.856 UTC [30413][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-04-01 10:19:35.856 UTC [30413][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:35.857 UTC [30413][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-04-01 10:19:35.859 UTC [30413][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=postgres database=postgres host=[local]
2024-04-01 10:19:35.908 UTC [30420][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:35.909 UTC [30420][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-04-01 10:19:35.909 UTC [30420][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:35.913 UTC [30420][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-04-01 10:19:35.993 UTC [30420][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.085 user=postgres database=postgres host=[local]
2024-04-01 10:19:36.021 UTC [30442][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:36.022 UTC [30442][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-04-01 10:19:36.022 UTC [30442][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:36.023 UTC [30442][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-04-01 10:19:36.081 UTC [30442][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.060 user=postgres database=postgres host=[local]
2024-04-01 10:19:36.152 UTC [30467][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:36.153 UTC [30467][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-04-01 10:19:36.153 UTC [30467][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:36.154 UTC [30467][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-04-01 10:19:36.198 UTC [30467][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.046 user=postgres database=postgres host=[local]
2024-04-01 10:19:36.306 UTC [30494][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:36.307 UTC [30494][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-04-01 10:19:36.307 UTC [30494][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:36.308 UTC [30494][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-04-01 10:19:36.401 UTC [30494][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.103 user=postgres database=postgres host=[local]
2024-04-01 10:19:36.590 UTC [30539][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:36.591 UTC [30539][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-04-01 10:19:36.591 UTC [30539][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:36.592 UTC [30539][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-04-01 10:19:36.629 UTC [30539][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.039 user=postgres database=postgres host=[local]
2024-04-01 10:19:36.721 UTC [30554][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-04-01 10:19:36.722 UTC [30554][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-04-01 10:19:36.722 UTC [30554][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=001_ssltests.pl
2024-04-01 10:19:36.724 UTC [30554][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-04-01 10:19:36.814 UTC [30554][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.093 user=postgres database=postgres host=[local]
2024-04-01 10:19:36.831 UTC [30328][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:36.831 UTC [30328][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:36.839 UTC [30328][postmaster] LOG:  background worker "logical replication launcher" (PID 30350) exited with exit code 1
2024-04-01 10:19:36.839 UTC [30338][checkpointer] LOG:  shutting down
2024-04-01 10:19:36.839 UTC [30338][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:36.884 UTC [30338][checkpointer] LOG:  checkpoint complete: wrote 5620 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.045 s, sync=0.001 s, total=0.046 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45238 kB, estimate=45238 kB; lsn=0/414C170, redo lsn=0/414C170
2024-04-01 10:19:36.900 UTC [30328][postmaster] LOG:  database system is shut down
2024-04-01 10:19:37.000 UTC [30598][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-04-01 10:19:37.000 UTC [30598][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:37.000 UTC [30598][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:37.024 UTC [30612][startup] LOG:  database system was shut down at 2024-04-01 10:19:36 UTC
2024-04-01 10:19:37.032 UTC [30598][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:37.070 UTC [30598][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:37.070 UTC [30598][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:37.078 UTC [30598][postmaster] LOG:  background worker "logical replication launcher" (PID 30620) exited with exit code 1
2024-04-01 10:19:37.078 UTC [30609][checkpointer] LOG:  shutting down
2024-04-01 10:19:37.078 UTC [30609][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:37.079 UTC [30609][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/414C1E8, redo lsn=0/414C1E8
2024-04-01 10:19:37.084 UTC [30598][postmaster] LOG:  database system is shut down
2024-04-01 10:19:37.235 UTC [30651][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-04-01 10:19:37.235 UTC [30651][postmaster] LOG:  database system is shut down
2024-04-01 10:19:37.341 UTC [30680][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-04-01 10:19:37.341 UTC [30680][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:37.341 UTC [30680][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:37.364 UTC [30700][startup] LOG:  database system was shut down at 2024-04-01 10:19:37 UTC
2024-04-01 10:19:37.381 UTC [30680][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:37.399 UTC [30680][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:37.399 UTC [30680][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:37.409 UTC [30680][postmaster] LOG:  background worker "logical replication launcher" (PID 30706) exited with exit code 1
2024-04-01 10:19:37.438 UTC [30697][checkpointer] LOG:  shutting down
2024-04-01 10:19:37.438 UTC [30697][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:37.521 UTC [30697][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.083 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/414C260, redo lsn=0/414C260
2024-04-01 10:19:37.528 UTC [30680][postmaster] LOG:  database system is shut down
2024-04-01 10:19:37.664 UTC [30744][postmaster] FATAL:  could not set SSL protocol version range
2024-04-01 10:19:37.664 UTC [30744][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-04-01 10:19:37.664 UTC [30744][postmaster] LOG:  database system is shut down
2024-04-01 10:19:37.845 UTC [30760][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-04-01 10:19:37.845 UTC [30760][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:37.845 UTC [30760][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:37.867 UTC [30783][startup] LOG:  database system was shut down at 2024-04-01 10:19:37 UTC
2024-04-01 10:19:37.881 UTC [30760][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:37.944 UTC [30760][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:37.944 UTC [30760][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:37.952 UTC [30760][postmaster] LOG:  background worker "logical replication launcher" (PID 30787) exited with exit code 1
2024-04-01 10:19:37.956 UTC [30781][checkpointer] LOG:  shutting down
2024-04-01 10:19:37.956 UTC [30781][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:37.957 UTC [30781][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/414C2D8, redo lsn=0/414C2D8
2024-04-01 10:19:37.963 UTC [30760][postmaster] LOG:  database system is shut down
2024-04-01 10:19:38.098 UTC [30821][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-04-01 10:19:38.098 UTC [30821][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:38.098 UTC [30821][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:38.129 UTC [30834][startup] LOG:  database system was shut down at 2024-04-01 10:19:37 UTC
2024-04-01 10:19:38.138 UTC [30821][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:38.167 UTC [30854][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59386
2024-04-01 10:19:38.168 UTC [30854][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-04-01 10:19:38.168 UTC [30854][client backend] [[unknown]][0/1:0] DETAIL:  Client IP address resolved to "localhost", forward lookup not checked.
2024-04-01 10:19:38.197 UTC [30858][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59396
2024-04-01 10:19:38.203 UTC [30858][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-04-01 10:19:38.203 UTC [30858][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-04-01 10:19:38.217 UTC [30858][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-04-01 10:19:38.223 UTC [30858][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=ssltestuser database=trustdb host=localhost port=59396
2024-04-01 10:19:38.267 UTC [30871][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59398
2024-04-01 10:19:38.269 UTC [30871][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-04-01 10:19:38.317 UTC [30884][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59404
2024-04-01 10:19:38.319 UTC [30884][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-04-01 10:19:38.351 UTC [30895][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59410
2024-04-01 10:19:38.356 UTC [30895][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-04-01 10:19:38.394 UTC [30906][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59424
2024-04-01 10:19:38.408 UTC [30906][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-04-01 10:19:38.437 UTC [30913][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59438
2024-04-01 10:19:38.445 UTC [30913][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-04-01 10:19:38.490 UTC [30930][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59442
2024-04-01 10:19:38.498 UTC [30930][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-04-01 10:19:38.543 UTC [30944][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59454
2024-04-01 10:19:38.550 UTC [30944][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-04-01 10:19:38.550 UTC [30944][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-04-01 10:19:38.552 UTC [30944][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-04-01 10:19:38.556 UTC [30944][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=59454
2024-04-01 10:19:38.586 UTC [30961][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59468
2024-04-01 10:19:38.593 UTC [30961][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-04-01 10:19:38.593 UTC [30961][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-04-01 10:19:38.595 UTC [30961][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-04-01 10:19:38.598 UTC [30961][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=59468
2024-04-01 10:19:38.631 UTC [30967][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59472
2024-04-01 10:19:38.648 UTC [30967][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-04-01 10:19:38.648 UTC [30967][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-04-01 10:19:38.649 UTC [30967][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-04-01 10:19:38.653 UTC [30967][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=ssltestuser database=trustdb host=localhost port=59472
2024-04-01 10:19:38.689 UTC [30975][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59476
2024-04-01 10:19:38.696 UTC [30975][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-04-01 10:19:38.696 UTC [30975][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-04-01 10:19:38.698 UTC [30975][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-04-01 10:19:38.702 UTC [30975][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=59476
2024-04-01 10:19:38.722 UTC [30981][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59482
2024-04-01 10:19:38.729 UTC [30981][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-04-01 10:19:38.729 UTC [30981][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-04-01 10:19:38.731 UTC [30981][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-04-01 10:19:38.735 UTC [30981][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.019 user=ssltestuser database=trustdb host=localhost port=59482
2024-04-01 10:19:38.758 UTC [30988][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59494
2024-04-01 10:19:38.764 UTC [30988][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-04-01 10:19:38.764 UTC [30988][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-04-01 10:19:38.766 UTC [30988][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-04-01 10:19:38.769 UTC [30988][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=59494
2024-04-01 10:19:38.787 UTC [30992][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59496
2024-04-01 10:19:38.799 UTC [30992][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-04-01 10:19:38.799 UTC [30992][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-04-01 10:19:38.801 UTC [30992][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-04-01 10:19:38.804 UTC [30992][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=ssltestuser database=trustdb host=localhost port=59496
2024-04-01 10:19:38.834 UTC [30997][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59502
2024-04-01 10:19:38.842 UTC [30997][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-04-01 10:19:38.842 UTC [30997][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-04-01 10:19:38.845 UTC [30997][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=59502
2024-04-01 10:19:38.866 UTC [31008][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59516
2024-04-01 10:19:38.888 UTC [31008][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-04-01 10:19:38.888 UTC [31008][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-04-01 10:19:38.890 UTC [31008][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-04-01 10:19:38.891 UTC [31008][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=trustdb host=localhost port=59516
2024-04-01 10:19:38.942 UTC [31021][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59522
2024-04-01 10:19:38.948 UTC [31021][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-04-01 10:19:38.988 UTC [31031][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59538
2024-04-01 10:19:39.000 UTC [31031][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-04-01 10:19:39.018 UTC [31039][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59552
2024-04-01 10:19:39.025 UTC [31039][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-04-01 10:19:39.025 UTC [31039][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-04-01 10:19:39.027 UTC [31039][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-04-01 10:19:39.030 UTC [31039][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=59552
2024-04-01 10:19:39.057 UTC [31045][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59556
2024-04-01 10:19:39.065 UTC [31045][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-04-01 10:19:39.065 UTC [31045][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-04-01 10:19:39.067 UTC [31045][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-04-01 10:19:39.069 UTC [31045][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=59556
2024-04-01 10:19:39.117 UTC [31056][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59572
2024-04-01 10:19:39.125 UTC [31056][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-04-01 10:19:39.125 UTC [31056][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-04-01 10:19:39.127 UTC [31056][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-04-01 10:19:39.128 UTC [31056][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=59572
2024-04-01 10:19:39.166 UTC [31075][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59580
2024-04-01 10:19:39.172 UTC [31075][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-04-01 10:19:39.172 UTC [31075][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-04-01 10:19:39.177 UTC [31075][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-04-01 10:19:39.178 UTC [31075][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=59580
2024-04-01 10:19:39.194 UTC [31085][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59586
2024-04-01 10:19:39.211 UTC [30821][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:39.211 UTC [30821][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:39.221 UTC [30821][postmaster] LOG:  background worker "logical replication launcher" (PID 30839) exited with exit code 1
2024-04-01 10:19:39.222 UTC [30832][checkpointer] LOG:  shutting down
2024-04-01 10:19:39.222 UTC [30832][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:39.222 UTC [30832][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/414C350, redo lsn=0/414C350
2024-04-01 10:19:39.229 UTC [30821][postmaster] LOG:  database system is shut down
2024-04-01 10:19:39.320 UTC [31096][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-04-01 10:19:39.320 UTC [31096][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:39.320 UTC [31096][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:39.352 UTC [31122][startup] LOG:  database system was shut down at 2024-04-01 10:19:39 UTC
2024-04-01 10:19:39.361 UTC [31096][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:39.470 UTC [31152][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59596
2024-04-01 10:19:39.486 UTC [31152][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-04-01 10:19:39.486 UTC [31152][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-04-01 10:19:39.511 UTC [31152][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-04-01 10:19:39.522 UTC [31152][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.053 user=ssltestuser database=trustdb host=localhost port=59596
2024-04-01 10:19:39.557 UTC [31169][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59602
2024-04-01 10:19:39.590 UTC [31096][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:39.590 UTC [31096][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:39.599 UTC [31096][postmaster] LOG:  background worker "logical replication launcher" (PID 31133) exited with exit code 1
2024-04-01 10:19:39.601 UTC [31120][checkpointer] LOG:  shutting down
2024-04-01 10:19:39.601 UTC [31120][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:39.602 UTC [31120][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/414C3C8, redo lsn=0/414C3C8
2024-04-01 10:19:39.608 UTC [31096][postmaster] LOG:  database system is shut down
2024-04-01 10:19:39.789 UTC [31203][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-04-01 10:19:39.789 UTC [31203][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:39.789 UTC [31203][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:39.797 UTC [31217][startup] LOG:  database system was shut down at 2024-04-01 10:19:39 UTC
2024-04-01 10:19:39.811 UTC [31203][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:39.927 UTC [31244][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59616
2024-04-01 10:19:39.943 UTC [31244][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-04-01 10:19:39.943 UTC [31244][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-04-01 10:19:39.963 UTC [31244][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-04-01 10:19:39.971 UTC [31244][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.044 user=ssltestuser database=trustdb host=localhost port=59616
2024-04-01 10:19:39.980 UTC [31203][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:39.980 UTC [31203][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:39.987 UTC [31203][postmaster] LOG:  background worker "logical replication launcher" (PID 31224) exited with exit code 1
2024-04-01 10:19:39.989 UTC [31215][checkpointer] LOG:  shutting down
2024-04-01 10:19:39.989 UTC [31215][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:39.989 UTC [31215][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/414C440, redo lsn=0/414C440
2024-04-01 10:19:39.996 UTC [31203][postmaster] LOG:  database system is shut down
2024-04-01 10:19:40.144 UTC [31262][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-04-01 10:19:40.145 UTC [31262][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:40.145 UTC [31262][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:40.160 UTC [31278][startup] LOG:  database system was shut down at 2024-04-01 10:19:39 UTC
2024-04-01 10:19:40.169 UTC [31262][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:40.201 UTC [31284][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59626
2024-04-01 10:19:40.210 UTC [31284][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-04-01 10:19:40.210 UTC [31284][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-04-01 10:19:40.244 UTC [31284][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-04-01 10:19:40.244 UTC [31284][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.043 user=ssltestuser database=trustdb host=localhost port=59626
2024-04-01 10:19:40.274 UTC [31295][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59632
2024-04-01 10:19:40.280 UTC [31295][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-04-01 10:19:40.280 UTC [31295][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-04-01 10:19:40.285 UTC [31295][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-04-01 10:19:40.288 UTC [31295][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=59632
2024-04-01 10:19:40.312 UTC [31300][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59644
2024-04-01 10:19:40.319 UTC [31300][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-04-01 10:19:40.319 UTC [31300][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-04-01 10:19:40.321 UTC [31300][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-04-01 10:19:40.327 UTC [31300][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=59644
2024-04-01 10:19:40.374 UTC [31309][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59654
2024-04-01 10:19:40.428 UTC [31314][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59666
2024-04-01 10:19:40.459 UTC [31262][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:40.459 UTC [31262][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:40.471 UTC [31262][postmaster] LOG:  background worker "logical replication launcher" (PID 31281) exited with exit code 1
2024-04-01 10:19:40.471 UTC [31276][checkpointer] LOG:  shutting down
2024-04-01 10:19:40.472 UTC [31276][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:40.472 UTC [31276][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/414C4B8, redo lsn=0/414C4B8
2024-04-01 10:19:40.478 UTC [31262][postmaster] LOG:  database system is shut down
2024-04-01 10:19:40.530 UTC [31325][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-04-01 10:19:40.530 UTC [31325][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:40.530 UTC [31325][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:40.578 UTC [31330][startup] LOG:  database system was shut down at 2024-04-01 10:19:40 UTC
2024-04-01 10:19:40.586 UTC [31325][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:40.702 UTC [31355][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59678
2024-04-01 10:19:40.709 UTC [31355][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-04-01 10:19:40.709 UTC [31355][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-04-01 10:19:40.730 UTC [31355][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-04-01 10:19:40.731 UTC [31355][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=ssltestuser database=trustdb host=localhost port=59678
2024-04-01 10:19:40.747 UTC [31361][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59692
2024-04-01 10:19:40.770 UTC [31365][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59708
2024-04-01 10:19:40.789 UTC [31325][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:40.789 UTC [31325][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:40.794 UTC [31325][postmaster] LOG:  background worker "logical replication launcher" (PID 31336) exited with exit code 1
2024-04-01 10:19:40.798 UTC [31328][checkpointer] LOG:  shutting down
2024-04-01 10:19:40.798 UTC [31328][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:40.799 UTC [31328][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/414C530, redo lsn=0/414C530
2024-04-01 10:19:40.804 UTC [31325][postmaster] LOG:  database system is shut down
2024-04-01 10:19:40.945 UTC [31378][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-04-01 10:19:40.945 UTC [31378][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:40.945 UTC [31378][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:40.976 UTC [31383][startup] LOG:  database system was shut down at 2024-04-01 10:19:40 UTC
2024-04-01 10:19:40.984 UTC [31378][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:41.050 UTC [31390][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59716
2024-04-01 10:19:41.057 UTC [31390][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-04-01 10:19:41.057 UTC [31390][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-04-01 10:19:41.083 UTC [31390][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-04-01 10:19:41.090 UTC [31390][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.051 user=ssltestuser database=trustdb host=localhost port=59716
2024-04-01 10:19:41.138 UTC [31401][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59724
2024-04-01 10:19:41.144 UTC [31401][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-04-01 10:19:41.144 UTC [31401][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-04-01 10:19:41.146 UTC [31401][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-04-01 10:19:41.146 UTC [31401][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=59724
2024-04-01 10:19:41.164 UTC [31406][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59738
2024-04-01 10:19:41.219 UTC [31415][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59742
2024-04-01 10:19:41.231 UTC [31415][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-04-01 10:19:41.231 UTC [31415][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-04-01 10:19:41.235 UTC [31415][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-04-01 10:19:41.240 UTC [31415][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=ssltestuser database=trustdb host=localhost port=59742
2024-04-01 10:19:41.273 UTC [31420][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59758
2024-04-01 10:19:41.282 UTC [31420][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-04-01 10:19:41.282 UTC [31420][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-04-01 10:19:41.284 UTC [31420][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-04-01 10:19:41.290 UTC [31420][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=59758
2024-04-01 10:19:41.306 UTC [31422][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59772
2024-04-01 10:19:41.313 UTC [31422][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-04-01 10:19:41.313 UTC [31422][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-04-01 10:19:41.314 UTC [31422][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-04-01 10:19:41.320 UTC [31422][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=59772
2024-04-01 10:19:41.340 UTC [31428][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59784
2024-04-01 10:19:41.418 UTC [31434][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59788
2024-04-01 10:19:41.449 UTC [31378][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:41.449 UTC [31378][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:41.458 UTC [31378][postmaster] LOG:  background worker "logical replication launcher" (PID 31387) exited with exit code 1
2024-04-01 10:19:41.458 UTC [31381][checkpointer] LOG:  shutting down
2024-04-01 10:19:41.458 UTC [31381][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:41.459 UTC [31381][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/414C5A8, redo lsn=0/414C5A8
2024-04-01 10:19:41.469 UTC [31378][postmaster] LOG:  database system is shut down
2024-04-01 10:19:41.645 UTC [31449][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-04-01 10:19:41.645 UTC [31449][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:41.645 UTC [31449][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:41.659 UTC [31460][startup] LOG:  database system was shut down at 2024-04-01 10:19:41 UTC
2024-04-01 10:19:41.668 UTC [31449][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:41.718 UTC [31469][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59798
2024-04-01 10:19:41.733 UTC [31469][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-04-01 10:19:41.733 UTC [31469][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-04-01 10:19:41.772 UTC [31469][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-04-01 10:19:41.773 UTC [31469][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.059 user=ssltestuser database=trustdb host=localhost port=59798
2024-04-01 10:19:41.806 UTC [31477][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59814
2024-04-01 10:19:41.813 UTC [31477][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-04-01 10:19:41.813 UTC [31477][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-04-01 10:19:41.815 UTC [31477][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-04-01 10:19:41.826 UTC [31477][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.028 user=ssltestuser database=trustdb host=localhost port=59814
2024-04-01 10:19:41.884 UTC [31492][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59816
2024-04-01 10:19:41.920 UTC [31449][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:41.920 UTC [31449][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:41.929 UTC [31449][postmaster] LOG:  background worker "logical replication launcher" (PID 31463) exited with exit code 1
2024-04-01 10:19:41.930 UTC [31457][checkpointer] LOG:  shutting down
2024-04-01 10:19:41.930 UTC [31457][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:41.931 UTC [31457][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/414C620, redo lsn=0/414C620
2024-04-01 10:19:41.938 UTC [31449][postmaster] LOG:  database system is shut down
2024-04-01 10:19:42.106 UTC [31520][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-04-01 10:19:42.106 UTC [31520][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:42.106 UTC [31520][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:42.149 UTC [31538][startup] LOG:  database system was shut down at 2024-04-01 10:19:41 UTC
2024-04-01 10:19:42.169 UTC [31520][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:42.263 UTC [31559][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59826
2024-04-01 10:19:42.270 UTC [31559][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-04-01 10:19:42.270 UTC [31559][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-04-01 10:19:42.283 UTC [31559][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-04-01 10:19:42.291 UTC [31559][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=ssltestuser database=trustdb host=localhost port=59826
2024-04-01 10:19:42.312 UTC [31570][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59832
2024-04-01 10:19:42.321 UTC [31570][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-04-01 10:19:42.321 UTC [31570][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-04-01 10:19:42.325 UTC [31570][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-04-01 10:19:42.330 UTC [31570][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=59832
2024-04-01 10:19:42.362 UTC [31576][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59846
2024-04-01 10:19:42.385 UTC [31576][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-04-01 10:19:42.385 UTC [31576][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-04-01 10:19:42.386 UTC [31576][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-04-01 10:19:42.393 UTC [31576][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=ssltestuser database=trustdb host=localhost port=59846
2024-04-01 10:19:42.411 UTC [31520][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:42.411 UTC [31520][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:42.420 UTC [31520][postmaster] LOG:  background worker "logical replication launcher" (PID 31541) exited with exit code 1
2024-04-01 10:19:42.421 UTC [31535][checkpointer] LOG:  shutting down
2024-04-01 10:19:42.421 UTC [31535][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:42.422 UTC [31535][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/414C698, redo lsn=0/414C698
2024-04-01 10:19:42.427 UTC [31520][postmaster] LOG:  database system is shut down
2024-04-01 10:19:42.573 UTC [31604][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-04-01 10:19:42.573 UTC [31604][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:42.585 UTC [31604][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:42.604 UTC [31618][startup] LOG:  database system was shut down at 2024-04-01 10:19:42 UTC
2024-04-01 10:19:42.623 UTC [31604][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:42.767 UTC [31647][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59848
2024-04-01 10:19:42.776 UTC [31647][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-04-01 10:19:42.776 UTC [31647][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-04-01 10:19:42.827 UTC [31647][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-04-01 10:19:42.833 UTC [31647][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.067 user=ssltestuser database=trustdb host=localhost port=59848
2024-04-01 10:19:42.865 UTC [31664][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59862
2024-04-01 10:19:42.888 UTC [31664][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-04-01 10:19:42.888 UTC [31664][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-04-01 10:19:42.890 UTC [31664][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-04-01 10:19:42.893 UTC [31664][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=ssltestuser database=trustdb host=localhost port=59862
2024-04-01 10:19:42.968 UTC [31677][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59866
2024-04-01 10:19:42.992 UTC [31604][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:42.992 UTC [31604][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:43.004 UTC [31604][postmaster] LOG:  background worker "logical replication launcher" (PID 31625) exited with exit code 1
2024-04-01 10:19:43.004 UTC [31616][checkpointer] LOG:  shutting down
2024-04-01 10:19:43.004 UTC [31616][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:43.005 UTC [31616][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/414C710, redo lsn=0/414C710
2024-04-01 10:19:43.010 UTC [31604][postmaster] LOG:  database system is shut down
2024-04-01 10:19:43.181 UTC [31699][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-04-01 10:19:43.181 UTC [31699][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:43.181 UTC [31699][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:43.198 UTC [31708][startup] LOG:  database system was shut down at 2024-04-01 10:19:43 UTC
2024-04-01 10:19:43.207 UTC [31699][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:43.354 UTC [31746][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59876
2024-04-01 10:19:43.360 UTC [31746][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-04-01 10:19:43.360 UTC [31746][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-04-01 10:19:43.376 UTC [31746][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-04-01 10:19:43.377 UTC [31746][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.036 user=ssltestuser database=trustdb host=localhost port=59876
2024-04-01 10:19:43.417 UTC [31756][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59892
2024-04-01 10:19:43.445 UTC [31756][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-04-01 10:19:43.445 UTC [31756][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-04-01 10:19:43.450 UTC [31756][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-04-01 10:19:43.462 UTC [31756][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.046 user=ssltestuser database=trustdb host=localhost port=59892
2024-04-01 10:19:43.490 UTC [31761][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59904
2024-04-01 10:19:43.497 UTC [31761][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-04-01 10:19:43.497 UTC [31761][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-04-01 10:19:43.499 UTC [31761][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-04-01 10:19:43.501 UTC [31761][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=59904
2024-04-01 10:19:43.512 UTC [31699][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:43.512 UTC [31699][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:43.521 UTC [31699][postmaster] LOG:  background worker "logical replication launcher" (PID 31713) exited with exit code 1
2024-04-01 10:19:43.521 UTC [31705][checkpointer] LOG:  shutting down
2024-04-01 10:19:43.521 UTC [31705][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:43.522 UTC [31705][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/414C788, redo lsn=0/414C788
2024-04-01 10:19:43.533 UTC [31699][postmaster] LOG:  database system is shut down
2024-04-01 10:19:43.693 UTC [31773][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-04-01 10:19:43.693 UTC [31773][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:43.693 UTC [31773][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:43.722 UTC [31781][startup] LOG:  database system was shut down at 2024-04-01 10:19:43 UTC
2024-04-01 10:19:43.733 UTC [31773][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:43.856 UTC [31805][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59910
2024-04-01 10:19:43.866 UTC [31805][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-04-01 10:19:43.866 UTC [31805][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-04-01 10:19:43.886 UTC [31805][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-04-01 10:19:43.889 UTC [31805][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.033 user=ssltestuser database=trustdb host=localhost port=59910
2024-04-01 10:19:43.924 UTC [31813][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59924
2024-04-01 10:19:43.952 UTC [31773][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:43.953 UTC [31773][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:43.963 UTC [31773][postmaster] LOG:  background worker "logical replication launcher" (PID 31785) exited with exit code 1
2024-04-01 10:19:43.963 UTC [31779][checkpointer] LOG:  shutting down
2024-04-01 10:19:43.963 UTC [31779][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:43.963 UTC [31779][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/414C800, redo lsn=0/414C800
2024-04-01 10:19:43.969 UTC [31773][postmaster] LOG:  database system is shut down
2024-04-01 10:19:44.130 UTC [31835][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-04-01 10:19:44.130 UTC [31835][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:44.130 UTC [31835][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:44.153 UTC [31850][startup] LOG:  database system was shut down at 2024-04-01 10:19:43 UTC
2024-04-01 10:19:44.163 UTC [31835][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:44.304 UTC [31861][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59934
2024-04-01 10:19:44.319 UTC [31861][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-04-01 10:19:44.402 UTC [31872][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59948
2024-04-01 10:19:44.425 UTC [31872][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-04-01 10:19:44.432 UTC [31872][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-04-01 10:19:44.450 UTC [31872][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-04-01 10:19:44.456 UTC [31872][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.055 user=ssltestuser database=trustdb host=localhost port=59948
2024-04-01 10:19:44.506 UTC [31885][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59952
2024-04-01 10:19:44.535 UTC [31835][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:44.535 UTC [31835][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:44.547 UTC [31835][postmaster] LOG:  background worker "logical replication launcher" (PID 31854) exited with exit code 1
2024-04-01 10:19:44.548 UTC [31848][checkpointer] LOG:  shutting down
2024-04-01 10:19:44.548 UTC [31848][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:44.548 UTC [31848][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/414C878, redo lsn=0/414C878
2024-04-01 10:19:44.554 UTC [31835][postmaster] LOG:  database system is shut down
2024-04-01 10:19:44.720 UTC [31898][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-04-01 10:19:44.720 UTC [31898][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:44.720 UTC [31898][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:44.740 UTC [31912][startup] LOG:  database system was shut down at 2024-04-01 10:19:44 UTC
2024-04-01 10:19:44.749 UTC [31898][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:44.864 UTC [31934][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59954
2024-04-01 10:19:44.870 UTC [31934][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-04-01 10:19:44.870 UTC [31934][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-04-01 10:19:44.900 UTC [31934][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-04-01 10:19:44.907 UTC [31934][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.046 user=ssltestuser database=trustdb host=localhost port=59954
2024-04-01 10:19:44.939 UTC [31942][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59966
2024-04-01 10:19:44.953 UTC [31942][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-04-01 10:19:44.991 UTC [31950][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59982
2024-04-01 10:19:45.006 UTC [31950][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-04-01 10:19:45.035 UTC [31952][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59984
2024-04-01 10:19:45.054 UTC [31952][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-04-01 10:19:45.054 UTC [31952][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-04-01 10:19:45.056 UTC [31952][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-04-01 10:19:45.061 UTC [31952][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.028 user=ssltestuser database=trustdb host=localhost port=59984
2024-04-01 10:19:45.090 UTC [31956][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=59990
2024-04-01 10:19:45.095 UTC [31956][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-04-01 10:19:45.096 UTC [31956][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-04-01 10:19:45.109 UTC [31956][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-04-01 10:19:45.113 UTC [31956][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.028 user=ssltestuser database=trustdb host=localhost port=59990
2024-04-01 10:19:45.235 UTC [31973][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=60006
2024-04-01 10:19:45.243 UTC [31973][client backend] [[unknown]][3/1:0] FATAL:  connection requires a valid client certificate
2024-04-01 10:19:45.297 UTC [31988][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=60014
2024-04-01 10:19:45.306 UTC [31988][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-04-01 10:19:45.306 UTC [31988][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-04-01 10:19:45.344 UTC [31988][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_NxvD/client.key$$
2024-04-01 10:19:45.348 UTC [31988][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.057 user=ssltestuser database=certdb host=localhost port=60014
2024-04-01 10:19:45.370 UTC [32004][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=60028
2024-04-01 10:19:45.378 UTC [32004][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-04-01 10:19:45.378 UTC [32004][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-04-01 10:19:45.380 UTC [32004][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_NxvD/client-der.key$$
2024-04-01 10:19:45.383 UTC [32004][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=certdb host=localhost port=60028
2024-04-01 10:19:45.408 UTC [32012][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=60032
2024-04-01 10:19:45.418 UTC [32012][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-04-01 10:19:45.418 UTC [32012][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-04-01 10:19:45.420 UTC [32012][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_NxvD/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$
2024-04-01 10:19:45.421 UTC [32012][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=ssltestuser database=certdb host=localhost port=60032
2024-04-01 10:19:45.458 UTC [32019][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=60040
2024-04-01 10:19:45.467 UTC [32019][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-04-01 10:19:45.467 UTC [32019][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-04-01 10:19:45.469 UTC [32019][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_NxvD/client-encrypted-der.key sslpassword='dUmmyP^#+'$$
2024-04-01 10:19:45.469 UTC [32019][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.017 user=ssltestuser database=certdb host=localhost port=60040
2024-04-01 10:19:45.522 UTC [32029][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=60044
2024-04-01 10:19:45.535 UTC [32029][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-04-01 10:19:45.535 UTC [32029][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-04-01 10:19:45.537 UTC [32029][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_NxvD/client.key$$
2024-04-01 10:19:45.538 UTC [32029][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=ssltestuser database=certdb host=localhost port=60044
2024-04-01 10:19:45.598 UTC [32037][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=60058
2024-04-01 10:19:45.620 UTC [32037][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-04-01 10:19:45.620 UTC [32037][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-04-01 10:19:45.625 UTC [32037][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_NxvD/client.key$$
2024-04-01 10:19:45.629 UTC [32037][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.033 user=ssltestuser database=certdb host=localhost port=60058
2024-04-01 10:19:45.663 UTC [32043][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=60070
2024-04-01 10:19:45.683 UTC [32043][client backend] [[unknown]][10/1:0] FATAL:  connection requires a valid client certificate
2024-04-01 10:19:45.710 UTC [32049][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=60084
2024-04-01 10:19:45.715 UTC [32049][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-04-01 10:19:45.733 UTC [32059][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=60086
2024-04-01 10:19:45.741 UTC [32059][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-04-01 10:19:45.741 UTC [32059][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-04-01 10:19:45.759 UTC [32059][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_NxvD/client-dn.key$$
2024-04-01 10:19:45.765 UTC [32059][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.033 user=ssltestuser database=certdb_dn host=localhost port=60086
2024-04-01 10:19:45.802 UTC [32063][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=60088
2024-04-01 10:19:45.810 UTC [32063][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-04-01 10:19:45.810 UTC [32063][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-04-01 10:19:45.829 UTC [32063][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_NxvD/client-dn.key$$
2024-04-01 10:19:45.835 UTC [32063][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.041 user=ssltestuser database=certdb_dn_re host=localhost port=60088
2024-04-01 10:19:45.865 UTC [32070][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=60098
2024-04-01 10:19:45.873 UTC [32070][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-04-01 10:19:45.873 UTC [32070][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-04-01 10:19:45.885 UTC [32070][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_NxvD/client-dn.key$$
2024-04-01 10:19:45.891 UTC [32070][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.027 user=ssltestuser database=certdb_cn host=localhost port=60098
2024-04-01 10:19:45.950 UTC [32078][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=60106
2024-04-01 10:19:45.963 UTC [32078][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-04-01 10:19:45.963 UTC [32078][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-04-01 10:19:45.966 UTC [32078][client backend] [001_ssltests.pl][14/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-04-01 10:19:45.978 UTC [32078][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=ssltestuser database=certdb host=localhost port=60106
2024-04-01 10:19:46.014 UTC [32097][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=60112
2024-04-01 10:19:46.021 UTC [32097][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-04-01 10:19:46.045 UTC [32101][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=60118
2024-04-01 10:19:46.079 UTC [32101][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-04-01 10:19:46.079 UTC [32101][client backend] [[unknown]][15/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-04-01 10:19:46.079 UTC [32101][client backend] [[unknown]][15/1:0] FATAL:  certificate authentication failed for user "anotheruser"
2024-04-01 10:19:46.079 UTC [32101][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-04-01 10:19:46.111 UTC [32111][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=60124
2024-04-01 10:19:46.127 UTC [32111][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-04-01 10:19:46.127 UTC [32111][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-04-01 10:19:46.194 UTC [32122][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=60140
2024-04-01 10:19:46.202 UTC [32122][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-04-01 10:19:46.202 UTC [32122][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-04-01 10:19:46.214 UTC [32122][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_NxvD/client.key$$
2024-04-01 10:19:46.217 UTC [32122][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=verifydb host=localhost port=60140
2024-04-01 10:19:46.233 UTC [32124][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=60142
2024-04-01 10:19:46.241 UTC [32124][client backend] [[unknown]][17/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-04-01 10:19:46.241 UTC [32124][client backend] [[unknown]][17/1:0] LOG:  certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch
2024-04-01 10:19:46.241 UTC [32124][client backend] [[unknown]][17/1:0] FATAL:  "trust" authentication failed for user "anotheruser"
2024-04-01 10:19:46.241 UTC [32124][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-04-01 10:19:46.262 UTC [32126][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=60148
2024-04-01 10:19:46.270 UTC [32126][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-04-01 10:19:46.270 UTC [32126][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-04-01 10:19:46.277 UTC [32126][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_NxvD/client.key$$
2024-04-01 10:19:46.277 UTC [32126][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.015 user=yetanotheruser database=verifydb host=localhost port=60148
2024-04-01 10:19:46.290 UTC [31898][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:46.290 UTC [31898][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:46.297 UTC [31898][postmaster] LOG:  background worker "logical replication launcher" (PID 31919) exited with exit code 1
2024-04-01 10:19:46.298 UTC [31909][checkpointer] LOG:  shutting down
2024-04-01 10:19:46.298 UTC [31909][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:46.299 UTC [31909][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/414C8F0, redo lsn=0/414C8F0
2024-04-01 10:19:46.305 UTC [31898][postmaster] LOG:  database system is shut down
2024-04-01 10:19:46.431 UTC [32152][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-04-01 10:19:46.431 UTC [32152][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:46.431 UTC [32152][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:46.436 UTC [32167][startup] LOG:  database system was shut down at 2024-04-01 10:19:46 UTC
2024-04-01 10:19:46.445 UTC [32152][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:46.538 UTC [32193][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=60150
2024-04-01 10:19:46.551 UTC [32193][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-04-01 10:19:46.551 UTC [32193][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-04-01 10:19:46.597 UTC [32193][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_NxvD/client.key sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 host=localhost sslmode=require sslcert=ssl/client+client_ca.crt$$
2024-04-01 10:19:46.601 UTC [32193][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.071 user=ssltestuser database=certdb host=localhost port=60150
2024-04-01 10:19:46.634 UTC [32204][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=60164
2024-04-01 10:19:46.647 UTC [32204][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-04-01 10:19:46.647 UTC [32204][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-04-01 10:19:46.671 UTC [32214][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=60170
2024-04-01 10:19:46.686 UTC [32214][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-04-01 10:19:46.686 UTC [32214][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-04-01 10:19:46.692 UTC [32152][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:46.692 UTC [32152][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:46.697 UTC [32152][postmaster] LOG:  background worker "logical replication launcher" (PID 32174) exited with exit code 1
2024-04-01 10:19:46.699 UTC [32164][checkpointer] LOG:  shutting down
2024-04-01 10:19:46.699 UTC [32164][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:46.700 UTC [32164][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/414C968, redo lsn=0/414C968
2024-04-01 10:19:46.706 UTC [32152][postmaster] LOG:  database system is shut down
2024-04-01 10:19:46.838 UTC [32237][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-04-01 10:19:46.838 UTC [32237][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:46.838 UTC [32237][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:46.860 UTC [32251][startup] LOG:  database system was shut down at 2024-04-01 10:19:46 UTC
2024-04-01 10:19:46.869 UTC [32237][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:46.919 UTC [32267][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=35916
2024-04-01 10:19:46.926 UTC [32267][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-04-01 10:19:46.926 UTC [32267][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-04-01 10:19:46.947 UTC [32237][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:46.947 UTC [32237][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:46.951 UTC [32237][postmaster] LOG:  background worker "logical replication launcher" (PID 32260) exited with exit code 1
2024-04-01 10:19:46.960 UTC [32249][checkpointer] LOG:  shutting down
2024-04-01 10:19:46.960 UTC [32249][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:46.961 UTC [32249][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/414C9E0, redo lsn=0/414C9E0
2024-04-01 10:19:46.967 UTC [32237][postmaster] LOG:  database system is shut down
2024-04-01 10:19:47.095 UTC [32289][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-04-01 10:19:47.096 UTC [32289][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:47.096 UTC [32289][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:47.109 UTC [32302][startup] LOG:  database system was shut down at 2024-04-01 10:19:46 UTC
2024-04-01 10:19:47.125 UTC [32289][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:47.172 UTC [32318][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=35918
2024-04-01 10:19:47.180 UTC [32318][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-04-01 10:19:47.180 UTC [32318][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-04-01 10:19:47.209 UTC [32326][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=35924
2024-04-01 10:19:47.216 UTC [32326][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-04-01 10:19:47.216 UTC [32326][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-04-01 10:19:47.230 UTC [32289][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:47.230 UTC [32289][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:47.237 UTC [32289][postmaster] LOG:  background worker "logical replication launcher" (PID 32309) exited with exit code 1
2024-04-01 10:19:47.238 UTC [32300][checkpointer] LOG:  shutting down
2024-04-01 10:19:47.238 UTC [32300][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:47.239 UTC [32300][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/414CA58, redo lsn=0/414CA58
2024-04-01 10:19:47.245 UTC [32289][postmaster] LOG:  database system is shut down
2024-04-01 10:19:47.383 UTC [32339][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-04-01 10:19:47.383 UTC [32339][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:47.383 UTC [32339][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:47.391 UTC [32349][startup] LOG:  database system was shut down at 2024-04-01 10:19:47 UTC
2024-04-01 10:19:47.407 UTC [32339][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:47.458 UTC [32365][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=35938
2024-04-01 10:19:47.464 UTC [32365][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-01 10:19:47.464 UTC [32365][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-04-01 10:19:47.510 UTC [32372][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=35946
2024-04-01 10:19:47.521 UTC [32372][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-04-01 10:19:47.521 UTC [32372][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-04-01 10:19:47.546 UTC [32372][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-04-01 10:19:47.550 UTC [32372][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.041 user=ssltestuser database=trustdb host=localhost port=35946
2024-04-01 10:19:47.559 UTC [32339][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:47.559 UTC [32339][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:47.564 UTC [32339][postmaster] LOG:  background worker "logical replication launcher" (PID 32354) exited with exit code 1
2024-04-01 10:19:47.567 UTC [32347][checkpointer] LOG:  shutting down
2024-04-01 10:19:47.567 UTC [32347][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:47.568 UTC [32347][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/414CAD0, redo lsn=0/414CAD0
2024-04-01 10:19:47.574 UTC [32339][postmaster] LOG:  database system is shut down
2024-04-01 10:19:47.748 UTC [32401][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-04-01 10:19:47.748 UTC [32401][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:47.748 UTC [32401][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:47.753 UTC [32417][startup] LOG:  database system was shut down at 2024-04-01 10:19:47 UTC
2024-04-01 10:19:47.761 UTC [32401][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:47.798 UTC [32430][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=35958
2024-04-01 10:19:47.801 UTC [32430][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-01 10:19:47.801 UTC [32430][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-01 10:19:47.818 UTC [32430][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert internal error
2024-04-01 10:19:47.863 UTC [32445][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=35960
2024-04-01 10:19:47.873 UTC [32445][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-04-01 10:19:47.873 UTC [32445][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-04-01 10:19:47.903 UTC [32445][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-04-01 10:19:47.909 UTC [32445][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.048 user=ssltestuser database=trustdb host=localhost port=35960
2024-04-01 10:19:47.915 UTC [32401][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:47.915 UTC [32401][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:47.920 UTC [32401][postmaster] LOG:  background worker "logical replication launcher" (PID 32421) exited with exit code 1
2024-04-01 10:19:47.925 UTC [32414][checkpointer] LOG:  shutting down
2024-04-01 10:19:47.925 UTC [32414][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:47.926 UTC [32414][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/414CB48, redo lsn=0/414CB48
2024-04-01 10:19:47.932 UTC [32401][postmaster] LOG:  database system is shut down
2024-04-01 10:19:48.038 UTC [32478][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-04-01 10:19:48.038 UTC [32478][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:48.038 UTC [32478][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:48.043 UTC [32484][startup] LOG:  database system was shut down at 2024-04-01 10:19:47 UTC
2024-04-01 10:19:48.050 UTC [32478][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:48.151 UTC [32505][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=35974
2024-04-01 10:19:48.153 UTC [32505][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-01 10:19:48.165 UTC [32505][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-01 10:19:48.170 UTC [32505][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert internal error
2024-04-01 10:19:48.194 UTC [32516][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=35988
2024-04-01 10:19:48.204 UTC [32516][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-04-01 10:19:48.204 UTC [32516][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-04-01 10:19:48.225 UTC [32516][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-04-01 10:19:48.229 UTC [32516][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.035 user=ssltestuser database=trustdb host=localhost port=35988
2024-04-01 10:19:48.238 UTC [32478][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:48.238 UTC [32478][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:48.245 UTC [32478][postmaster] LOG:  background worker "logical replication launcher" (PID 32487) exited with exit code 1
2024-04-01 10:19:48.254 UTC [32482][checkpointer] LOG:  shutting down
2024-04-01 10:19:48.254 UTC [32482][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:48.255 UTC [32482][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/414CBC0, redo lsn=0/414CBC0
2024-04-01 10:19:48.261 UTC [32478][postmaster] LOG:  database system is shut down
2024-04-01 10:19:48.378 UTC [32556][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-04-01 10:19:48.378 UTC [32556][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:48.379 UTC [32556][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:48.406 UTC [32571][startup] LOG:  database system was shut down at 2024-04-01 10:19:48 UTC
2024-04-01 10:19:48.414 UTC [32556][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:48.467 UTC [32585][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=36000
2024-04-01 10:19:48.470 UTC [32585][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-01 10:19:48.470 UTC [32585][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-01 10:19:48.476 UTC [32585][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert internal error
2024-04-01 10:19:48.503 UTC [32588][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=36008
2024-04-01 10:19:48.512 UTC [32588][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-04-01 10:19:48.512 UTC [32588][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-04-01 10:19:48.527 UTC [32588][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-04-01 10:19:48.533 UTC [32588][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=ssltestuser database=trustdb host=localhost port=36008
2024-04-01 10:19:48.539 UTC [32556][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:48.540 UTC [32556][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:48.544 UTC [32556][postmaster] LOG:  background worker "logical replication launcher" (PID 32576) exited with exit code 1
2024-04-01 10:19:48.548 UTC [32569][checkpointer] LOG:  shutting down
2024-04-01 10:19:48.548 UTC [32569][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:48.549 UTC [32569][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/414CC38, redo lsn=0/414CC38
2024-04-01 10:19:48.554 UTC [32556][postmaster] LOG:  database system is shut down
2024-04-01 10:19:48.593 UTC [32597][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-04-01 10:19:48.593 UTC [32597][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:48.593 UTC [32597][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:48.614 UTC [32607][startup] LOG:  database system was shut down at 2024-04-01 10:19:48 UTC
2024-04-01 10:19:48.621 UTC [32597][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:48.698 UTC [32627][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=36014
2024-04-01 10:19:48.704 UTC [32627][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-01 10:19:48.704 UTC [32627][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-04-01 10:19:48.735 UTC [32633][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=36026
2024-04-01 10:19:48.749 UTC [32633][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-04-01 10:19:48.749 UTC [32633][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-04-01 10:19:48.764 UTC [32633][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-04-01 10:19:48.773 UTC [32633][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.039 user=ssltestuser database=trustdb host=localhost port=36026
2024-04-01 10:19:48.787 UTC [32597][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:48.787 UTC [32597][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:48.795 UTC [32597][postmaster] LOG:  background worker "logical replication launcher" (PID 32611) exited with exit code 1
2024-04-01 10:19:48.795 UTC [32605][checkpointer] LOG:  shutting down
2024-04-01 10:19:48.795 UTC [32605][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:48.795 UTC [32605][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/414CCB0, redo lsn=0/414CCB0
2024-04-01 10:19:48.801 UTC [32597][postmaster] LOG:  database system is shut down
2024-04-01 10:19:48.930 UTC [32655][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-04-01 10:19:48.930 UTC [32655][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:48.930 UTC [32655][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:48.936 UTC [32665][startup] LOG:  database system was shut down at 2024-04-01 10:19:48 UTC
2024-04-01 10:19:48.945 UTC [32655][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:49.030 UTC [32679][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=36030
2024-04-01 10:19:49.033 UTC [32679][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-01 10:19:49.035 UTC [32679][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-01 10:19:49.046 UTC [32679][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-04-01 10:19:49.078 UTC [32685][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=36038
2024-04-01 10:19:49.084 UTC [32685][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-04-01 10:19:49.084 UTC [32685][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-04-01 10:19:49.121 UTC [32685][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-04-01 10:19:49.127 UTC [32685][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.057 user=ssltestuser database=trustdb host=localhost port=36038
2024-04-01 10:19:49.135 UTC [32655][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:49.135 UTC [32655][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:49.153 UTC [32655][postmaster] LOG:  background worker "logical replication launcher" (PID 32670) exited with exit code 1
2024-04-01 10:19:49.153 UTC [32662][checkpointer] LOG:  shutting down
2024-04-01 10:19:49.153 UTC [32662][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:49.154 UTC [32662][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/414CD28, redo lsn=0/414CD28
2024-04-01 10:19:49.160 UTC [32655][postmaster] LOG:  database system is shut down
2024-04-01 10:19:49.287 UTC [32715][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-04-01 10:19:49.287 UTC [32715][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:49.287 UTC [32715][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:49.292 UTC [32728][startup] LOG:  database system was shut down at 2024-04-01 10:19:49 UTC
2024-04-01 10:19:49.301 UTC [32715][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:49.356 UTC [32742][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=36040
2024-04-01 10:19:49.359 UTC [32742][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-01 10:19:49.359 UTC [32742][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-01 10:19:49.364 UTC [32742][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-04-01 10:19:49.395 UTC [32748][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=36046
2024-04-01 10:19:49.404 UTC [32748][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-04-01 10:19:49.404 UTC [32748][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-04-01 10:19:49.421 UTC [32748][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-04-01 10:19:49.427 UTC [32748][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=ssltestuser database=trustdb host=localhost port=36046
2024-04-01 10:19:49.436 UTC [32715][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:49.436 UTC [32715][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:49.445 UTC [32715][postmaster] LOG:  background worker "logical replication launcher" (PID 32732) exited with exit code 1
2024-04-01 10:19:49.445 UTC [32726][checkpointer] LOG:  shutting down
2024-04-01 10:19:49.445 UTC [32726][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:49.446 UTC [32726][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/414CDA0, redo lsn=0/414CDA0
2024-04-01 10:19:49.451 UTC [32715][postmaster] LOG:  database system is shut down
2024-04-01 10:19:49.605 UTC [32773][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-04-01 10:19:49.605 UTC [32773][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:49.605 UTC [32773][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:49.642 UTC [32777][startup] LOG:  database system was shut down at 2024-04-01 10:19:49 UTC
2024-04-01 10:19:49.651 UTC [32773][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:49.670 UTC [32783][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=36054
2024-04-01 10:19:49.672 UTC [32783][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-01 10:19:49.673 UTC [32783][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-01 10:19:49.680 UTC [32783][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-04-01 10:19:49.705 UTC [32785][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=36062
2024-04-01 10:19:49.712 UTC [32785][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-04-01 10:19:49.712 UTC [32785][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-04-01 10:19:49.725 UTC [32785][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-04-01 10:19:49.730 UTC [32785][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.026 user=ssltestuser database=trustdb host=localhost port=36062
2024-04-01 10:19:49.743 UTC [32773][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:49.743 UTC [32773][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:49.748 UTC [32773][postmaster] LOG:  background worker "logical replication launcher" (PID 32780) exited with exit code 1
2024-04-01 10:19:49.752 UTC [32775][checkpointer] LOG:  shutting down
2024-04-01 10:19:49.752 UTC [32775][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:49.753 UTC [32775][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/414CE18, redo lsn=0/414CE18
2024-04-01 10:19:49.759 UTC [32773][postmaster] LOG:  database system is shut down
2024-04-01 10:19:49.893 UTC [32801][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-04-01 10:19:49.907 UTC [32801][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 63232
2024-04-01 10:19:49.907 UTC [32801][postmaster] LOG:  listening on Unix socket "/tmp/35INN_KzIs/.s.PGSQL.63232"
2024-04-01 10:19:49.926 UTC [32808][startup] LOG:  database system was shut down at 2024-04-01 10:19:49 UTC
2024-04-01 10:19:49.954 UTC [32801][postmaster] LOG:  database system is ready to accept connections
2024-04-01 10:19:50.070 UTC [32836][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=36074
2024-04-01 10:19:50.075 UTC [32836][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-04-01 10:19:50.076 UTC [32836][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: Resource temporarily unavailable
2024-04-01 10:19:50.137 UTC [32847][not initialized] [[unknown]][:0] LOG:  connection received: host=localhost port=36090
2024-04-01 10:19:50.151 UTC [32847][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-04-01 10:19:50.151 UTC [32847][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-04-01 10:19:50.177 UTC [32847][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-04-01 10:19:50.181 UTC [32847][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.045 user=ssltestuser database=trustdb host=localhost port=36090
2024-04-01 10:19:50.191 UTC [32801][postmaster] LOG:  received fast shutdown request
2024-04-01 10:19:50.191 UTC [32801][postmaster] LOG:  aborting any active transactions
2024-04-01 10:19:50.200 UTC [32801][postmaster] LOG:  background worker "logical replication launcher" (PID 32815) exited with exit code 1
2024-04-01 10:19:50.200 UTC [32805][checkpointer] LOG:  shutting down
2024-04-01 10:19:50.200 UTC [32805][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-04-01 10:19:50.201 UTC [32805][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/414CE90, redo lsn=0/414CE90
2024-04-01 10:19:50.208 UTC [32801][postmaster] LOG:  database system is shut down
2024-04-01 10:19:50.309 UTC [32859][postmaster] FATAL:  could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory
2024-04-01 10:19:50.309 UTC [32859][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[10:19:50.394](0.212s) Bail out!  pg_ctl restart failed
# No postmaster PID for node "primary"