[00:45:56.909](0.228s) # setting up data directory
# Checking port 52707
# Found port 52707
Name: primary
Data directory: C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata
Backup directory: C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/backup
Archive directory: C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/archives
Connection string: port=52707 host=C:/Windows/TEMP/iWwKbmkgMF
Log file: C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log
[00:45:56.974](0.065s) # initializing database system by copying initdb template
# Running: robocopy /E /NJS /NJH /NFL /NDL /NP C:/cirrus/build/tmp_install/initdb-template C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata

# Running: C:\cirrus\build\src/test\regress\pg_regress.exe --config-auth C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata
### Starting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log -o --cluster-name=primary start
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 6756
[00:45:58.984](2.011s) ok 1 - ssl_library parameter
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 8136
[00:46:03.538](4.554s) # testing password-protected keys
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... stopped waiting
pg_ctl: could not start server
Examine the log output.
# pg_ctl restart failed; logfile:
2024-03-06 00:45:58.396 GMT [6756][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:45:58.397 GMT [6756][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:45:58.531 GMT [7272][startup] LOG:  database system was shut down at 2024-03-06 00:36:20 GMT
2024-03-06 00:45:58.555 GMT [6756][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:45:58.905 GMT [6444][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:45:58.907 GMT [6444][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:45:58.907 GMT [6444][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:45:58.920 GMT [6444][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-06 00:45:58.927 GMT [6444][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=SYSTEM database=postgres host=[local]
2024-03-06 00:45:59.090 GMT [7388][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:45:59.091 GMT [7388][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:45:59.091 GMT [7388][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:45:59.094 GMT [7388][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-06 00:45:59.095 GMT [7388][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=SYSTEM database=postgres host=[local]
2024-03-06 00:45:59.256 GMT [5948][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:45:59.260 GMT [5948][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:45:59.260 GMT [5948][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:45:59.262 GMT [5948][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-06 00:45:59.264 GMT [5948][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=SYSTEM database=postgres host=[local]
2024-03-06 00:45:59.389 GMT [4672][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:45:59.390 GMT [4672][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:45:59.390 GMT [4672][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:45:59.393 GMT [4672][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-06 00:45:59.394 GMT [4672][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=SYSTEM database=postgres host=[local]
2024-03-06 00:45:59.517 GMT [4912][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:45:59.519 GMT [4912][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:45:59.519 GMT [4912][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:45:59.521 GMT [4912][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-06 00:45:59.566 GMT [4912][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.052 user=SYSTEM database=postgres host=[local]
2024-03-06 00:45:59.668 GMT [5956][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:45:59.669 GMT [5956][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:45:59.669 GMT [5956][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:45:59.703 GMT [5956][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-06 00:45:59.937 GMT [5956][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.272 user=SYSTEM database=postgres host=[local]
2024-03-06 00:46:00.124 GMT [4664][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:46:00.125 GMT [4664][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:46:00.125 GMT [4664][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:46:00.128 GMT [4664][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-06 00:46:00.411 GMT [4664][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.290 user=SYSTEM database=postgres host=[local]
2024-03-06 00:46:00.661 GMT [3444][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:46:00.663 GMT [3444][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:46:00.663 GMT [3444][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:46:00.665 GMT [3444][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-06 00:46:00.916 GMT [3444][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.257 user=SYSTEM database=postgres host=[local]
2024-03-06 00:46:01.076 GMT [3656][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:46:01.078 GMT [3656][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:46:01.078 GMT [3656][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:46:01.080 GMT [3656][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-06 00:46:01.416 GMT [3656][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.350 user=SYSTEM database=postgres host=[local]
2024-03-06 00:46:01.704 GMT [3408][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:46:01.705 GMT [3408][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:46:01.705 GMT [3408][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:46:01.727 GMT [3408][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-06 00:46:01.991 GMT [3408][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.290 user=SYSTEM database=postgres host=[local]
2024-03-06 00:46:02.119 GMT [7560][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:46:02.120 GMT [7560][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:46:02.120 GMT [7560][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:46:02.123 GMT [7560][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-06 00:46:02.432 GMT [7560][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.316 user=SYSTEM database=postgres host=[local]
2024-03-06 00:46:02.703 GMT [6756][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:02.703 GMT [6756][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:02.778 GMT [6756][postmaster] LOG:  background worker "logical replication launcher" (PID 4852) exited with exit code 1
2024-03-06 00:46:02.804 GMT [6460][checkpointer] LOG:  shutting down
2024-03-06 00:46:02.804 GMT [6460][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:03.030 GMT [6460][checkpointer] LOG:  checkpoint complete: wrote 5566 buffers (34.0%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.222 s, sync=0.001 s, total=0.227 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44804 kB, estimate=44804 kB; lsn=0/40C7D00, redo lsn=0/40C7D00
2024-03-06 00:46:03.156 GMT [6756][postmaster] LOG:  database system is shut down
2024-03-06 00:46:03.398 GMT [8136][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:03.400 GMT [8136][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:03.402 GMT [8136][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:03.444 GMT [2528][startup] LOG:  database system was shut down at 2024-03-06 00:46:03 GMT
2024-03-06 00:46:03.474 GMT [8136][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:03.579 GMT [8136][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:03.579 GMT [8136][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:03.602 GMT [8136][postmaster] LOG:  background worker "logical replication launcher" (PID 4168) exited with exit code 1
2024-03-06 00:46:03.605 GMT [4892][checkpointer] LOG:  shutting down
2024-03-06 00:46:03.606 GMT [4892][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:03.610 GMT [4892][checkpointer] LOG:  checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.005 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C7D78, redo lsn=0/40C7D78
2024-03-06 00:46:03.628 GMT [8136][postmaster] LOG:  database system is shut down
2024-03-06 00:46:03.795 GMT [5400][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-06 00:46:03.795 GMT [5400][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[00:46:03.895](0.357s) ok 2 - restart fails with password-protected key file with wrong password
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
pg_ctl: PID file "C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/postmaster.pid" does not exist
Is server running?
trying to start server anyway
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 6788
[00:46:04.165](0.270s) ok 3 - restart succeeds with password-protected key file
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... stopped waiting
pg_ctl: could not start server
Examine the log output.
# pg_ctl restart failed; logfile:
2024-03-06 00:45:58.396 GMT [6756][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:45:58.397 GMT [6756][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:45:58.531 GMT [7272][startup] LOG:  database system was shut down at 2024-03-06 00:36:20 GMT
2024-03-06 00:45:58.555 GMT [6756][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:45:58.905 GMT [6444][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:45:58.907 GMT [6444][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:45:58.907 GMT [6444][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:45:58.920 GMT [6444][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-06 00:45:58.927 GMT [6444][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=SYSTEM database=postgres host=[local]
2024-03-06 00:45:59.090 GMT [7388][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:45:59.091 GMT [7388][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:45:59.091 GMT [7388][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:45:59.094 GMT [7388][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-06 00:45:59.095 GMT [7388][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=SYSTEM database=postgres host=[local]
2024-03-06 00:45:59.256 GMT [5948][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:45:59.260 GMT [5948][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:45:59.260 GMT [5948][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:45:59.262 GMT [5948][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-06 00:45:59.264 GMT [5948][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=SYSTEM database=postgres host=[local]
2024-03-06 00:45:59.389 GMT [4672][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:45:59.390 GMT [4672][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:45:59.390 GMT [4672][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:45:59.393 GMT [4672][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-06 00:45:59.394 GMT [4672][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=SYSTEM database=postgres host=[local]
2024-03-06 00:45:59.517 GMT [4912][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:45:59.519 GMT [4912][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:45:59.519 GMT [4912][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:45:59.521 GMT [4912][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-06 00:45:59.566 GMT [4912][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.052 user=SYSTEM database=postgres host=[local]
2024-03-06 00:45:59.668 GMT [5956][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:45:59.669 GMT [5956][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:45:59.669 GMT [5956][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:45:59.703 GMT [5956][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-06 00:45:59.937 GMT [5956][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.272 user=SYSTEM database=postgres host=[local]
2024-03-06 00:46:00.124 GMT [4664][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:46:00.125 GMT [4664][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:46:00.125 GMT [4664][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:46:00.128 GMT [4664][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-06 00:46:00.411 GMT [4664][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.290 user=SYSTEM database=postgres host=[local]
2024-03-06 00:46:00.661 GMT [3444][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:46:00.663 GMT [3444][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:46:00.663 GMT [3444][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:46:00.665 GMT [3444][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-06 00:46:00.916 GMT [3444][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.257 user=SYSTEM database=postgres host=[local]
2024-03-06 00:46:01.076 GMT [3656][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:46:01.078 GMT [3656][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:46:01.078 GMT [3656][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:46:01.080 GMT [3656][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-06 00:46:01.416 GMT [3656][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.350 user=SYSTEM database=postgres host=[local]
2024-03-06 00:46:01.704 GMT [3408][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:46:01.705 GMT [3408][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:46:01.705 GMT [3408][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:46:01.727 GMT [3408][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-06 00:46:01.991 GMT [3408][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.290 user=SYSTEM database=postgres host=[local]
2024-03-06 00:46:02.119 GMT [7560][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:46:02.120 GMT [7560][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:46:02.120 GMT [7560][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:46:02.123 GMT [7560][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-06 00:46:02.432 GMT [7560][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.316 user=SYSTEM database=postgres host=[local]
2024-03-06 00:46:02.703 GMT [6756][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:02.703 GMT [6756][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:02.778 GMT [6756][postmaster] LOG:  background worker "logical replication launcher" (PID 4852) exited with exit code 1
2024-03-06 00:46:02.804 GMT [6460][checkpointer] LOG:  shutting down
2024-03-06 00:46:02.804 GMT [6460][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:03.030 GMT [6460][checkpointer] LOG:  checkpoint complete: wrote 5566 buffers (34.0%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.222 s, sync=0.001 s, total=0.227 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44804 kB, estimate=44804 kB; lsn=0/40C7D00, redo lsn=0/40C7D00
2024-03-06 00:46:03.156 GMT [6756][postmaster] LOG:  database system is shut down
2024-03-06 00:46:03.398 GMT [8136][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:03.400 GMT [8136][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:03.402 GMT [8136][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:03.444 GMT [2528][startup] LOG:  database system was shut down at 2024-03-06 00:46:03 GMT
2024-03-06 00:46:03.474 GMT [8136][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:03.579 GMT [8136][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:03.579 GMT [8136][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:03.602 GMT [8136][postmaster] LOG:  background worker "logical replication launcher" (PID 4168) exited with exit code 1
2024-03-06 00:46:03.605 GMT [4892][checkpointer] LOG:  shutting down
2024-03-06 00:46:03.606 GMT [4892][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:03.610 GMT [4892][checkpointer] LOG:  checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.005 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C7D78, redo lsn=0/40C7D78
2024-03-06 00:46:03.628 GMT [8136][postmaster] LOG:  database system is shut down
2024-03-06 00:46:03.795 GMT [5400][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-06 00:46:03.795 GMT [5400][postmaster] LOG:  database system is shut down
2024-03-06 00:46:04.018 GMT [6788][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:04.019 GMT [6788][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:04.020 GMT [6788][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:04.078 GMT [1136][startup] LOG:  database system was shut down at 2024-03-06 00:46:03 GMT
2024-03-06 00:46:04.108 GMT [6788][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:04.193 GMT [6788][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:04.193 GMT [6788][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:04.209 GMT [6788][postmaster] LOG:  background worker "logical replication launcher" (PID 2392) exited with exit code 1
2024-03-06 00:46:04.210 GMT [1668][checkpointer] LOG:  shutting down
2024-03-06 00:46:04.210 GMT [1668][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:04.213 GMT [1668][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C7DF0, redo lsn=0/40C7DF0
2024-03-06 00:46:04.233 GMT [6788][postmaster] LOG:  database system is shut down
2024-03-06 00:46:04.745 GMT [5228][postmaster] FATAL:  could not set SSL protocol version range
2024-03-06 00:46:04.745 GMT [5228][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-06 00:46:04.745 GMT [5228][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[00:46:04.846](0.681s) ok 4 - restart fails with incorrect SSL protocol bounds
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
pg_ctl: PID file "C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/postmaster.pid" does not exist
Is server running?
trying to start server anyway
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 4724
[00:46:05.541](0.695s) ok 5 - restart succeeds with correct SSL protocol bounds
[00:46:05.542](0.000s) # running client tests
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 5928
[00:46:06.182](0.640s) ok 6 - server doesn't accept non-SSL connections
[00:46:06.183](0.001s) ok 7 - server doesn't accept non-SSL connections: matches
[00:46:06.431](0.249s) ok 8 - connect without server root cert sslmode=require
[00:46:06.432](0.001s) ok 9 - connect without server root cert sslmode=require: no stderr
[00:46:06.619](0.188s) ok 10 - connect without server root cert sslmode=verify-ca
[00:46:06.620](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches
[00:46:06.745](0.126s) ok 12 - connect without server root cert sslmode=verify-full
[00:46:06.746](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches
[00:46:06.929](0.184s) ok 14 - connect with wrong server root cert sslmode=require
[00:46:06.930](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches
[00:46:07.062](0.132s) ok 16 - connect with wrong server root cert sslmode=verify-ca
[00:46:07.062](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches
[00:46:07.207](0.144s) ok 18 - connect with wrong server root cert sslmode=verify-full
[00:46:07.207](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches
[00:46:07.355](0.148s) ok 20 - connect with server CA cert, without root CA
[00:46:07.355](0.000s) ok 21 - connect with server CA cert, without root CA: matches
[00:46:07.454](0.099s) ok 22 - connect with correct server CA cert file sslmode=require
[00:46:07.456](0.001s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr
[00:46:07.544](0.088s) ok 24 - connect with correct server CA cert file sslmode=verify-ca
[00:46:07.544](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr
[00:46:07.762](0.218s) ok 26 - connect with correct server CA cert file sslmode=verify-full
[00:46:07.763](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr
[00:46:07.872](0.110s) ok 28 - cert root file that contains two certificates, order 1
[00:46:07.873](0.001s) ok 29 - cert root file that contains two certificates, order 1: no stderr
[00:46:08.034](0.161s) ok 30 - cert root file that contains two certificates, order 2
[00:46:08.035](0.001s) ok 31 - cert root file that contains two certificates, order 2: no stderr
[00:46:08.286](0.251s) ok 32 - connect with sslcertmode=disable
[00:46:08.286](0.000s) ok 33 - connect with sslcertmode=disable: no stderr
[00:46:08.484](0.198s) ok 34 - connect with sslcertmode=allow
[00:46:08.484](0.000s) ok 35 - connect with sslcertmode=allow: no stderr
[00:46:08.594](0.109s) ok 36 - connect with sslcertmode=require fails without a client certificate
[00:46:08.594](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches
[00:46:08.744](0.150s) ok 38 - sslcrl option with invalid file name
[00:46:08.744](0.000s) ok 39 - sslcrl option with invalid file name: no stderr
[00:46:08.873](0.129s) ok 40 - CRL belonging to a different CA
[00:46:08.873](0.000s) ok 41 - CRL belonging to a different CA: matches
[00:46:09.017](0.144s) ok 42 - directory CRL belonging to a different CA
[00:46:09.017](0.000s) ok 43 - directory CRL belonging to a different CA: matches
[00:46:09.233](0.216s) ok 44 - CRL with a non-revoked cert
[00:46:09.234](0.001s) ok 45 - CRL with a non-revoked cert: no stderr
[00:46:09.410](0.176s) ok 46 - directory CRL with a non-revoked cert
[00:46:09.410](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr
[00:46:09.581](0.171s) ok 48 - mismatch between host name and server certificate sslmode=require
[00:46:09.582](0.001s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr
[00:46:09.683](0.101s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca
[00:46:09.684](0.001s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr
[00:46:09.778](0.094s) ok 52 - mismatch between host name and server certificate sslmode=verify-full
[00:46:09.778](0.001s) ok 53 - mismatch between host name and server certificate sslmode=verify-full: matches
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 4824
[00:46:10.239](0.461s) ok 54 - IP address in the Common Name
[00:46:10.239](0.000s) ok 55 - IP address in the Common Name: no stderr
[00:46:10.425](0.186s) ok 56 - mismatch between host name and server certificate IP address
[00:46:10.426](0.001s) ok 57 - mismatch between host name and server certificate IP address: matches
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 3540
[00:46:10.880](0.454s) ok 58 - IP address in a dNSName
[00:46:10.880](0.000s) ok 59 - IP address in a dNSName: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 6888
[00:46:11.224](0.344s) ok 60 - host name matching with X.509 Subject Alternative Names 1
[00:46:11.224](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr
[00:46:11.334](0.110s) ok 62 - host name matching with X.509 Subject Alternative Names 2
[00:46:11.334](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr
[00:46:11.443](0.109s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard
[00:46:11.444](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr
[00:46:11.506](0.063s) ok 66 - host name not matching with X.509 Subject Alternative Names
[00:46:11.507](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches
[00:46:11.568](0.061s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard
[00:46:11.569](0.000s) ok 69 - host name not matching with X.509 Subject Alternative Names wildcard: matches
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 7692
[00:46:11.912](0.344s) ok 70 - host name matching with a single X.509 Subject Alternative Name
[00:46:11.912](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr
[00:46:11.974](0.062s) ok 72 - host name not matching with a single X.509 Subject Alternative Name
[00:46:11.975](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches
[00:46:12.037](0.062s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard
[00:46:12.038](0.000s) ok 75 - host name not matching with a single X.509 Subject Alternative Name wildcard: matches
[00:46:12.067](0.029s) ok 76 # skip IPv6 addresses in certificates not support on this platform
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 1520
[00:46:12.412](0.345s) ok 77 - certificate with both a CN and SANs 1
[00:46:12.412](0.000s) ok 78 - certificate with both a CN and SANs 1: no stderr
[00:46:12.475](0.063s) ok 79 - certificate with both a CN and SANs 2
[00:46:12.475](0.000s) ok 80 - certificate with both a CN and SANs 2: no stderr
[00:46:12.537](0.062s) ok 81 - certificate with both a CN and SANs ignores CN
[00:46:12.538](0.000s) ok 82 - certificate with both a CN and SANs ignores CN: matches
[00:46:12.568](0.031s) ok 83 # skip IPv6 addresses in certificates not support on this platform
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 5344
[00:46:12.913](0.345s) ok 84 - certificate with both an IP CN and DNS SANs matches CN
[00:46:12.913](0.000s) ok 85 - certificate with both an IP CN and DNS SANs matches CN: no stderr
[00:46:13.038](0.125s) ok 86 - certificate with both an IP CN and DNS SANs matches SAN 1
[00:46:13.038](0.000s) ok 87 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr
[00:46:13.101](0.062s) ok 88 - certificate with both an IP CN and DNS SANs matches SAN 2
[00:46:13.101](0.000s) ok 89 - certificate with both an IP CN and DNS SANs matches SAN 2: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 5084
[00:46:13.444](0.343s) ok 90 - server certificate without CN or SANs sslmode=verify-ca
[00:46:13.444](0.000s) ok 91 - server certificate without CN or SANs sslmode=verify-ca: no stderr
[00:46:13.507](0.062s) ok 92 - server certificate without CN or SANs sslmode=verify-full
[00:46:13.507](0.000s) ok 93 - server certificate without CN or SANs sslmode=verify-full: matches
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 4420
[00:46:13.804](0.297s) ok 94 - sslrootcert=system does not connect with private CA
[00:46:13.804](0.000s) ok 95 - sslrootcert=system does not connect with private CA: matches
[00:46:13.837](0.033s) ok 96 - sslrootcert=system only accepts sslmode=verify-full
[00:46:13.837](0.000s) ok 97 - sslrootcert=system only accepts sslmode=verify-full: matches
[00:46:13.945](0.108s) ok 98 - sslrootcert=system connects with overridden SSL_CERT_FILE
[00:46:13.945](0.000s) ok 99 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr
[00:46:14.009](0.063s) ok 100 - sslrootcert=system defaults to sslmode=verify-full
[00:46:14.009](0.000s) ok 101 - sslrootcert=system defaults to sslmode=verify-full: matches
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 916
[00:46:14.353](0.345s) ok 102 - connects without client-side CRL
[00:46:14.354](0.000s) ok 103 - connects without client-side CRL: no stderr
[00:46:14.462](0.108s) ok 104 - does not connect with client-side CRL file
[00:46:14.462](0.000s) ok 105 - does not connect with client-side CRL file: matches
[00:46:14.529](0.066s) ok 106 - does not connect with client-side CRL directory
[00:46:14.529](0.000s) ok 107 - 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()
[00:46:14.589](0.060s) ok 108 - pg_stat_ssl view without client certificate: exit code 0
[00:46:14.589](0.000s) ok 109 - pg_stat_ssl view without client certificate: no stderr
[00:46:14.590](0.000s) ok 110 - pg_stat_ssl view without client certificate: matches
[00:46:14.653](0.064s) ok 111 - connection success with correct range of TLS protocol versions
[00:46:14.653](0.000s) ok 112 - connection success with correct range of TLS protocol versions: no stderr
[00:46:14.716](0.063s) ok 113 - connection failure with incorrect range of TLS protocol versions
[00:46:14.717](0.000s) ok 114 - connection failure with incorrect range of TLS protocol versions: matches
[00:46:14.749](0.032s) ok 115 - connection failure with an incorrect SSL protocol minimum bound
[00:46:14.749](0.000s) ok 116 - connection failure with an incorrect SSL protocol minimum bound: matches
[00:46:14.810](0.061s) ok 117 - connection failure with an incorrect SSL protocol maximum bound
[00:46:14.810](0.000s) ok 118 - connection failure with an incorrect SSL protocol maximum bound: matches
[00:46:14.810](0.000s) # running server tests
[00:46:14.919](0.108s) ok 119 - certificate authorization fails without client cert
[00:46:14.919](0.000s) ok 120 - certificate authorization fails without client cert: matches
[00:46:15.028](0.109s) ok 121 - certificate authorization succeeds with correct client cert in PEM format
[00:46:15.029](0.000s) ok 122 - certificate authorization succeeds with correct client cert in PEM format: no stderr
[00:46:15.138](0.110s) ok 123 - certificate authorization succeeds with correct client cert in DER format
[00:46:15.139](0.001s) ok 124 - certificate authorization succeeds with correct client cert in DER format: no stderr
[00:46:15.247](0.107s) ok 125 - certificate authorization succeeds with correct client cert in encrypted PEM format
[00:46:15.247](0.000s) ok 126 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr
[00:46:15.356](0.110s) ok 127 - certificate authorization succeeds with correct client cert in encrypted DER format
[00:46:15.357](0.000s) ok 128 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr
[00:46:15.466](0.109s) ok 129 - certificate authorization succeeds with correct client cert and sslcertmode=require
[00:46:15.466](0.000s) ok 130 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr
[00:46:15.575](0.109s) ok 131 - certificate authorization succeeds with correct client cert and sslcertmode=allow
[00:46:15.576](0.001s) ok 132 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr
[00:46:15.951](0.375s) ok 133 - certificate authorization fails with correct client cert and sslcertmode=disable
[00:46:15.952](0.001s) ok 134 - certificate authorization fails with correct client cert and sslcertmode=disable: matches
[00:46:16.059](0.108s) ok 135 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format
[00:46:16.060](0.000s) ok 136 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches
[00:46:16.168](0.108s) ok 137 - certificate authorization succeeds with DN mapping
[00:46:16.168](0.000s) ok 138 - certificate authorization succeeds with DN mapping: no stderr
[00:46:16.169](0.001s) ok 139 - certificate authorization succeeds with DN mapping: log matches
[00:46:16.294](0.125s) ok 140 - certificate authorization succeeds with DN regex mapping
[00:46:16.294](0.001s) ok 141 - certificate authorization succeeds with DN regex mapping: no stderr
[00:46:16.403](0.108s) ok 142 - certificate authorization succeeds with CN mapping
[00:46:16.403](0.000s) ok 143 - certificate authorization succeeds with CN mapping: no stderr
[00:46:16.403](0.001s) ok 144 - certificate authorization succeeds with CN mapping: log matches
[00:46:16.404](0.000s) not ok 145 # TODO & SKIP Need Pty support
[00:46:16.404](0.000s) not ok 146 # TODO & SKIP Need Pty support
[00:46:16.404](0.000s) not ok 147 # TODO & SKIP Need Pty support
[00:46:16.404](0.000s) not ok 148 # TODO & SKIP Need Pty support
'C:\Program' is not recognized as an internal or external command,
operable program or batch file.
couldn't run "C:\Program Files\Git\usr\bin\openssl.EXE x509" to get client cert serialno at C:/cirrus/src/test/ssl/t/001_ssltests.pl line 726.
# 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=C:/cirrus/build/testrun/ssl/001_ssltests/data/tmp_test_jAgD/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
[00:46:16.510](0.106s) ok 149 - pg_stat_ssl with client certificate: exit code 0
[00:46:16.511](0.001s) ok 150 - pg_stat_ssl with client certificate: no stderr
[00:46:16.511](0.000s) ok 151 - pg_stat_ssl with client certificate: matches
[00:46:16.511](0.000s) ok 152 # skip Permissions check not enforced on Windows
[00:46:16.512](0.000s) ok 153 # skip Permissions check not enforced on Windows
[00:46:16.623](0.111s) ok 154 - certificate authorization fails with client cert belonging to another user
[00:46:16.623](0.000s) ok 155 - certificate authorization fails with client cert belonging to another user: matches
[00:46:16.732](0.109s) ok 156 - certificate authorization fails with revoked client cert
[00:46:16.733](0.000s) ok 157 - certificate authorization fails with revoked client cert: matches
[00:46:16.733](0.001s) ok 158 - certificate authorization fails with revoked client cert: log does not match
[00:46:16.841](0.108s) ok 159 - auth_option clientcert=verify-full succeeds with matching username and Common Name
[00:46:16.842](0.000s) ok 160 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr
[00:46:16.842](0.001s) ok 161 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches
[00:46:16.951](0.109s) ok 162 - auth_option clientcert=verify-full fails with mismatching username and Common Name
[00:46:16.951](0.000s) ok 163 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches
[00:46:16.952](0.001s) ok 164 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match
[00:46:17.060](0.108s) ok 165 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name
[00:46:17.060](0.000s) ok 166 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr
[00:46:17.061](0.000s) ok 167 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: log matches
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 4720
[00:46:17.404](0.344s) ok 168 - intermediate client certificate is provided by client
[00:46:17.404](0.000s) ok 169 - intermediate client certificate is provided by client: no stderr
[00:46:17.467](0.062s) ok 170 - intermediate client certificate is missing
[00:46:17.467](0.000s) ok 171 - intermediate client certificate is missing: matches
[00:46:17.577](0.110s) ok 172 - logged client certificate Subjects are truncated if they're too long
[00:46:17.577](0.000s) ok 173 - logged client certificate Subjects are truncated if they're too long: matches
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 7180
[00:46:17.936](0.359s) ok 174 - intermediate client certificate is untrusted
[00:46:17.937](0.000s) ok 175 - intermediate client certificate is untrusted: matches
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 7536
[00:46:18.280](0.344s) ok 176 - certificate authorization fails with revoked client cert with server-side CRL directory
[00:46:18.281](0.000s) ok 177 - certificate authorization fails with revoked client cert with server-side CRL directory: matches
[00:46:18.342](0.062s) ok 178 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory
[00:46:18.343](0.000s) ok 179 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory: matches
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 7720
[00:46:18.687](0.345s) not ok 180 - connect with valid stapled ocsp response when sslocspstapling=1
[00:46:18.688](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1'
#   at C:/cirrus/src/test/ssl/t/001_ssltests.pl line 923.
[00:46:18.688](0.000s) #          got: '2'
#     expected: '0'
[00:46:18.688](0.000s) not ok 181 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[00:46:18.689](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr'
#   at C:/cirrus/src/test/ssl/t/001_ssltests.pl line 923.
[00:46:18.689](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 52707 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 (0x00002746/10054)'
#     expected: ''
[00:46:18.798](0.109s) ok 182 - connect without requesting ocsp response when sslocspstapling=0
[00:46:18.798](0.000s) ok 183 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 4236
[00:46:19.095](0.297s) ok 184 - failed with a revoked ocsp response when sslocspstapling=1
[00:46:19.205](0.110s) ok 185 - connect without requesting ocsp response when sslocspstapling=0
[00:46:19.205](0.000s) ok 186 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 7764
[00:46:19.501](0.296s) ok 187 - failed with a revoked ocsp response when sslocspstapling=1
[00:46:19.611](0.110s) ok 188 - connect without requesting ocsp response when sslocspstapling=0
[00:46:19.612](0.000s) ok 189 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 5184
[00:46:19.971](0.359s) ok 190 - failed with an expired ocsp response when sslocspstapling=1
[00:46:20.080](0.109s) ok 191 - connect without requesting ocsp response when sslocspstapling=0
[00:46:20.080](0.000s) ok 192 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 7304
[00:46:20.377](0.297s) not ok 193 - connect with valid stapled ocsp response when sslocspstapling=1
[00:46:20.378](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1'
#   at C:/cirrus/src/test/ssl/t/001_ssltests.pl line 1001.
[00:46:20.378](0.000s) #          got: '2'
#     expected: '0'
[00:46:20.378](0.000s) not ok 194 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr
[00:46:20.378](0.000s) #   Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr'
#   at C:/cirrus/src/test/ssl/t/001_ssltests.pl line 1001.
[00:46:20.378](0.000s) #          got: 'psql: error: connection to server at "127.0.0.1", port 52707 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 (0x00002746/10054)'
#     expected: ''
[00:46:20.487](0.109s) ok 195 - connect without requesting ocsp response when sslocspstapling=0
[00:46:20.487](0.000s) ok 196 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 6804
[00:46:20.783](0.296s) ok 197 - failed with a revoked ocsp response when sslocspstapling=1
[00:46:20.893](0.110s) ok 198 - connect without requesting ocsp response when sslocspstapling=0
[00:46:20.894](0.001s) ok 199 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 3164
[00:46:21.190](0.297s) ok 200 - failed with a revoked ocsp response when sslocspstapling=1
[00:46:21.300](0.110s) ok 201 - connect without requesting ocsp response when sslocspstapling=0
[00:46:21.300](0.001s) ok 202 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 6244
[00:46:21.596](0.296s) ok 203 - failed with an expired ocsp response when sslocspstapling=1
[00:46:21.706](0.109s) ok 204 - connect without requesting ocsp response when sslocspstapling=0
[00:46:21.706](0.000s) ok 205 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 7220
[00:46:22.002](0.296s) ok 206 - failed with an expired ocsp response when sslocspstapling=1
[00:46:22.111](0.109s) ok 207 - connect without requesting ocsp response when sslocspstapling=0
[00:46:22.112](0.000s) ok 208 - connect without requesting ocsp response when sslocspstapling=0: no stderr
### Restarting node "primary"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/ssl/001_ssltests\data/t_001_ssltests_primary_data/pgdata -l C:\cirrus\build/testrun/ssl/001_ssltests\log/001_ssltests_primary.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... stopped waiting
pg_ctl: could not start server
Examine the log output.
# pg_ctl restart failed; logfile:
2024-03-06 00:45:58.396 GMT [6756][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:45:58.397 GMT [6756][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:45:58.531 GMT [7272][startup] LOG:  database system was shut down at 2024-03-06 00:36:20 GMT
2024-03-06 00:45:58.555 GMT [6756][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:45:58.905 GMT [6444][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:45:58.907 GMT [6444][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:45:58.907 GMT [6444][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:45:58.920 GMT [6444][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SHOW ssl_library
2024-03-06 00:45:58.927 GMT [6444][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=SYSTEM database=postgres host=[local]
2024-03-06 00:45:59.090 GMT [7388][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:45:59.091 GMT [7388][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:45:59.091 GMT [7388][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:45:59.094 GMT [7388][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-06 00:45:59.095 GMT [7388][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.009 user=SYSTEM database=postgres host=[local]
2024-03-06 00:45:59.256 GMT [5948][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:45:59.260 GMT [5948][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:45:59.260 GMT [5948][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:45:59.262 GMT [5948][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-06 00:45:59.264 GMT [5948][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.013 user=SYSTEM database=postgres host=[local]
2024-03-06 00:45:59.389 GMT [4672][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:45:59.390 GMT [4672][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:45:59.390 GMT [4672][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:45:59.393 GMT [4672][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-06 00:45:59.394 GMT [4672][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.008 user=SYSTEM database=postgres host=[local]
2024-03-06 00:45:59.517 GMT [4912][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:45:59.519 GMT [4912][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:45:59.519 GMT [4912][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:45:59.521 GMT [4912][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-06 00:45:59.566 GMT [4912][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.052 user=SYSTEM database=postgres host=[local]
2024-03-06 00:45:59.668 GMT [5956][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:45:59.669 GMT [5956][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:45:59.669 GMT [5956][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:45:59.703 GMT [5956][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-06 00:45:59.937 GMT [5956][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.272 user=SYSTEM database=postgres host=[local]
2024-03-06 00:46:00.124 GMT [4664][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:46:00.125 GMT [4664][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:46:00.125 GMT [4664][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:46:00.128 GMT [4664][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-06 00:46:00.411 GMT [4664][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.290 user=SYSTEM database=postgres host=[local]
2024-03-06 00:46:00.661 GMT [3444][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:46:00.663 GMT [3444][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:46:00.663 GMT [3444][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:46:00.665 GMT [3444][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-06 00:46:00.916 GMT [3444][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.257 user=SYSTEM database=postgres host=[local]
2024-03-06 00:46:01.076 GMT [3656][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:46:01.078 GMT [3656][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:46:01.078 GMT [3656][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:46:01.080 GMT [3656][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-06 00:46:01.416 GMT [3656][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.350 user=SYSTEM database=postgres host=[local]
2024-03-06 00:46:01.704 GMT [3408][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:46:01.705 GMT [3408][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:46:01.705 GMT [3408][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:46:01.727 GMT [3408][client backend] [001_ssltests.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-06 00:46:01.991 GMT [3408][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.290 user=SYSTEM database=postgres host=[local]
2024-03-06 00:46:02.119 GMT [7560][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-06 00:46:02.120 GMT [7560][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="SYSTEM" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117)
2024-03-06 00:46:02.120 GMT [7560][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=SYSTEM database=postgres application_name=001_ssltests.pl
2024-03-06 00:46:02.123 GMT [7560][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-06 00:46:02.432 GMT [7560][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.316 user=SYSTEM database=postgres host=[local]
2024-03-06 00:46:02.703 GMT [6756][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:02.703 GMT [6756][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:02.778 GMT [6756][postmaster] LOG:  background worker "logical replication launcher" (PID 4852) exited with exit code 1
2024-03-06 00:46:02.804 GMT [6460][checkpointer] LOG:  shutting down
2024-03-06 00:46:02.804 GMT [6460][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:03.030 GMT [6460][checkpointer] LOG:  checkpoint complete: wrote 5566 buffers (34.0%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.222 s, sync=0.001 s, total=0.227 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44804 kB, estimate=44804 kB; lsn=0/40C7D00, redo lsn=0/40C7D00
2024-03-06 00:46:03.156 GMT [6756][postmaster] LOG:  database system is shut down
2024-03-06 00:46:03.398 GMT [8136][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:03.400 GMT [8136][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:03.402 GMT [8136][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:03.444 GMT [2528][startup] LOG:  database system was shut down at 2024-03-06 00:46:03 GMT
2024-03-06 00:46:03.474 GMT [8136][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:03.579 GMT [8136][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:03.579 GMT [8136][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:03.602 GMT [8136][postmaster] LOG:  background worker "logical replication launcher" (PID 4168) exited with exit code 1
2024-03-06 00:46:03.605 GMT [4892][checkpointer] LOG:  shutting down
2024-03-06 00:46:03.606 GMT [4892][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:03.610 GMT [4892][checkpointer] LOG:  checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.005 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C7D78, redo lsn=0/40C7D78
2024-03-06 00:46:03.628 GMT [8136][postmaster] LOG:  database system is shut down
2024-03-06 00:46:03.795 GMT [5400][postmaster] FATAL:  could not load private key file "server-password.key": bad decrypt
2024-03-06 00:46:03.795 GMT [5400][postmaster] LOG:  database system is shut down
2024-03-06 00:46:04.018 GMT [6788][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:04.019 GMT [6788][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:04.020 GMT [6788][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:04.078 GMT [1136][startup] LOG:  database system was shut down at 2024-03-06 00:46:03 GMT
2024-03-06 00:46:04.108 GMT [6788][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:04.193 GMT [6788][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:04.193 GMT [6788][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:04.209 GMT [6788][postmaster] LOG:  background worker "logical replication launcher" (PID 2392) exited with exit code 1
2024-03-06 00:46:04.210 GMT [1668][checkpointer] LOG:  shutting down
2024-03-06 00:46:04.210 GMT [1668][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:04.213 GMT [1668][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C7DF0, redo lsn=0/40C7DF0
2024-03-06 00:46:04.233 GMT [6788][postmaster] LOG:  database system is shut down
2024-03-06 00:46:04.745 GMT [5228][postmaster] FATAL:  could not set SSL protocol version range
2024-03-06 00:46:04.745 GMT [5228][postmaster] DETAIL:  ssl_min_protocol_version cannot be higher than ssl_max_protocol_version
2024-03-06 00:46:04.745 GMT [5228][postmaster] LOG:  database system is shut down
2024-03-06 00:46:04.984 GMT [4724][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:04.984 GMT [4724][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:04.986 GMT [4724][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:05.033 GMT [5792][startup] LOG:  database system was shut down at 2024-03-06 00:46:04 GMT
2024-03-06 00:46:05.077 GMT [4724][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:05.576 GMT [4724][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:05.577 GMT [4724][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:05.595 GMT [4724][postmaster] LOG:  background worker "logical replication launcher" (PID 3632) exited with exit code 1
2024-03-06 00:46:05.614 GMT [1272][checkpointer] LOG:  shutting down
2024-03-06 00:46:05.614 GMT [1272][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:05.617 GMT [1272][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C7E68, redo lsn=0/40C7E68
2024-03-06 00:46:05.623 GMT [4724][postmaster] LOG:  database system is shut down
2024-03-06 00:46:05.837 GMT [5928][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:05.838 GMT [5928][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:05.839 GMT [5928][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:05.874 GMT [7756][startup] LOG:  database system was shut down at 2024-03-06 00:46:05 GMT
2024-03-06 00:46:05.894 GMT [5928][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:06.109 GMT [5608][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50321
2024-03-06 00:46:06.110 GMT [5608][client backend] [[unknown]][0/1:0] FATAL:  no pg_hba.conf entry for host "127.0.0.1", user "ssltestuser", database "trustdb", no encryption
2024-03-06 00:46:06.110 GMT [5608][client backend] [[unknown]][0/1:0] DETAIL:  Client IP address resolved to "pg-loadbalancetest", forward lookup not checked.
2024-03-06 00:46:06.258 GMT [3348][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50324
2024-03-06 00:46:06.266 GMT [3348][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:06.266 GMT [3348][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:06.329 GMT [3348][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid sslmode=require$$
2024-03-06 00:46:06.333 GMT [3348][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.082 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50324
2024-03-06 00:46:06.574 GMT [5476][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50327
2024-03-06 00:46:06.583 GMT [5476][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-06 00:46:06.710 GMT [7260][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50331
2024-03-06 00:46:06.720 GMT [7260][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-06 00:46:06.856 GMT [6176][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50333
2024-03-06 00:46:06.862 GMT [6176][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-06 00:46:06.989 GMT [4208][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50335
2024-03-06 00:46:06.995 GMT [4208][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-06 00:46:07.143 GMT [7796][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50338
2024-03-06 00:46:07.148 GMT [7796][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-06 00:46:07.296 GMT [7688][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50341
2024-03-06 00:46:07.302 GMT [7688][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-06 00:46:07.423 GMT [8028][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50343
2024-03-06 00:46:07.430 GMT [8028][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:07.430 GMT [8028][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:07.433 GMT [8028][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require$$
2024-03-06 00:46:07.436 GMT [8028][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50343
2024-03-06 00:46:07.523 GMT [2372][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50345
2024-03-06 00:46:07.530 GMT [2372][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:07.530 GMT [2372][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:07.533 GMT [2372][client backend] [001_ssltests.pl][3/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$
2024-03-06 00:46:07.535 GMT [2372][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50345
2024-03-06 00:46:07.614 GMT [5168][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50346
2024-03-06 00:46:07.621 GMT [5168][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:07.621 GMT [5168][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:07.624 GMT [5168][client backend] [001_ssltests.pl][4/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-full$$
2024-03-06 00:46:07.689 GMT [5168][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.082 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50346
2024-03-06 00:46:07.812 GMT [5408][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50348
2024-03-06 00:46:07.820 GMT [5408][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:07.820 GMT [5408][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:07.823 GMT [5408][client backend] [001_ssltests.pl][5/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-1.crt sslmode=verify-ca$$
2024-03-06 00:46:07.825 GMT [5408][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50348
2024-03-06 00:46:07.977 GMT [4724][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50351
2024-03-06 00:46:07.984 GMT [4724][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:07.984 GMT [4724][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:07.987 GMT [4724][client backend] [001_ssltests.pl][6/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-2.crt sslmode=verify-ca$$
2024-03-06 00:46:07.989 GMT [4724][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50351
2024-03-06 00:46:08.120 GMT [7860][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50353
2024-03-06 00:46:08.127 GMT [7860][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:08.127 GMT [7860][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:08.149 GMT [7860][client backend] [001_ssltests.pl][7/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=disable$$
2024-03-06 00:46:08.153 GMT [7860][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.039 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50353
2024-03-06 00:46:08.404 GMT [7820][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50356
2024-03-06 00:46:08.412 GMT [7820][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:08.412 GMT [7820][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:08.415 GMT [7820][client backend] [001_ssltests.pl][8/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=allow$$
2024-03-06 00:46:08.418 GMT [7820][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.038 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50356
2024-03-06 00:46:08.542 GMT [1240][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50359
2024-03-06 00:46:08.549 GMT [1240][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:08.549 GMT [1240][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:08.565 GMT [1240][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50359
2024-03-06 00:46:08.670 GMT [1688][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50361
2024-03-06 00:46:08.678 GMT [1688][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:08.678 GMT [1688][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:08.683 GMT [1688][client backend] [001_ssltests.pl][10/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=invalid$$
2024-03-06 00:46:08.687 GMT [1688][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50361
2024-03-06 00:46:08.800 GMT [1692][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50363
2024-03-06 00:46:08.805 GMT [1692][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-06 00:46:08.924 GMT [4056][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50364
2024-03-06 00:46:08.929 GMT [4056][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-06 00:46:09.128 GMT [2832][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50365
2024-03-06 00:46:09.136 GMT [2832][client backend] [[unknown]][11/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:09.136 GMT [2832][client backend] [[unknown]][11/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:09.139 GMT [2832][client backend] [001_ssltests.pl][11/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=ssl/root+server.crl$$
2024-03-06 00:46:09.144 GMT [2832][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50365
2024-03-06 00:46:09.355 GMT [7940][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50366
2024-03-06 00:46:09.362 GMT [7940][client backend] [[unknown]][12/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:09.362 GMT [7940][client backend] [[unknown]][12/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:09.365 GMT [7940][client backend] [001_ssltests.pl][12/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrldir=ssl/root+server-crldir$$
2024-03-06 00:46:09.369 GMT [7940][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50366
2024-03-06 00:46:09.509 GMT [5876][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50367
2024-03-06 00:46:09.516 GMT [5876][client backend] [[unknown]][13/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:09.517 GMT [5876][client backend] [[unknown]][13/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:09.526 GMT [5876][client backend] [001_ssltests.pl][13/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=require host=wronghost.test$$
2024-03-06 00:46:09.532 GMT [5876][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50367
2024-03-06 00:46:09.647 GMT [7448][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50369
2024-03-06 00:46:09.655 GMT [7448][client backend] [[unknown]][14/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:09.655 GMT [7448][client backend] [[unknown]][14/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:09.658 GMT [7448][client backend] [001_ssltests.pl][14/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=wronghost.test$$
2024-03-06 00:46:09.664 GMT [7448][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50369
2024-03-06 00:46:09.747 GMT [7704][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50370
2024-03-06 00:46:09.807 GMT [5928][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:09.807 GMT [5928][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:09.817 GMT [5928][postmaster] LOG:  background worker "logical replication launcher" (PID 196) exited with exit code 1
2024-03-06 00:46:09.817 GMT [1496][checkpointer] LOG:  shutting down
2024-03-06 00:46:09.818 GMT [1496][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:09.821 GMT [1496][checkpointer] LOG:  checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C7EE0, redo lsn=0/40C7EE0
2024-03-06 00:46:09.827 GMT [5928][postmaster] LOG:  database system is shut down
2024-03-06 00:46:09.988 GMT [4824][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:09.988 GMT [4824][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:09.989 GMT [4824][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:10.018 GMT [6468][startup] LOG:  database system was shut down at 2024-03-06 00:46:09 GMT
2024-03-06 00:46:10.036 GMT [4824][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:10.182 GMT [7280][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50371
2024-03-06 00:46:10.190 GMT [7280][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:10.190 GMT [7280][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:10.205 GMT [7280][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-06 00:46:10.209 GMT [7280][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.034 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50371
2024-03-06 00:46:10.353 GMT [4788][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50372
2024-03-06 00:46:10.448 GMT [4824][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:10.448 GMT [4824][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:10.455 GMT [4824][postmaster] LOG:  background worker "logical replication launcher" (PID 1052) exited with exit code 1
2024-03-06 00:46:10.456 GMT [5652][checkpointer] LOG:  shutting down
2024-03-06 00:46:10.457 GMT [5652][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:10.460 GMT [5652][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C7F58, redo lsn=0/40C7F58
2024-03-06 00:46:10.466 GMT [4824][postmaster] LOG:  database system is shut down
2024-03-06 00:46:10.613 GMT [3540][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:10.614 GMT [3540][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:10.617 GMT [3540][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:10.663 GMT [3068][startup] LOG:  database system was shut down at 2024-03-06 00:46:10 GMT
2024-03-06 00:46:10.672 GMT [3540][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:10.817 GMT [5888][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50373
2024-03-06 00:46:10.825 GMT [5888][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:10.825 GMT [5888][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:10.839 GMT [5888][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-06 00:46:10.844 GMT [5888][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.034 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50373
2024-03-06 00:46:10.896 GMT [3540][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:10.897 GMT [3540][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:10.903 GMT [3540][postmaster] LOG:  background worker "logical replication launcher" (PID 7640) exited with exit code 1
2024-03-06 00:46:10.904 GMT [6680][checkpointer] LOG:  shutting down
2024-03-06 00:46:10.904 GMT [6680][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:10.906 GMT [6680][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.003 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C7FD0, redo lsn=0/40C7FD0
2024-03-06 00:46:10.912 GMT [3540][postmaster] LOG:  database system is shut down
2024-03-06 00:46:11.060 GMT [6888][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:11.061 GMT [6888][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:11.062 GMT [6888][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:11.089 GMT [6000][startup] LOG:  database system was shut down at 2024-03-06 00:46:10 GMT
2024-03-06 00:46:11.099 GMT [6888][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:11.166 GMT [416][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50374
2024-03-06 00:46:11.174 GMT [416][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:11.174 GMT [416][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:11.187 GMT [416][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$
2024-03-06 00:46:11.192 GMT [416][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.034 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50374
2024-03-06 00:46:11.274 GMT [7536][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50375
2024-03-06 00:46:11.282 GMT [7536][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:11.282 GMT [7536][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:11.285 GMT [7536][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$
2024-03-06 00:46:11.289 GMT [7536][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50375
2024-03-06 00:46:11.380 GMT [2692][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50376
2024-03-06 00:46:11.388 GMT [2692][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:11.389 GMT [2692][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:11.392 GMT [2692][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=foo.wildcard.pg-ssltest.test$$
2024-03-06 00:46:11.396 GMT [2692][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50376
2024-03-06 00:46:11.487 GMT [3960][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50377
2024-03-06 00:46:11.551 GMT [5424][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50378
2024-03-06 00:46:11.585 GMT [6888][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:11.585 GMT [6888][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:11.589 GMT [6888][postmaster] LOG:  background worker "logical replication launcher" (PID 3732) exited with exit code 1
2024-03-06 00:46:11.590 GMT [6972][checkpointer] LOG:  shutting down
2024-03-06 00:46:11.590 GMT [6972][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:11.593 GMT [6972][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.003 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C8060, redo lsn=0/40C8060
2024-03-06 00:46:11.598 GMT [6888][postmaster] LOG:  database system is shut down
2024-03-06 00:46:11.748 GMT [7692][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:11.749 GMT [7692][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:11.750 GMT [7692][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:11.777 GMT [5192][startup] LOG:  database system was shut down at 2024-03-06 00:46:11 GMT
2024-03-06 00:46:11.784 GMT [7692][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:11.850 GMT [7728][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50379
2024-03-06 00:46:11.858 GMT [7728][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:11.858 GMT [7728][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:11.872 GMT [7728][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=single.alt-name.pg-ssltest.test$$
2024-03-06 00:46:11.874 GMT [7728][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50379
2024-03-06 00:46:11.955 GMT [1420][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50380
2024-03-06 00:46:12.016 GMT [6736][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50381
2024-03-06 00:46:12.084 GMT [7692][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:12.084 GMT [7692][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:12.087 GMT [7692][postmaster] LOG:  background worker "logical replication launcher" (PID 2748) exited with exit code 1
2024-03-06 00:46:12.088 GMT [6528][checkpointer] LOG:  shutting down
2024-03-06 00:46:12.088 GMT [6528][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:12.091 GMT [6528][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.003 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C80D8, redo lsn=0/40C80D8
2024-03-06 00:46:12.095 GMT [7692][postmaster] LOG:  database system is shut down
2024-03-06 00:46:12.246 GMT [1520][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:12.247 GMT [1520][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:12.248 GMT [1520][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:12.267 GMT [8028][startup] LOG:  database system was shut down at 2024-03-06 00:46:12 GMT
2024-03-06 00:46:12.275 GMT [1520][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:12.350 GMT [5656][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50382
2024-03-06 00:46:12.357 GMT [5656][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:12.357 GMT [5656][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:12.370 GMT [5656][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$
2024-03-06 00:46:12.373 GMT [5656][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50382
2024-03-06 00:46:12.455 GMT [6256][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50383
2024-03-06 00:46:12.463 GMT [6256][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:12.463 GMT [6256][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:12.466 GMT [6256][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$
2024-03-06 00:46:12.468 GMT [6256][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50383
2024-03-06 00:46:12.520 GMT [2940][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50384
2024-03-06 00:46:12.585 GMT [1520][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:12.585 GMT [1520][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:12.588 GMT [1520][postmaster] LOG:  background worker "logical replication launcher" (PID 4852) exited with exit code 1
2024-03-06 00:46:12.589 GMT [6508][checkpointer] LOG:  shutting down
2024-03-06 00:46:12.590 GMT [6508][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:12.593 GMT [6508][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C8150, redo lsn=0/40C8150
2024-03-06 00:46:12.597 GMT [1520][postmaster] LOG:  database system is shut down
2024-03-06 00:46:12.748 GMT [5344][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:12.749 GMT [5344][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:12.750 GMT [5344][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:12.770 GMT [6916][startup] LOG:  database system was shut down at 2024-03-06 00:46:12 GMT
2024-03-06 00:46:12.778 GMT [5344][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:12.848 GMT [4940][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50385
2024-03-06 00:46:12.855 GMT [4940][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:12.855 GMT [4940][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:12.867 GMT [4940][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$
2024-03-06 00:46:12.870 GMT [4940][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50385
2024-03-06 00:46:12.960 GMT [2388][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50386
2024-03-06 00:46:12.968 GMT [2388][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:12.968 GMT [2388][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:12.971 GMT [2388][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$
2024-03-06 00:46:12.973 GMT [2388][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50386
2024-03-06 00:46:13.081 GMT [3632][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50387
2024-03-06 00:46:13.089 GMT [3632][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:13.090 GMT [3632][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:13.093 GMT [3632][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$
2024-03-06 00:46:13.095 GMT [3632][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50387
2024-03-06 00:46:13.118 GMT [5344][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:13.118 GMT [5344][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:13.123 GMT [5344][postmaster] LOG:  background worker "logical replication launcher" (PID 4840) exited with exit code 1
2024-03-06 00:46:13.123 GMT [6004][checkpointer] LOG:  shutting down
2024-03-06 00:46:13.124 GMT [6004][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:13.127 GMT [6004][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C81C8, redo lsn=0/40C81C8
2024-03-06 00:46:13.132 GMT [5344][postmaster] LOG:  database system is shut down
2024-03-06 00:46:13.284 GMT [5084][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:13.285 GMT [5084][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:13.287 GMT [5084][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:13.311 GMT [5840][startup] LOG:  database system was shut down at 2024-03-06 00:46:13 GMT
2024-03-06 00:46:13.325 GMT [5084][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:13.382 GMT [5328][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50388
2024-03-06 00:46:13.390 GMT [5328][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:13.390 GMT [5328][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:13.403 GMT [5328][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=common-name.pg-ssltest.test$$
2024-03-06 00:46:13.406 GMT [5328][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50388
2024-03-06 00:46:13.485 GMT [6716][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50389
2024-03-06 00:46:13.523 GMT [5084][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:13.524 GMT [5084][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:13.527 GMT [5084][postmaster] LOG:  background worker "logical replication launcher" (PID 5748) exited with exit code 1
2024-03-06 00:46:13.528 GMT [1528][checkpointer] LOG:  shutting down
2024-03-06 00:46:13.528 GMT [1528][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:13.531 GMT [1528][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C8240, redo lsn=0/40C8240
2024-03-06 00:46:13.536 GMT [5084][postmaster] LOG:  database system is shut down
2024-03-06 00:46:13.682 GMT [4420][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:13.683 GMT [4420][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:13.684 GMT [4420][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:13.705 GMT [1608][startup] LOG:  database system was shut down at 2024-03-06 00:46:13 GMT
2024-03-06 00:46:13.712 GMT [4420][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:13.785 GMT [5132][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50390
2024-03-06 00:46:13.791 GMT [5132][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: tlsv1 alert unknown ca
2024-03-06 00:46:13.881 GMT [5296][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50391
2024-03-06 00:46:13.888 GMT [5296][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:13.888 GMT [5296][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:13.901 GMT [5296][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=system hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$
2024-03-06 00:46:13.905 GMT [5296][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50391
2024-03-06 00:46:13.988 GMT [6940][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50392
2024-03-06 00:46:14.027 GMT [4420][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:14.027 GMT [4420][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:14.032 GMT [4420][postmaster] LOG:  background worker "logical replication launcher" (PID 2588) exited with exit code 1
2024-03-06 00:46:14.033 GMT [8180][checkpointer] LOG:  shutting down
2024-03-06 00:46:14.033 GMT [8180][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:14.036 GMT [8180][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C82B8, redo lsn=0/40C82B8
2024-03-06 00:46:14.041 GMT [4420][postmaster] LOG:  database system is shut down
2024-03-06 00:46:14.188 GMT [916][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:14.189 GMT [916][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:14.190 GMT [916][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:14.209 GMT [5268][startup] LOG:  database system was shut down at 2024-03-06 00:46:14 GMT
2024-03-06 00:46:14.217 GMT [916][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:14.293 GMT [6596][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50393
2024-03-06 00:46:14.301 GMT [6596][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:14.301 GMT [6596][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:14.314 GMT [6596][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$
2024-03-06 00:46:14.316 GMT [6596][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50393
2024-03-06 00:46:14.406 GMT [3656][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50394
2024-03-06 00:46:14.412 GMT [3656][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-06 00:46:14.503 GMT [4260][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50395
2024-03-06 00:46:14.511 GMT [4260][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: sslv3 alert certificate revoked
2024-03-06 00:46:14.568 GMT [5788][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50396
2024-03-06 00:46:14.575 GMT [5788][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:14.575 GMT [5788][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:14.578 GMT [5788][client backend] [001_ssltests.pl][1/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-06 00:46:14.583 GMT [5788][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50396
2024-03-06 00:46:14.633 GMT [1504][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50397
2024-03-06 00:46:14.640 GMT [1504][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:14.640 GMT [1504][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.2, cipher=ECDHE-RSA-AES256-GCM-SHA384, bits=256)
2024-03-06 00:46:14.644 GMT [1504][client backend] [001_ssltests.pl][2/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require ssl_min_protocol_version=TLSv1.2 ssl_max_protocol_version=TLSv1.2$$
2024-03-06 00:46:14.646 GMT [1504][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.020 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50397
2024-03-06 00:46:14.856 GMT [428][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50398
2024-03-06 00:46:14.864 GMT [428][client backend] [[unknown]][3/1:0] FATAL:  connection requires a valid client certificate
2024-03-06 00:46:14.966 GMT [5304][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50399
2024-03-06 00:46:14.976 GMT [5304][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-06 00:46:14.976 GMT [5304][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:14.990 GMT [5304][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=C:/cirrus/build/testrun/ssl/001_ssltests/data/tmp_test_jAgD/client.key$$
2024-03-06 00:46:14.992 GMT [5304][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.034 user=ssltestuser database=certdb host=pg-loadbalancetest port=50399
2024-03-06 00:46:15.079 GMT [5736][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50400
2024-03-06 00:46:15.089 GMT [5736][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-06 00:46:15.089 GMT [5736][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:15.092 GMT [5736][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=C:/cirrus/build/testrun/ssl/001_ssltests/data/tmp_test_jAgD/client-der.key$$
2024-03-06 00:46:15.094 GMT [5736][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.022 user=ssltestuser database=certdb host=pg-loadbalancetest port=50400
2024-03-06 00:46:15.185 GMT [7164][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50401
2024-03-06 00:46:15.196 GMT [7164][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-06 00:46:15.196 GMT [7164][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:15.200 GMT [7164][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=C:/cirrus/build/testrun/ssl/001_ssltests/data/tmp_test_jAgD/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$
2024-03-06 00:46:15.202 GMT [7164][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=certdb host=pg-loadbalancetest port=50401
2024-03-06 00:46:15.293 GMT [2424][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50402
2024-03-06 00:46:15.302 GMT [2424][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-06 00:46:15.302 GMT [2424][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:15.305 GMT [2424][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=C:/cirrus/build/testrun/ssl/001_ssltests/data/tmp_test_jAgD/client-encrypted-der.key sslpassword='dUmmyP^#+'$$
2024-03-06 00:46:15.308 GMT [2424][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.023 user=ssltestuser database=certdb host=pg-loadbalancetest port=50402
2024-03-06 00:46:15.401 GMT [3852][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50403
2024-03-06 00:46:15.411 GMT [3852][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-06 00:46:15.411 GMT [3852][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:15.414 GMT [3852][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=C:/cirrus/build/testrun/ssl/001_ssltests/data/tmp_test_jAgD/client.key$$
2024-03-06 00:46:15.416 GMT [3852][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.022 user=ssltestuser database=certdb host=pg-loadbalancetest port=50403
2024-03-06 00:46:15.510 GMT [3576][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50404
2024-03-06 00:46:15.520 GMT [3576][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-06 00:46:15.520 GMT [3576][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:15.523 GMT [3576][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=C:/cirrus/build/testrun/ssl/001_ssltests/data/tmp_test_jAgD/client.key$$
2024-03-06 00:46:15.525 GMT [3576][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.021 user=ssltestuser database=certdb host=pg-loadbalancetest port=50404
2024-03-06 00:46:15.766 GMT [2512][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50405
2024-03-06 00:46:15.773 GMT [2512][client backend] [[unknown]][10/1:0] FATAL:  connection requires a valid client certificate
2024-03-06 00:46:15.999 GMT [8156][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50406
2024-03-06 00:46:16.009 GMT [8156][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: EOF detected
2024-03-06 00:46:16.105 GMT [6500][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50407
2024-03-06 00:46:16.114 GMT [6500][client backend] [[unknown]][11/1:0] LOG:  connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:8)
2024-03-06 00:46:16.114 GMT [6500][client backend] [[unknown]][11/1:0] LOG:  connection authorized: user=ssltestuser database=certdb_dn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:16.128 GMT [6500][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=C:/cirrus/build/testrun/ssl/001_ssltests/data/tmp_test_jAgD/client-dn.key$$
2024-03-06 00:46:16.130 GMT [6500][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=ssltestuser database=certdb_dn host=pg-loadbalancetest port=50407
2024-03-06 00:46:16.217 GMT [1988][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50408
2024-03-06 00:46:16.226 GMT [1988][client backend] [[unknown]][12/1:0] LOG:  connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:9)
2024-03-06 00:46:16.226 GMT [1988][client backend] [[unknown]][12/1:0] LOG:  connection authorized: user=ssltestuser database=certdb_dn_re application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:16.239 GMT [1988][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=C:/cirrus/build/testrun/ssl/001_ssltests/data/tmp_test_jAgD/client-dn.key$$
2024-03-06 00:46:16.242 GMT [1988][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.033 user=ssltestuser database=certdb_dn_re host=pg-loadbalancetest port=50408
2024-03-06 00:46:16.338 GMT [7936][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50409
2024-03-06 00:46:16.347 GMT [7936][client backend] [[unknown]][13/1:0] LOG:  connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:10)
2024-03-06 00:46:16.347 GMT [7936][client backend] [[unknown]][13/1:0] LOG:  connection authorized: user=ssltestuser database=certdb_cn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:16.359 GMT [7936][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=C:/cirrus/build/testrun/ssl/001_ssltests/data/tmp_test_jAgD/client-dn.key$$
2024-03-06 00:46:16.363 GMT [7936][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=ssltestuser database=certdb_cn host=pg-loadbalancetest port=50409
2024-03-06 00:46:16.453 GMT [8088][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50410
2024-03-06 00:46:16.462 GMT [8088][client backend] [[unknown]][14/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-06 00:46:16.462 GMT [8088][client backend] [[unknown]][14/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:16.465 GMT [8088][client backend] [001_ssltests.pl][14/2:0] LOG:  statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid()
2024-03-06 00:46:16.470 GMT [8088][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=ssltestuser database=certdb host=pg-loadbalancetest port=50410
2024-03-06 00:46:16.559 GMT [5688][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50411
2024-03-06 00:46:16.568 GMT [5688][client backend] [[unknown]][15/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-06 00:46:16.568 GMT [5688][client backend] [[unknown]][15/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-06 00:46:16.568 GMT [5688][client backend] [[unknown]][15/1:0] FATAL:  certificate authentication failed for user "anotheruser"
2024-03-06 00:46:16.568 GMT [5688][client backend] [[unknown]][15/1:0] DETAIL:  Connection matched file "C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf" line 7: "hostssl certdb          all             127.0.0.1/32            cert"
2024-03-06 00:46:16.667 GMT [7880][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50412
2024-03-06 00:46:16.675 GMT [7880][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-06 00:46:16.675 GMT [7880][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: certificate revoked.
	Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-06 00:46:16.778 GMT [1284][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50413
2024-03-06 00:46:16.787 GMT [1284][client backend] [[unknown]][16/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:4)
2024-03-06 00:46:16.787 GMT [1284][client backend] [[unknown]][16/1:0] LOG:  connection authorized: user=ssltestuser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:16.800 GMT [1284][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=C:/cirrus/build/testrun/ssl/001_ssltests/data/tmp_test_jAgD/client.key$$
2024-03-06 00:46:16.804 GMT [1284][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.033 user=ssltestuser database=verifydb host=pg-loadbalancetest port=50413
2024-03-06 00:46:16.885 GMT [2776][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50414
2024-03-06 00:46:16.894 GMT [2776][client backend] [[unknown]][17/1:0] LOG:  provided user name (anotheruser) and authenticated user name (ssltestuser) do not match
2024-03-06 00:46:16.894 GMT [2776][client backend] [[unknown]][17/1:0] LOG:  certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch
2024-03-06 00:46:16.894 GMT [2776][client backend] [[unknown]][17/1:0] FATAL:  "trust" authentication failed for user "anotheruser"
2024-03-06 00:46:16.894 GMT [2776][client backend] [[unknown]][17/1:0] DETAIL:  Connection matched file "C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf" line 5: "hostssl verifydb        anotheruser     127.0.0.1/32            trust        clientcert=verify-full"
2024-03-06 00:46:16.998 GMT [1236][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50415
2024-03-06 00:46:17.007 GMT [1236][client backend] [[unknown]][18/1:0] LOG:  connection authenticated: user="yetanotheruser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:6)
2024-03-06 00:46:17.007 GMT [1236][client backend] [[unknown]][18/1:0] LOG:  connection authorized: user=yetanotheruser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:17.011 GMT [1236][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=C:/cirrus/build/testrun/ssl/001_ssltests/data/tmp_test_jAgD/client.key$$
2024-03-06 00:46:17.013 GMT [1236][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.022 user=yetanotheruser database=verifydb host=pg-loadbalancetest port=50415
2024-03-06 00:46:17.079 GMT [916][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:17.079 GMT [916][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:17.083 GMT [916][postmaster] LOG:  background worker "logical replication launcher" (PID 2256) exited with exit code 1
2024-03-06 00:46:17.084 GMT [8108][checkpointer] LOG:  shutting down
2024-03-06 00:46:17.084 GMT [8108][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:17.087 GMT [8108][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C8330, redo lsn=0/40C8330
2024-03-06 00:46:17.092 GMT [916][postmaster] LOG:  database system is shut down
2024-03-06 00:46:17.242 GMT [4720][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:17.243 GMT [4720][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:17.244 GMT [4720][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:17.266 GMT [2688][startup] LOG:  database system was shut down at 2024-03-06 00:46:17 GMT
2024-03-06 00:46:17.275 GMT [4720][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:17.342 GMT [6400][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50416
2024-03-06 00:46:17.351 GMT [6400][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: identity="CN=ssltestuser" method=cert (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7)
2024-03-06 00:46:17.351 GMT [6400][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:17.365 GMT [6400][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=C:/cirrus/build/testrun/ssl/001_ssltests/data/tmp_test_jAgD/client.key sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 host=localhost sslmode=require sslcert=ssl/client+client_ca.crt$$
2024-03-06 00:46:17.368 GMT [6400][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.034 user=ssltestuser database=certdb host=pg-loadbalancetest port=50416
2024-03-06 00:46:17.448 GMT [1568][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50417
2024-03-06 00:46:17.456 GMT [1568][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-06 00:46:17.456 GMT [1568][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: unable to get local issuer certificate.
	Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921344, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-06 00:46:17.513 GMT [5368][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50418
2024-03-06 00:46:17.521 GMT [5368][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-06 00:46:17.521 GMT [5368][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: unable to get local issuer certificate.
	Failed certificate data (unverified): subject ".../CN=ssl-123456789012345678901234567890123456789012345678901234567890", serial number 2315702411956921348, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-06 00:46:17.596 GMT [4720][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:17.596 GMT [4720][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:17.603 GMT [4720][postmaster] LOG:  background worker "logical replication launcher" (PID 4316) exited with exit code 1
2024-03-06 00:46:17.603 GMT [1460][checkpointer] LOG:  shutting down
2024-03-06 00:46:17.603 GMT [1460][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:17.607 GMT [1460][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C83A8, redo lsn=0/40C83A8
2024-03-06 00:46:17.612 GMT [4720][postmaster] LOG:  database system is shut down
2024-03-06 00:46:17.759 GMT [7180][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:17.759 GMT [7180][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:17.761 GMT [7180][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:17.782 GMT [5768][startup] LOG:  database system was shut down at 2024-03-06 00:46:17 GMT
2024-03-06 00:46:17.790 GMT [7180][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:17.860 GMT [5924][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50419
2024-03-06 00:46:17.867 GMT [5924][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-06 00:46:17.867 GMT [5924][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 1: unable to get local issuer certificate.
	Failed certificate data (unverified): subject "/CN=Test CA for PostgreSQL SSL regression test client certs", serial number 2315702411956921089, issuer "/CN=Test root CA for PostgreSQL SSL regression test suite".
2024-03-06 00:46:17.955 GMT [7180][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:17.955 GMT [7180][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:17.963 GMT [7180][postmaster] LOG:  background worker "logical replication launcher" (PID 2368) exited with exit code 1
2024-03-06 00:46:17.964 GMT [5760][checkpointer] LOG:  shutting down
2024-03-06 00:46:17.964 GMT [5760][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:17.967 GMT [5760][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.003 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C8420, redo lsn=0/40C8420
2024-03-06 00:46:17.972 GMT [7180][postmaster] LOG:  database system is shut down
2024-03-06 00:46:18.122 GMT [7536][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:18.122 GMT [7536][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:18.124 GMT [7536][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:18.152 GMT [5244][startup] LOG:  database system was shut down at 2024-03-06 00:46:17 GMT
2024-03-06 00:46:18.160 GMT [7536][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:18.223 GMT [836][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50420
2024-03-06 00:46:18.231 GMT [836][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-06 00:46:18.231 GMT [836][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: certificate revoked.
	Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-06 00:46:18.322 GMT [7340][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50421
2024-03-06 00:46:18.331 GMT [7340][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: certificate verify failed
2024-03-06 00:46:18.331 GMT [7340][not initialized] [[unknown]][:0] DETAIL:  Client certificate verification failed at depth 0: certificate revoked.
	Failed certificate data (unverified): subject "/CN=\xce\x9f\xce\xb4\xcf\x85\xcf\x83\xcf\x83\xce\xad\xce\xb1\xcf\x82", serial number 2315702411956921349, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs".
2024-03-06 00:46:18.360 GMT [7536][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:18.360 GMT [7536][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:18.365 GMT [7536][postmaster] LOG:  background worker "logical replication launcher" (PID 2792) exited with exit code 1
2024-03-06 00:46:18.365 GMT [4916][checkpointer] LOG:  shutting down
2024-03-06 00:46:18.365 GMT [4916][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:18.368 GMT [4916][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C8498, redo lsn=0/40C8498
2024-03-06 00:46:18.373 GMT [7536][postmaster] LOG:  database system is shut down
2024-03-06 00:46:18.526 GMT [7720][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:18.526 GMT [7720][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:18.527 GMT [7720][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:18.551 GMT [3968][startup] LOG:  database system was shut down at 2024-03-06 00:46:18 GMT
2024-03-06 00:46:18.558 GMT [7720][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:18.626 GMT [6516][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50422
2024-03-06 00:46:18.629 GMT [6516][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-06 00:46:18.630 GMT [6516][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: A non-blocking socket operation could not be completed immediately.
	
2024-03-06 00:46:18.731 GMT [8076][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50423
2024-03-06 00:46:18.738 GMT [8076][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:18.738 GMT [8076][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:18.751 GMT [8076][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-06 00:46:18.753 GMT [8076][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.029 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50423
2024-03-06 00:46:18.815 GMT [7720][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:18.815 GMT [7720][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:18.819 GMT [7720][postmaster] LOG:  background worker "logical replication launcher" (PID 3316) exited with exit code 1
2024-03-06 00:46:18.820 GMT [5880][checkpointer] LOG:  shutting down
2024-03-06 00:46:18.820 GMT [5880][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:18.823 GMT [5880][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.003 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C8510, redo lsn=0/40C8510
2024-03-06 00:46:18.827 GMT [7720][postmaster] LOG:  database system is shut down
2024-03-06 00:46:18.978 GMT [4236][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:18.978 GMT [4236][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:18.979 GMT [4236][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:19.003 GMT [7692][startup] LOG:  database system was shut down at 2024-03-06 00:46:18 GMT
2024-03-06 00:46:19.011 GMT [4236][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:19.078 GMT [5972][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50424
2024-03-06 00:46:19.081 GMT [5972][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-06 00:46:19.081 GMT [5972][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: A non-blocking socket operation could not be completed immediately.
	
2024-03-06 00:46:19.139 GMT [8160][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50425
2024-03-06 00:46:19.146 GMT [8160][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:19.146 GMT [8160][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:19.159 GMT [8160][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-06 00:46:19.162 GMT [8160][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.030 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50425
2024-03-06 00:46:19.222 GMT [4236][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:19.222 GMT [4236][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:19.228 GMT [4236][postmaster] LOG:  background worker "logical replication launcher" (PID 4572) exited with exit code 1
2024-03-06 00:46:19.228 GMT [7796][checkpointer] LOG:  shutting down
2024-03-06 00:46:19.228 GMT [7796][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:19.231 GMT [7796][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C8588, redo lsn=0/40C8588
2024-03-06 00:46:19.236 GMT [4236][postmaster] LOG:  database system is shut down
2024-03-06 00:46:19.386 GMT [7764][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:19.387 GMT [7764][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:19.388 GMT [7764][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:19.413 GMT [7896][startup] LOG:  database system was shut down at 2024-03-06 00:46:19 GMT
2024-03-06 00:46:19.420 GMT [7764][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:19.486 GMT [4252][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50426
2024-03-06 00:46:19.490 GMT [4252][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-06 00:46:19.490 GMT [4252][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: A non-blocking socket operation could not be completed immediately.
	
2024-03-06 00:46:19.548 GMT [5692][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50427
2024-03-06 00:46:19.555 GMT [5692][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:19.555 GMT [5692][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:19.569 GMT [5692][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-06 00:46:19.572 GMT [5692][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50427
2024-03-06 00:46:19.629 GMT [7764][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:19.630 GMT [7764][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:19.634 GMT [7764][postmaster] LOG:  background worker "logical replication launcher" (PID 668) exited with exit code 1
2024-03-06 00:46:19.635 GMT [7124][checkpointer] LOG:  shutting down
2024-03-06 00:46:19.636 GMT [7124][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:19.639 GMT [7124][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C8600, redo lsn=0/40C8600
2024-03-06 00:46:19.644 GMT [7764][postmaster] LOG:  database system is shut down
2024-03-06 00:46:19.795 GMT [5184][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:19.796 GMT [5184][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:19.797 GMT [5184][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:19.832 GMT [7772][startup] LOG:  database system was shut down at 2024-03-06 00:46:19 GMT
2024-03-06 00:46:19.840 GMT [5184][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:19.911 GMT [4816][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50428
2024-03-06 00:46:19.914 GMT [4816][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-06 00:46:19.915 GMT [4816][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: A non-blocking socket operation could not be completed immediately.
	
2024-03-06 00:46:20.014 GMT [7024][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50429
2024-03-06 00:46:20.021 GMT [7024][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:20.021 GMT [7024][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:20.035 GMT [7024][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-06 00:46:20.039 GMT [7024][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50429
2024-03-06 00:46:20.097 GMT [5184][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:20.097 GMT [5184][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:20.101 GMT [5184][postmaster] LOG:  background worker "logical replication launcher" (PID 7816) exited with exit code 1
2024-03-06 00:46:20.104 GMT [2008][checkpointer] LOG:  shutting down
2024-03-06 00:46:20.104 GMT [2008][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:20.107 GMT [2008][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.003 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C8678, redo lsn=0/40C8678
2024-03-06 00:46:20.111 GMT [5184][postmaster] LOG:  database system is shut down
2024-03-06 00:46:20.262 GMT [7304][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:20.263 GMT [7304][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:20.264 GMT [7304][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:20.288 GMT [3984][startup] LOG:  database system was shut down at 2024-03-06 00:46:20 GMT
2024-03-06 00:46:20.296 GMT [7304][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:20.364 GMT [7444][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50430
2024-03-06 00:46:20.367 GMT [7444][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-06 00:46:20.367 GMT [7444][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: A non-blocking socket operation could not be completed immediately.
	
2024-03-06 00:46:20.425 GMT [5084][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50431
2024-03-06 00:46:20.433 GMT [5084][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:20.433 GMT [5084][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:20.446 GMT [5084][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-06 00:46:20.449 GMT [5084][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50431
2024-03-06 00:46:20.506 GMT [7304][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:20.506 GMT [7304][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:20.511 GMT [7304][postmaster] LOG:  background worker "logical replication launcher" (PID 6304) exited with exit code 1
2024-03-06 00:46:20.511 GMT [2784][checkpointer] LOG:  shutting down
2024-03-06 00:46:20.512 GMT [2784][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:20.514 GMT [2784][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.003 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C86F0, redo lsn=0/40C86F0
2024-03-06 00:46:20.519 GMT [7304][postmaster] LOG:  database system is shut down
2024-03-06 00:46:20.671 GMT [6804][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:20.671 GMT [6804][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:20.673 GMT [6804][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:20.698 GMT [7756][startup] LOG:  database system was shut down at 2024-03-06 00:46:20 GMT
2024-03-06 00:46:20.707 GMT [6804][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:20.766 GMT [2676][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50432
2024-03-06 00:46:20.770 GMT [2676][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-06 00:46:20.770 GMT [2676][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: A non-blocking socket operation could not be completed immediately.
	
2024-03-06 00:46:20.828 GMT [4688][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50433
2024-03-06 00:46:20.836 GMT [4688][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:20.836 GMT [4688][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:20.849 GMT [4688][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-06 00:46:20.853 GMT [4688][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.032 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50433
2024-03-06 00:46:20.912 GMT [6804][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:20.912 GMT [6804][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:20.916 GMT [6804][postmaster] LOG:  background worker "logical replication launcher" (PID 5296) exited with exit code 1
2024-03-06 00:46:20.917 GMT [3256][checkpointer] LOG:  shutting down
2024-03-06 00:46:20.918 GMT [3256][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:20.921 GMT [3256][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C8768, redo lsn=0/40C8768
2024-03-06 00:46:20.925 GMT [6804][postmaster] LOG:  database system is shut down
2024-03-06 00:46:21.072 GMT [3164][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:21.073 GMT [3164][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:21.074 GMT [3164][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:21.095 GMT [7288][startup] LOG:  database system was shut down at 2024-03-06 00:46:20 GMT
2024-03-06 00:46:21.103 GMT [3164][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:21.174 GMT [7380][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50434
2024-03-06 00:46:21.177 GMT [7380][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-06 00:46:21.177 GMT [7380][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: A non-blocking socket operation could not be completed immediately.
	
2024-03-06 00:46:21.234 GMT [4100][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50435
2024-03-06 00:46:21.241 GMT [4100][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:21.241 GMT [4100][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:21.261 GMT [4100][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-06 00:46:21.265 GMT [4100][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.038 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50435
2024-03-06 00:46:21.319 GMT [3164][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:21.319 GMT [3164][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:21.324 GMT [3164][postmaster] LOG:  background worker "logical replication launcher" (PID 7232) exited with exit code 1
2024-03-06 00:46:21.324 GMT [5352][checkpointer] LOG:  shutting down
2024-03-06 00:46:21.324 GMT [5352][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:21.327 GMT [5352][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.003 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C87E0, redo lsn=0/40C87E0
2024-03-06 00:46:21.332 GMT [3164][postmaster] LOG:  database system is shut down
2024-03-06 00:46:21.484 GMT [6244][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:21.485 GMT [6244][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:21.486 GMT [6244][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:21.507 GMT [6572][startup] LOG:  database system was shut down at 2024-03-06 00:46:21 GMT
2024-03-06 00:46:21.515 GMT [6244][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:21.578 GMT [6932][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50436
2024-03-06 00:46:21.581 GMT [6932][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-06 00:46:21.582 GMT [6932][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: A non-blocking socket operation could not be completed immediately.
	
2024-03-06 00:46:21.644 GMT [7400][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50437
2024-03-06 00:46:21.651 GMT [7400][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:21.651 GMT [7400][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:21.664 GMT [7400][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-06 00:46:21.667 GMT [7400][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50437
2024-03-06 00:46:21.724 GMT [6244][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:21.724 GMT [6244][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:21.730 GMT [6244][postmaster] LOG:  background worker "logical replication launcher" (PID 6088) exited with exit code 1
2024-03-06 00:46:21.730 GMT [4004][checkpointer] LOG:  shutting down
2024-03-06 00:46:21.730 GMT [4004][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:21.733 GMT [4004][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C8858, redo lsn=0/40C8858
2024-03-06 00:46:21.738 GMT [6244][postmaster] LOG:  database system is shut down
2024-03-06 00:46:21.885 GMT [7220][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-windows, compiled by msvc-19.29.30151, 64-bit
2024-03-06 00:46:21.886 GMT [7220][postmaster] LOG:  listening on IPv4 address "127.0.0.1", port 52707
2024-03-06 00:46:21.887 GMT [7220][postmaster] LOG:  listening on Unix socket "C:/Windows/TEMP/iWwKbmkgMF/.s.PGSQL.52707"
2024-03-06 00:46:21.907 GMT [3020][startup] LOG:  database system was shut down at 2024-03-06 00:46:21 GMT
2024-03-06 00:46:21.915 GMT [7220][postmaster] LOG:  database system is ready to accept connections
2024-03-06 00:46:21.986 GMT [3400][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50438
2024-03-06 00:46:21.990 GMT [3400][not initialized] [[unknown]][:0] WARNING:  could not read ssl_ocsp_file
2024-03-06 00:46:21.990 GMT [3400][not initialized] [[unknown]][:0] LOG:  could not accept SSL connection: A non-blocking socket operation could not be completed immediately.
	
2024-03-06 00:46:22.045 GMT [8156][not initialized] [[unknown]][:0] LOG:  connection received: host=pg-loadbalancetest port=50439
2024-03-06 00:46:22.052 GMT [8156][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="ssltestuser" method=trust (C:/cirrus/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3)
2024-03-06 00:46:22.052 GMT [8156][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256)
2024-03-06 00:46:22.065 GMT [8156][client backend] [001_ssltests.pl][0/2:0] LOG:  statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$
2024-03-06 00:46:22.069 GMT [8156][client backend] [001_ssltests.pl][:0] LOG:  disconnection: session time: 0:00:00.031 user=ssltestuser database=trustdb host=pg-loadbalancetest port=50439
2024-03-06 00:46:22.128 GMT [7220][postmaster] LOG:  received fast shutdown request
2024-03-06 00:46:22.128 GMT [7220][postmaster] LOG:  aborting any active transactions
2024-03-06 00:46:22.132 GMT [7220][postmaster] LOG:  background worker "logical replication launcher" (PID 6416) exited with exit code 1
2024-03-06 00:46:22.133 GMT [6752][checkpointer] LOG:  shutting down
2024-03-06 00:46:22.133 GMT [6752][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-06 00:46:22.136 GMT [6752][checkpointer] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.003 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40C88D0, redo lsn=0/40C88D0
2024-03-06 00:46:22.141 GMT [7220][postmaster] LOG:  database system is shut down
2024-03-06 00:46:22.282 GMT [832][postmaster] FATAL:  could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory
2024-03-06 00:46:22.282 GMT [832][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[00:46:22.347](0.235s) Bail out!  pg_ctl restart failed
# No postmaster PID for node "primary"