[14:09:03.359](0.069s) # setting up data directory # Checking port 59400 # Found port 59400 Name: primary Data directory: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata Backup directory: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/backup Archive directory: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/archives Connection string: port=59400 host=/tmp/ZRzs18G8YM Log file: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [14:09:03.368](0.010s) # initializing database system by copying initdb template # Running: cp -RPp /tmp/cirrus-ci-build/build/tmp_install/initdb-template /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata # Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata ### Starting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log -o --cluster-name=primary start waiting for server to start.... done server started # Postmaster PID for node "primary" is 36882 [14:09:03.605](0.236s) ok 1 - ssl_library parameter psql::1: WARNING: roles created by regression test cases should have names starting with "regress_" psql::1: WARNING: roles created by regression test cases should have names starting with "regress_" psql::1: WARNING: roles created by regression test cases should have names starting with "regress_" psql::1: WARNING: roles created by regression test cases should have names starting with "regress_" psql::1: WARNING: databases created by regression test cases should have names including "regression" psql::1: WARNING: databases created by regression test cases should have names including "regression" psql::1: WARNING: databases created by regression test cases should have names including "regression" psql::1: WARNING: databases created by regression test cases should have names including "regression" psql::1: WARNING: databases created by regression test cases should have names including "regression" psql::1: WARNING: databases created by regression test cases should have names including "regression" ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37127 [14:09:04.317](0.713s) # testing password-protected keys ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... stopped waiting pg_ctl: could not start server Examine the log output. # pg_ctl restart failed; logfile: 2024-03-14 14:09:03.482 UTC [36882][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:03.482 UTC [36882][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:03.491 UTC [36887][startup] LOG: database system was shut down at 2024-03-14 14:06:49 UTC 2024-03-14 14:09:03.493 UTC [36882][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:03.598 UTC [36929][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.598 UTC [36929][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.598 UTC [36929][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.602 UTC [36929][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-14 14:09:03.603 UTC [36929][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.612 UTC [36935][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.613 UTC [36935][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.613 UTC [36935][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.613 UTC [36935][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-14 14:09:03.613 UTC [36935][client backend] [001_ssltests.pl][1/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-14 14:09:03.614 UTC [36935][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.658 UTC [36953][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.658 UTC [36953][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.658 UTC [36953][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.659 UTC [36953][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-14 14:09:03.659 UTC [36953][client backend] [001_ssltests.pl][2/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-14 14:09:03.664 UTC [36953][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.709 UTC [36965][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.710 UTC [36965][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.710 UTC [36965][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.710 UTC [36965][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-14 14:09:03.710 UTC [36965][client backend] [001_ssltests.pl][3/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-14 14:09:03.711 UTC [36965][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.754 UTC [36986][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.755 UTC [36986][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.755 UTC [36986][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.755 UTC [36986][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-14 14:09:03.755 UTC [36986][client backend] [001_ssltests.pl][4/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-14 14:09:03.756 UTC [36986][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.799 UTC [36998][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.806 UTC [36998][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.806 UTC [36998][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.806 UTC [36998][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-14 14:09:03.806 UTC [36998][client backend] [001_ssltests.pl][5/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-14 14:09:03.827 UTC [36998][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.028 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.850 UTC [37016][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.850 UTC [37016][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.850 UTC [37016][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.851 UTC [37016][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-14 14:09:03.851 UTC [37016][client backend] [001_ssltests.pl][6/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-14 14:09:03.893 UTC [37016][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.042 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.908 UTC [37030][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.908 UTC [37030][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.908 UTC [37030][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.912 UTC [37030][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-14 14:09:03.912 UTC [37030][client backend] [001_ssltests.pl][7/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-14 14:09:03.936 UTC [37030][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.027 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.962 UTC [37049][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.963 UTC [37049][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.963 UTC [37049][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.964 UTC [37049][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-14 14:09:03.964 UTC [37049][client backend] [001_ssltests.pl][8/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-14 14:09:03.998 UTC [37049][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.036 user=postgres database=postgres host=[local] 2024-03-14 14:09:04.008 UTC [37075][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:04.009 UTC [37075][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:04.009 UTC [37075][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:04.010 UTC [37075][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-14 14:09:04.010 UTC [37075][client backend] [001_ssltests.pl][9/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-14 14:09:04.027 UTC [37075][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=postgres database=postgres host=[local] 2024-03-14 14:09:04.070 UTC [37086][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:04.071 UTC [37086][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:04.071 UTC [37086][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:04.077 UTC [37086][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-14 14:09:04.077 UTC [37086][client backend] [001_ssltests.pl][10/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-14 14:09:04.104 UTC [37086][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.034 user=postgres database=postgres host=[local] 2024-03-14 14:09:04.112 UTC [36882][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:04.112 UTC [36882][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:04.114 UTC [36882][postmaster] LOG: background worker "logical replication launcher" (PID 36895) exited with exit code 1 2024-03-14 14:09:04.115 UTC [36885][checkpointer] LOG: shutting down 2024-03-14 14:09:04.115 UTC [36885][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:04.165 UTC [36885][checkpointer] LOG: checkpoint complete: wrote 5614 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.049 s, sync=0.001 s, total=0.051 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45190 kB, estimate=45190 kB; lsn=0/413E788, redo lsn=0/413E788 2024-03-14 14:09:04.172 UTC [36882][postmaster] LOG: database system is shut down 2024-03-14 14:09:04.226 UTC [37127][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:04.226 UTC [37127][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:04.226 UTC [37127][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:04.228 UTC [37135][startup] LOG: database system was shut down at 2024-03-14 14:09:04 UTC 2024-03-14 14:09:04.231 UTC [37127][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:04.319 UTC [37127][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:04.319 UTC [37127][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:04.320 UTC [37127][postmaster] LOG: background worker "logical replication launcher" (PID 37138) exited with exit code 1 2024-03-14 14:09:04.321 UTC [37133][checkpointer] LOG: shutting down 2024-03-14 14:09:04.321 UTC [37133][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:04.321 UTC [37133][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413E800, redo lsn=0/413E800 2024-03-14 14:09:04.322 UTC [37127][postmaster] LOG: database system is shut down 2024-03-14 14:09:04.429 UTC [37185][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-14 14:09:04.429 UTC [37185][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [14:09:04.523](0.206s) ok 2 - restart fails with password-protected key file with wrong password ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart pg_ctl: PID file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/postmaster.pid" does not exist Is server running? trying to start server anyway waiting for server to start.... done server started # Postmaster PID for node "primary" is 37208 [14:09:04.631](0.107s) ok 3 - restart succeeds with password-protected key file ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... stopped waiting pg_ctl: could not start server Examine the log output. # pg_ctl restart failed; logfile: 2024-03-14 14:09:03.482 UTC [36882][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:03.482 UTC [36882][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:03.491 UTC [36887][startup] LOG: database system was shut down at 2024-03-14 14:06:49 UTC 2024-03-14 14:09:03.493 UTC [36882][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:03.598 UTC [36929][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.598 UTC [36929][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.598 UTC [36929][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.602 UTC [36929][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-14 14:09:03.603 UTC [36929][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.612 UTC [36935][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.613 UTC [36935][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.613 UTC [36935][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.613 UTC [36935][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-14 14:09:03.613 UTC [36935][client backend] [001_ssltests.pl][1/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-14 14:09:03.614 UTC [36935][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.658 UTC [36953][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.658 UTC [36953][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.658 UTC [36953][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.659 UTC [36953][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-14 14:09:03.659 UTC [36953][client backend] [001_ssltests.pl][2/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-14 14:09:03.664 UTC [36953][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.709 UTC [36965][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.710 UTC [36965][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.710 UTC [36965][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.710 UTC [36965][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-14 14:09:03.710 UTC [36965][client backend] [001_ssltests.pl][3/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-14 14:09:03.711 UTC [36965][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.754 UTC [36986][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.755 UTC [36986][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.755 UTC [36986][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.755 UTC [36986][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-14 14:09:03.755 UTC [36986][client backend] [001_ssltests.pl][4/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-14 14:09:03.756 UTC [36986][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.799 UTC [36998][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.806 UTC [36998][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.806 UTC [36998][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.806 UTC [36998][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-14 14:09:03.806 UTC [36998][client backend] [001_ssltests.pl][5/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-14 14:09:03.827 UTC [36998][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.028 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.850 UTC [37016][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.850 UTC [37016][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.850 UTC [37016][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.851 UTC [37016][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-14 14:09:03.851 UTC [37016][client backend] [001_ssltests.pl][6/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-14 14:09:03.893 UTC [37016][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.042 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.908 UTC [37030][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.908 UTC [37030][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.908 UTC [37030][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.912 UTC [37030][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-14 14:09:03.912 UTC [37030][client backend] [001_ssltests.pl][7/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-14 14:09:03.936 UTC [37030][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.027 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.962 UTC [37049][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.963 UTC [37049][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.963 UTC [37049][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.964 UTC [37049][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-14 14:09:03.964 UTC [37049][client backend] [001_ssltests.pl][8/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-14 14:09:03.998 UTC [37049][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.036 user=postgres database=postgres host=[local] 2024-03-14 14:09:04.008 UTC [37075][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:04.009 UTC [37075][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:04.009 UTC [37075][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:04.010 UTC [37075][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-14 14:09:04.010 UTC [37075][client backend] [001_ssltests.pl][9/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-14 14:09:04.027 UTC [37075][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=postgres database=postgres host=[local] 2024-03-14 14:09:04.070 UTC [37086][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:04.071 UTC [37086][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:04.071 UTC [37086][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:04.077 UTC [37086][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-14 14:09:04.077 UTC [37086][client backend] [001_ssltests.pl][10/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-14 14:09:04.104 UTC [37086][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.034 user=postgres database=postgres host=[local] 2024-03-14 14:09:04.112 UTC [36882][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:04.112 UTC [36882][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:04.114 UTC [36882][postmaster] LOG: background worker "logical replication launcher" (PID 36895) exited with exit code 1 2024-03-14 14:09:04.115 UTC [36885][checkpointer] LOG: shutting down 2024-03-14 14:09:04.115 UTC [36885][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:04.165 UTC [36885][checkpointer] LOG: checkpoint complete: wrote 5614 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.049 s, sync=0.001 s, total=0.051 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45190 kB, estimate=45190 kB; lsn=0/413E788, redo lsn=0/413E788 2024-03-14 14:09:04.172 UTC [36882][postmaster] LOG: database system is shut down 2024-03-14 14:09:04.226 UTC [37127][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:04.226 UTC [37127][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:04.226 UTC [37127][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:04.228 UTC [37135][startup] LOG: database system was shut down at 2024-03-14 14:09:04 UTC 2024-03-14 14:09:04.231 UTC [37127][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:04.319 UTC [37127][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:04.319 UTC [37127][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:04.320 UTC [37127][postmaster] LOG: background worker "logical replication launcher" (PID 37138) exited with exit code 1 2024-03-14 14:09:04.321 UTC [37133][checkpointer] LOG: shutting down 2024-03-14 14:09:04.321 UTC [37133][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:04.321 UTC [37133][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413E800, redo lsn=0/413E800 2024-03-14 14:09:04.322 UTC [37127][postmaster] LOG: database system is shut down 2024-03-14 14:09:04.429 UTC [37185][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-14 14:09:04.429 UTC [37185][postmaster] LOG: database system is shut down 2024-03-14 14:09:04.541 UTC [37208][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:04.541 UTC [37208][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:04.541 UTC [37208][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:04.544 UTC [37221][startup] LOG: database system was shut down at 2024-03-14 14:09:04 UTC 2024-03-14 14:09:04.548 UTC [37208][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:04.633 UTC [37208][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:04.633 UTC [37208][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:04.634 UTC [37208][postmaster] LOG: background worker "logical replication launcher" (PID 37228) exited with exit code 1 2024-03-14 14:09:04.635 UTC [37219][checkpointer] LOG: shutting down 2024-03-14 14:09:04.635 UTC [37219][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:04.635 UTC [37219][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413E878, redo lsn=0/413E878 2024-03-14 14:09:04.636 UTC [37208][postmaster] LOG: database system is shut down 2024-03-14 14:09:04.749 UTC [37256][postmaster] FATAL: could not set SSL protocol version range 2024-03-14 14:09:04.749 UTC [37256][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-14 14:09:04.749 UTC [37256][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [14:09:04.841](0.211s) ok 4 - restart fails with incorrect SSL protocol bounds ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart pg_ctl: PID file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/postmaster.pid" does not exist Is server running? trying to start server anyway waiting for server to start.... done server started # Postmaster PID for node "primary" is 37283 [14:09:04.946](0.104s) ok 5 - restart succeeds with correct SSL protocol bounds [14:09:04.946](0.000s) # running client tests ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37338 [14:09:05.161](0.215s) ok 6 - server doesn't accept non-SSL connections [14:09:05.162](0.001s) ok 7 - server doesn't accept non-SSL connections: matches [14:09:05.181](0.019s) ok 8 - connect without server root cert sslmode=require [14:09:05.181](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [14:09:05.189](0.008s) ok 10 - connect without server root cert sslmode=verify-ca [14:09:05.189](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [14:09:05.198](0.009s) ok 12 - connect without server root cert sslmode=verify-full [14:09:05.199](0.001s) ok 13 - connect without server root cert sslmode=verify-full: matches [14:09:05.210](0.011s) ok 14 - connect with wrong server root cert sslmode=require [14:09:05.210](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [14:09:05.222](0.012s) ok 16 - connect with wrong server root cert sslmode=verify-ca [14:09:05.222](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [14:09:05.233](0.011s) ok 18 - connect with wrong server root cert sslmode=verify-full [14:09:05.234](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [14:09:05.246](0.012s) ok 20 - connect with server CA cert, without root CA [14:09:05.246](0.000s) ok 21 - connect with server CA cert, without root CA: matches [14:09:05.258](0.012s) ok 22 - connect with correct server CA cert file sslmode=require [14:09:05.258](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [14:09:05.272](0.013s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [14:09:05.272](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [14:09:05.285](0.013s) ok 26 - connect with correct server CA cert file sslmode=verify-full [14:09:05.285](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [14:09:05.299](0.014s) ok 28 - cert root file that contains two certificates, order 1 [14:09:05.299](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [14:09:05.313](0.014s) ok 30 - cert root file that contains two certificates, order 2 [14:09:05.314](0.001s) ok 31 - cert root file that contains two certificates, order 2: no stderr [14:09:05.328](0.014s) ok 32 - connect with sslcertmode=disable [14:09:05.329](0.001s) ok 33 - connect with sslcertmode=disable: no stderr [14:09:05.349](0.020s) ok 34 - connect with sslcertmode=allow [14:09:05.350](0.001s) ok 35 - connect with sslcertmode=allow: no stderr [14:09:05.364](0.014s) ok 36 - connect with sslcertmode=require fails without a client certificate [14:09:05.365](0.001s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [14:09:05.378](0.014s) ok 38 - sslcrl option with invalid file name [14:09:05.379](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [14:09:05.391](0.012s) ok 40 - CRL belonging to a different CA [14:09:05.391](0.000s) ok 41 - CRL belonging to a different CA: matches [14:09:05.402](0.011s) ok 42 - directory CRL belonging to a different CA [14:09:05.403](0.000s) ok 43 - directory CRL belonging to a different CA: matches [14:09:05.416](0.013s) ok 44 - CRL with a non-revoked cert [14:09:05.416](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [14:09:05.431](0.015s) ok 46 - directory CRL with a non-revoked cert [14:09:05.431](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [14:09:05.444](0.013s) ok 48 - mismatch between host name and server certificate sslmode=require [14:09:05.444](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [14:09:05.457](0.013s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [14:09:05.457](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [14:09:05.467](0.010s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [14:09:05.467](0.000s) ok 53 - mismatch between host name and server certificate sslmode=verify-full: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37469 [14:09:05.692](0.225s) ok 54 - IP address in the Common Name [14:09:05.693](0.000s) ok 55 - IP address in the Common Name: no stderr [14:09:05.703](0.011s) ok 56 - mismatch between host name and server certificate IP address [14:09:05.704](0.000s) ok 57 - mismatch between host name and server certificate IP address: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37495 [14:09:05.930](0.226s) ok 58 - IP address in a dNSName [14:09:05.930](0.000s) ok 59 - IP address in a dNSName: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37517 [14:09:06.156](0.226s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [14:09:06.157](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [14:09:06.170](0.013s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [14:09:06.170](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [14:09:06.184](0.014s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [14:09:06.184](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [14:09:06.196](0.012s) ok 66 - host name not matching with X.509 Subject Alternative Names [14:09:06.196](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [14:09:06.209](0.013s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [14:09:06.209](0.000s) ok 69 - host name not matching with X.509 Subject Alternative Names wildcard: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37550 [14:09:06.443](0.233s) ok 70 - host name matching with a single X.509 Subject Alternative Name [14:09:06.443](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [14:09:06.455](0.012s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [14:09:06.456](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [14:09:06.467](0.011s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [14:09:06.467](0.000s) ok 75 - host name not matching with a single X.509 Subject Alternative Name wildcard: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37577 [14:09:06.696](0.229s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [14:09:06.697](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [14:09:06.708](0.011s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [14:09:06.708](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [14:09:06.720](0.011s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [14:09:06.720](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [14:09:06.734](0.014s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [14:09:06.734](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [14:09:06.747](0.013s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [14:09:06.748](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [14:09:06.760](0.012s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [14:09:06.760](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [14:09:06.770](0.010s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [14:09:06.771](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [14:09:06.782](0.011s) ok 90 - IPv6 host with CIDR mask does not match [14:09:06.782](0.000s) ok 91 - IPv6 host with CIDR mask does not match: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37615 [14:09:07.015](0.233s) ok 92 - certificate with both a CN and SANs 1 [14:09:07.015](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [14:09:07.028](0.013s) ok 94 - certificate with both a CN and SANs 2 [14:09:07.028](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [14:09:07.040](0.012s) ok 96 - certificate with both a CN and SANs ignores CN [14:09:07.040](0.000s) ok 97 - certificate with both a CN and SANs ignores CN: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37631 [14:09:07.275](0.235s) ok 98 - certificate with both a CN and IP SANs matches CN [14:09:07.275](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [14:09:07.287](0.011s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [14:09:07.287](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [14:09:07.299](0.012s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [14:09:07.299](0.000s) ok 103 - certificate with both a CN and IP SANs matches SAN 2: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37645 [14:09:07.531](0.232s) ok 104 - certificate with both an IP CN and IP SANs 1 [14:09:07.531](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [14:09:07.542](0.011s) ok 106 - certificate with both an IP CN and IP SANs 2 [14:09:07.542](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [14:09:07.552](0.010s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [14:09:07.553](0.000s) ok 109 - certificate with both an IP CN and IP SANs ignores CN: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37659 [14:09:07.785](0.232s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [14:09:07.785](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [14:09:07.798](0.013s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [14:09:07.798](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [14:09:07.812](0.014s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [14:09:07.812](0.000s) ok 115 - certificate with both an IP CN and DNS SANs matches SAN 2: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37673 [14:09:08.043](0.231s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [14:09:08.043](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [14:09:08.054](0.011s) ok 118 - server certificate without CN or SANs sslmode=verify-full [14:09:08.055](0.000s) ok 119 - server certificate without CN or SANs sslmode=verify-full: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37685 [14:09:08.282](0.227s) ok 120 - sslrootcert=system does not connect with private CA [14:09:08.282](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [14:09:08.288](0.006s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [14:09:08.288](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [14:09:08.304](0.016s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [14:09:08.304](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [14:09:08.314](0.011s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [14:09:08.315](0.000s) ok 127 - sslrootcert=system defaults to sslmode=verify-full: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37700 [14:09:08.543](0.229s) ok 128 - connects without client-side CRL [14:09:08.544](0.000s) ok 129 - connects without client-side CRL: no stderr [14:09:08.556](0.012s) ok 130 - does not connect with client-side CRL file [14:09:08.556](0.000s) ok 131 - does not connect with client-side CRL file: matches [14:09:08.567](0.011s) ok 132 - does not connect with client-side CRL directory [14:09:08.567](0.000s) ok 133 - does not connect with client-side CRL directory: matches # Running: psql -X -A -F , -P null=_null_ -d sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [14:09:08.581](0.013s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [14:09:08.581](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [14:09:08.581](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [14:09:08.594](0.013s) ok 137 - connection success with correct range of TLS protocol versions [14:09:08.594](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [14:09:08.600](0.005s) ok 139 - connection failure with incorrect range of TLS protocol versions [14:09:08.600](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [14:09:08.605](0.005s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [14:09:08.605](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [14:09:08.611](0.006s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [14:09:08.612](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [14:09:08.612](0.000s) # running server tests [14:09:08.624](0.012s) ok 145 - certificate authorization fails without client cert [14:09:08.624](0.000s) ok 146 - certificate authorization fails without client cert: matches [14:09:08.642](0.018s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [14:09:08.643](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [14:09:08.658](0.015s) ok 149 - certificate authorization succeeds with correct client cert in DER format [14:09:08.658](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [14:09:08.674](0.016s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [14:09:08.674](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [14:09:08.689](0.015s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [14:09:08.689](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [14:09:08.704](0.015s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [14:09:08.704](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [14:09:08.718](0.014s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [14:09:08.718](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [14:09:08.730](0.012s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [14:09:08.730](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [14:09:08.740](0.010s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [14:09:08.740](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [14:09:08.757](0.018s) ok 163 - certificate authorization succeeds with DN mapping [14:09:08.758](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [14:09:08.758](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [14:09:08.776](0.018s) ok 166 - certificate authorization succeeds with DN regex mapping [14:09:08.776](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [14:09:08.792](0.016s) ok 168 - certificate authorization succeeds with CN mapping [14:09:08.793](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [14:09:08.793](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [14:09:08.793](0.000s) not ok 171 # TODO & SKIP Need Pty support [14:09:08.793](0.000s) not ok 172 # TODO & SKIP Need Pty support [14:09:08.793](0.000s) not ok 173 # TODO & SKIP Need Pty support [14:09:08.793](0.000s) not ok 174 # TODO & SKIP Need Pty support # Running: psql -X -A -F , -P null=_null_ -d sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_Q7Yd/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [14:09:08.825](0.031s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [14:09:08.825](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [14:09:08.825](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [14:09:08.835](0.010s) ok 178 - certificate authorization fails because of file permissions [14:09:08.835](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [14:09:08.849](0.013s) ok 180 - certificate authorization fails with client cert belonging to another user [14:09:08.849](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [14:09:08.862](0.013s) ok 182 - certificate authorization fails with revoked client cert [14:09:08.862](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [14:09:08.862](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [14:09:08.880](0.018s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [14:09:08.880](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [14:09:08.881](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [14:09:08.893](0.013s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [14:09:08.893](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [14:09:08.894](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [14:09:08.908](0.014s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [14:09:08.908](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [14:09:08.908](0.000s) ok 193 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: log matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37760 [14:09:09.137](0.229s) ok 194 - intermediate client certificate is provided by client [14:09:09.137](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [14:09:09.149](0.012s) ok 196 - intermediate client certificate is missing [14:09:09.150](0.000s) ok 197 - intermediate client certificate is missing: matches [14:09:09.161](0.012s) ok 198 - logged client certificate Subjects are truncated if they're too long [14:09:09.161](0.000s) ok 199 - logged client certificate Subjects are truncated if they're too long: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37774 [14:09:09.389](0.228s) ok 200 - intermediate client certificate is untrusted [14:09:09.389](0.000s) ok 201 - intermediate client certificate is untrusted: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37784 [14:09:09.618](0.228s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [14:09:09.618](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [14:09:09.629](0.011s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [14:09:09.629](0.000s) ok 205 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37796 [14:09:09.854](0.225s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [14:09:09.854](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 923. [14:09:09.854](0.000s) # got: '2' # expected: '0' [14:09:09.855](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [14:09:09.855](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 923. [14:09:09.855](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 59400 failed: SSL SYSCALL error: EOF detected' # expected: '' [14:09:09.869](0.014s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [14:09:09.869](0.000s) ok 209 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37808 [14:09:10.090](0.221s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [14:09:10.106](0.016s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [14:09:10.106](0.000s) ok 212 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37820 [14:09:10.331](0.225s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [14:09:10.347](0.016s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [14:09:10.347](0.000s) ok 215 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37832 [14:09:10.573](0.226s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [14:09:10.591](0.017s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [14:09:10.591](0.000s) ok 218 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37844 [14:09:10.817](0.226s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [14:09:10.817](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 1001. [14:09:10.817](0.000s) # got: '2' # expected: '0' [14:09:10.818](0.001s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [14:09:10.818](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 1001. [14:09:10.818](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 59400 failed: SSL SYSCALL error: EOF detected' # expected: '' [14:09:10.835](0.017s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [14:09:10.835](0.000s) ok 222 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37856 [14:09:11.061](0.226s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [14:09:11.076](0.016s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [14:09:11.077](0.000s) ok 225 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37868 [14:09:11.303](0.226s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [14:09:11.320](0.017s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [14:09:11.320](0.000s) ok 228 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37880 [14:09:11.547](0.227s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [14:09:11.563](0.016s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [14:09:11.563](0.000s) ok 231 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 37892 [14:09:11.789](0.225s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [14:09:11.805](0.017s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [14:09:11.806](0.000s) ok 234 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... stopped waiting pg_ctl: could not start server Examine the log output. # pg_ctl restart failed; logfile: 2024-03-14 14:09:03.482 UTC [36882][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:03.482 UTC [36882][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:03.491 UTC [36887][startup] LOG: database system was shut down at 2024-03-14 14:06:49 UTC 2024-03-14 14:09:03.493 UTC [36882][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:03.598 UTC [36929][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.598 UTC [36929][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.598 UTC [36929][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.602 UTC [36929][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-14 14:09:03.603 UTC [36929][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.612 UTC [36935][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.613 UTC [36935][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.613 UTC [36935][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.613 UTC [36935][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-14 14:09:03.613 UTC [36935][client backend] [001_ssltests.pl][1/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-14 14:09:03.614 UTC [36935][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.658 UTC [36953][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.658 UTC [36953][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.658 UTC [36953][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.659 UTC [36953][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-14 14:09:03.659 UTC [36953][client backend] [001_ssltests.pl][2/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-14 14:09:03.664 UTC [36953][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.709 UTC [36965][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.710 UTC [36965][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.710 UTC [36965][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.710 UTC [36965][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-14 14:09:03.710 UTC [36965][client backend] [001_ssltests.pl][3/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-14 14:09:03.711 UTC [36965][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.754 UTC [36986][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.755 UTC [36986][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.755 UTC [36986][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.755 UTC [36986][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-14 14:09:03.755 UTC [36986][client backend] [001_ssltests.pl][4/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-14 14:09:03.756 UTC [36986][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.799 UTC [36998][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.806 UTC [36998][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.806 UTC [36998][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.806 UTC [36998][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-14 14:09:03.806 UTC [36998][client backend] [001_ssltests.pl][5/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-14 14:09:03.827 UTC [36998][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.028 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.850 UTC [37016][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.850 UTC [37016][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.850 UTC [37016][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.851 UTC [37016][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-14 14:09:03.851 UTC [37016][client backend] [001_ssltests.pl][6/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-14 14:09:03.893 UTC [37016][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.042 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.908 UTC [37030][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.908 UTC [37030][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.908 UTC [37030][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.912 UTC [37030][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-14 14:09:03.912 UTC [37030][client backend] [001_ssltests.pl][7/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-14 14:09:03.936 UTC [37030][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.027 user=postgres database=postgres host=[local] 2024-03-14 14:09:03.962 UTC [37049][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:03.963 UTC [37049][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:03.963 UTC [37049][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:03.964 UTC [37049][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-14 14:09:03.964 UTC [37049][client backend] [001_ssltests.pl][8/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-14 14:09:03.998 UTC [37049][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.036 user=postgres database=postgres host=[local] 2024-03-14 14:09:04.008 UTC [37075][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:04.009 UTC [37075][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:04.009 UTC [37075][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:04.010 UTC [37075][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-14 14:09:04.010 UTC [37075][client backend] [001_ssltests.pl][9/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-14 14:09:04.027 UTC [37075][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=postgres database=postgres host=[local] 2024-03-14 14:09:04.070 UTC [37086][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-14 14:09:04.071 UTC [37086][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-14 14:09:04.071 UTC [37086][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-14 14:09:04.077 UTC [37086][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-14 14:09:04.077 UTC [37086][client backend] [001_ssltests.pl][10/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-14 14:09:04.104 UTC [37086][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.034 user=postgres database=postgres host=[local] 2024-03-14 14:09:04.112 UTC [36882][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:04.112 UTC [36882][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:04.114 UTC [36882][postmaster] LOG: background worker "logical replication launcher" (PID 36895) exited with exit code 1 2024-03-14 14:09:04.115 UTC [36885][checkpointer] LOG: shutting down 2024-03-14 14:09:04.115 UTC [36885][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:04.165 UTC [36885][checkpointer] LOG: checkpoint complete: wrote 5614 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.049 s, sync=0.001 s, total=0.051 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45190 kB, estimate=45190 kB; lsn=0/413E788, redo lsn=0/413E788 2024-03-14 14:09:04.172 UTC [36882][postmaster] LOG: database system is shut down 2024-03-14 14:09:04.226 UTC [37127][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:04.226 UTC [37127][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:04.226 UTC [37127][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:04.228 UTC [37135][startup] LOG: database system was shut down at 2024-03-14 14:09:04 UTC 2024-03-14 14:09:04.231 UTC [37127][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:04.319 UTC [37127][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:04.319 UTC [37127][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:04.320 UTC [37127][postmaster] LOG: background worker "logical replication launcher" (PID 37138) exited with exit code 1 2024-03-14 14:09:04.321 UTC [37133][checkpointer] LOG: shutting down 2024-03-14 14:09:04.321 UTC [37133][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:04.321 UTC [37133][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413E800, redo lsn=0/413E800 2024-03-14 14:09:04.322 UTC [37127][postmaster] LOG: database system is shut down 2024-03-14 14:09:04.429 UTC [37185][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-14 14:09:04.429 UTC [37185][postmaster] LOG: database system is shut down 2024-03-14 14:09:04.541 UTC [37208][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:04.541 UTC [37208][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:04.541 UTC [37208][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:04.544 UTC [37221][startup] LOG: database system was shut down at 2024-03-14 14:09:04 UTC 2024-03-14 14:09:04.548 UTC [37208][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:04.633 UTC [37208][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:04.633 UTC [37208][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:04.634 UTC [37208][postmaster] LOG: background worker "logical replication launcher" (PID 37228) exited with exit code 1 2024-03-14 14:09:04.635 UTC [37219][checkpointer] LOG: shutting down 2024-03-14 14:09:04.635 UTC [37219][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:04.635 UTC [37219][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413E878, redo lsn=0/413E878 2024-03-14 14:09:04.636 UTC [37208][postmaster] LOG: database system is shut down 2024-03-14 14:09:04.749 UTC [37256][postmaster] FATAL: could not set SSL protocol version range 2024-03-14 14:09:04.749 UTC [37256][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-14 14:09:04.749 UTC [37256][postmaster] LOG: database system is shut down 2024-03-14 14:09:04.856 UTC [37283][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:04.856 UTC [37283][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:04.856 UTC [37283][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:04.859 UTC [37289][startup] LOG: database system was shut down at 2024-03-14 14:09:04 UTC 2024-03-14 14:09:04.861 UTC [37283][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:04.948 UTC [37283][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:04.948 UTC [37283][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:04.950 UTC [37283][postmaster] LOG: background worker "logical replication launcher" (PID 37293) exited with exit code 1 2024-03-14 14:09:04.950 UTC [37287][checkpointer] LOG: shutting down 2024-03-14 14:09:04.950 UTC [37287][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:04.950 UTC [37287][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413E8F0, redo lsn=0/413E8F0 2024-03-14 14:09:04.951 UTC [37283][postmaster] LOG: database system is shut down 2024-03-14 14:09:05.066 UTC [37338][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:05.066 UTC [37338][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:05.066 UTC [37338][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:05.068 UTC [37348][startup] LOG: database system was shut down at 2024-03-14 14:09:04 UTC 2024-03-14 14:09:05.071 UTC [37338][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:05.159 UTC [37374][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34849 2024-03-14 14:09:05.160 UTC [37374][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-14 14:09:05.160 UTC [37374][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-03-14 14:09:05.169 UTC [37377][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34851 2024-03-14 14:09:05.174 UTC [37377][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:05.174 UTC [37377][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-14 14:09:05.179 UTC [37377][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-14 14:09:05.179 UTC [37377][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=34851 2024-03-14 14:09:05.187 UTC [37383][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34853 2024-03-14 14:09:05.188 UTC [37383][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-14 14:09:05.196 UTC [37386][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34855 2024-03-14 14:09:05.198 UTC [37386][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-14 14:09:05.205 UTC [37389][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34856 2024-03-14 14:09:05.208 UTC [37389][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-14 14:09:05.217 UTC [37391][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34857 2024-03-14 14:09:05.221 UTC [37391][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-14 14:09:05.229 UTC [37393][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34858 2024-03-14 14:09:05.232 UTC [37393][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-14 14:09:05.240 UTC [37396][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34859 2024-03-14 14:09:05.244 UTC [37396][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-14 14:09:05.252 UTC [37404][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34860 2024-03-14 14:09:05.256 UTC [37404][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:05.256 UTC [37404][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-14 14:09:05.257 UTC [37404][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-14 14:09:05.257 UTC [37404][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=34860 2024-03-14 14:09:05.264 UTC [37406][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34861 2024-03-14 14:09:05.269 UTC [37406][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:05.269 UTC [37406][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-14 14:09:05.270 UTC [37406][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-14 14:09:05.270 UTC [37406][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=34861 2024-03-14 14:09:05.277 UTC [37410][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34862 2024-03-14 14:09:05.282 UTC [37410][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:05.282 UTC [37410][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-14 14:09:05.283 UTC [37410][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-14 14:09:05.283 UTC [37410][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=34862 2024-03-14 14:09:05.291 UTC [37412][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34863 2024-03-14 14:09:05.296 UTC [37412][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:05.296 UTC [37412][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-14 14:09:05.297 UTC [37412][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-14 14:09:05.297 UTC [37412][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=34863 2024-03-14 14:09:05.306 UTC [37414][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34864 2024-03-14 14:09:05.311 UTC [37414][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:05.311 UTC [37414][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-14 14:09:05.311 UTC [37414][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-14 14:09:05.312 UTC [37414][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=34864 2024-03-14 14:09:05.320 UTC [37416][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34865 2024-03-14 14:09:05.325 UTC [37416][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:05.325 UTC [37416][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-14 14:09:05.326 UTC [37416][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-14 14:09:05.331 UTC [37416][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=34865 2024-03-14 14:09:05.341 UTC [37419][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34866 2024-03-14 14:09:05.346 UTC [37419][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:05.346 UTC [37419][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-14 14:09:05.347 UTC [37419][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-14 14:09:05.349 UTC [37419][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=34866 2024-03-14 14:09:05.357 UTC [37423][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34868 2024-03-14 14:09:05.362 UTC [37423][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:05.362 UTC [37423][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-14 14:09:05.363 UTC [37423][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=34868 2024-03-14 14:09:05.371 UTC [37427][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34870 2024-03-14 14:09:05.376 UTC [37427][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:05.376 UTC [37427][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-14 14:09:05.377 UTC [37427][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-14 14:09:05.377 UTC [37427][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=34870 2024-03-14 14:09:05.385 UTC [37429][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34871 2024-03-14 14:09:05.389 UTC [37429][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-14 14:09:05.397 UTC [37431][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34872 2024-03-14 14:09:05.401 UTC [37431][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-14 14:09:05.409 UTC [37433][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34873 2024-03-14 14:09:05.414 UTC [37433][client backend] [[unknown]][11/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:05.414 UTC [37433][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-14 14:09:05.414 UTC [37433][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-14 14:09:05.415 UTC [37433][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=34873 2024-03-14 14:09:05.423 UTC [37435][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34874 2024-03-14 14:09:05.428 UTC [37435][client backend] [[unknown]][12/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:05.428 UTC [37435][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-14 14:09:05.428 UTC [37435][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-14 14:09:05.429 UTC [37435][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=34874 2024-03-14 14:09:05.437 UTC [37439][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34875 2024-03-14 14:09:05.441 UTC [37439][client backend] [[unknown]][13/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:05.441 UTC [37439][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-14 14:09:05.442 UTC [37439][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-14 14:09:05.442 UTC [37439][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=34875 2024-03-14 14:09:05.450 UTC [37442][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34876 2024-03-14 14:09:05.455 UTC [37442][client backend] [[unknown]][14/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:05.455 UTC [37442][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-14 14:09:05.455 UTC [37442][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-14 14:09:05.456 UTC [37442][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=34876 2024-03-14 14:09:05.463 UTC [37444][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34877 2024-03-14 14:09:05.469 UTC [37338][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:05.469 UTC [37338][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:05.471 UTC [37338][postmaster] LOG: background worker "logical replication launcher" (PID 37352) exited with exit code 1 2024-03-14 14:09:05.472 UTC [37346][checkpointer] LOG: shutting down 2024-03-14 14:09:05.472 UTC [37346][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:05.473 UTC [37346][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413E968, redo lsn=0/413E968 2024-03-14 14:09:05.474 UTC [37338][postmaster] LOG: database system is shut down 2024-03-14 14:09:05.587 UTC [37469][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:05.587 UTC [37469][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:05.587 UTC [37469][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:05.589 UTC [37473][startup] LOG: database system was shut down at 2024-03-14 14:09:05 UTC 2024-03-14 14:09:05.593 UTC [37469][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:05.682 UTC [37480][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34880 2024-03-14 14:09:05.686 UTC [37480][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:05.686 UTC [37480][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-14 14:09:05.691 UTC [37480][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-14 14:09:05.691 UTC [37480][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=34880 2024-03-14 14:09:05.698 UTC [37486][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34881 2024-03-14 14:09:05.705 UTC [37469][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:05.705 UTC [37469][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:05.706 UTC [37469][postmaster] LOG: background worker "logical replication launcher" (PID 37476) exited with exit code 1 2024-03-14 14:09:05.707 UTC [37471][checkpointer] LOG: shutting down 2024-03-14 14:09:05.707 UTC [37471][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:05.707 UTC [37471][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413E9E0, redo lsn=0/413E9E0 2024-03-14 14:09:05.708 UTC [37469][postmaster] LOG: database system is shut down 2024-03-14 14:09:05.816 UTC [37495][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:05.816 UTC [37495][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:05.816 UTC [37495][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:05.818 UTC [37499][startup] LOG: database system was shut down at 2024-03-14 14:09:05 UTC 2024-03-14 14:09:05.820 UTC [37495][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:05.918 UTC [37505][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34883 2024-03-14 14:09:05.923 UTC [37505][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:05.923 UTC [37505][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-14 14:09:05.927 UTC [37505][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-14 14:09:05.928 UTC [37505][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=34883 2024-03-14 14:09:05.932 UTC [37495][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:05.932 UTC [37495][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:05.933 UTC [37495][postmaster] LOG: background worker "logical replication launcher" (PID 37502) exited with exit code 1 2024-03-14 14:09:05.933 UTC [37497][checkpointer] LOG: shutting down 2024-03-14 14:09:05.933 UTC [37497][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:05.934 UTC [37497][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413EA58, redo lsn=0/413EA58 2024-03-14 14:09:05.935 UTC [37495][postmaster] LOG: database system is shut down 2024-03-14 14:09:06.044 UTC [37517][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:06.044 UTC [37517][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:06.044 UTC [37517][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:06.046 UTC [37521][startup] LOG: database system was shut down at 2024-03-14 14:09:05 UTC 2024-03-14 14:09:06.049 UTC [37517][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:06.146 UTC [37528][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34885 2024-03-14 14:09:06.150 UTC [37528][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:06.150 UTC [37528][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-14 14:09:06.154 UTC [37528][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-14 14:09:06.155 UTC [37528][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=34885 2024-03-14 14:09:06.163 UTC [37537][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34886 2024-03-14 14:09:06.168 UTC [37537][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:06.168 UTC [37537][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-14 14:09:06.168 UTC [37537][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-14 14:09:06.169 UTC [37537][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=34886 2024-03-14 14:09:06.177 UTC [37539][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34887 2024-03-14 14:09:06.182 UTC [37539][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:06.182 UTC [37539][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-14 14:09:06.182 UTC [37539][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-14 14:09:06.183 UTC [37539][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=34887 2024-03-14 14:09:06.191 UTC [37541][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34888 2024-03-14 14:09:06.203 UTC [37543][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34889 2024-03-14 14:09:06.212 UTC [37517][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:06.212 UTC [37517][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:06.214 UTC [37517][postmaster] LOG: background worker "logical replication launcher" (PID 37524) exited with exit code 1 2024-03-14 14:09:06.214 UTC [37519][checkpointer] LOG: shutting down 2024-03-14 14:09:06.214 UTC [37519][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:06.215 UTC [37519][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413EAD0, redo lsn=0/413EAD0 2024-03-14 14:09:06.217 UTC [37517][postmaster] LOG: database system is shut down 2024-03-14 14:09:06.330 UTC [37550][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:06.330 UTC [37550][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:06.330 UTC [37550][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:06.332 UTC [37553][startup] LOG: database system was shut down at 2024-03-14 14:09:06 UTC 2024-03-14 14:09:06.336 UTC [37550][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:06.432 UTC [37565][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34891 2024-03-14 14:09:06.437 UTC [37565][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:06.437 UTC [37565][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-14 14:09:06.441 UTC [37565][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=single.alt-name.pg-ssltest.test$$ 2024-03-14 14:09:06.441 UTC [37565][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=34891 2024-03-14 14:09:06.450 UTC [37567][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34892 2024-03-14 14:09:06.462 UTC [37570][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34893 2024-03-14 14:09:06.472 UTC [37550][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:06.472 UTC [37550][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:06.474 UTC [37550][postmaster] LOG: background worker "logical replication launcher" (PID 37556) exited with exit code 1 2024-03-14 14:09:06.474 UTC [37551][checkpointer] LOG: shutting down 2024-03-14 14:09:06.474 UTC [37551][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:06.475 UTC [37551][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413EB48, redo lsn=0/413EB48 2024-03-14 14:09:06.476 UTC [37550][postmaster] LOG: database system is shut down 2024-03-14 14:09:06.590 UTC [37577][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:06.590 UTC [37577][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:06.590 UTC [37577][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:06.591 UTC [37580][startup] LOG: database system was shut down at 2024-03-14 14:09:06 UTC 2024-03-14 14:09:06.594 UTC [37577][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:06.686 UTC [37594][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34895 2024-03-14 14:09:06.690 UTC [37594][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:06.690 UTC [37594][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-14 14:09:06.695 UTC [37594][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-14 14:09:06.695 UTC [37594][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=34895 2024-03-14 14:09:06.702 UTC [37596][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34896 2024-03-14 14:09:06.706 UTC [37596][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:06.706 UTC [37596][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-14 14:09:06.706 UTC [37596][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.000.002.001$$ 2024-03-14 14:09:06.707 UTC [37596][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=34896 2024-03-14 14:09:06.714 UTC [37600][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34898 2024-03-14 14:09:06.726 UTC [37603][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34899 2024-03-14 14:09:06.731 UTC [37603][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:06.731 UTC [37603][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-14 14:09:06.732 UTC [37603][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:DB8::1$$ 2024-03-14 14:09:06.732 UTC [37603][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=34899 2024-03-14 14:09:06.740 UTC [37605][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34900 2024-03-14 14:09:06.745 UTC [37605][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:06.745 UTC [37605][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-14 14:09:06.746 UTC [37605][client backend] [001_ssltests.pl][3/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8:0:0:0:0:0:1$$ 2024-03-14 14:09:06.746 UTC [37605][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=34900 2024-03-14 14:09:06.753 UTC [37607][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34901 2024-03-14 14:09:06.758 UTC [37607][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:06.758 UTC [37607][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-14 14:09:06.758 UTC [37607][client backend] [001_ssltests.pl][4/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::0.0.0.1$$ 2024-03-14 14:09:06.759 UTC [37607][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=34901 2024-03-14 14:09:06.765 UTC [37609][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34902 2024-03-14 14:09:06.777 UTC [37611][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34903 2024-03-14 14:09:06.784 UTC [37577][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:06.784 UTC [37577][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:06.785 UTC [37577][postmaster] LOG: background worker "logical replication launcher" (PID 37583) exited with exit code 1 2024-03-14 14:09:06.785 UTC [37578][checkpointer] LOG: shutting down 2024-03-14 14:09:06.785 UTC [37578][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:06.786 UTC [37578][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413EBC0, redo lsn=0/413EBC0 2024-03-14 14:09:06.787 UTC [37577][postmaster] LOG: database system is shut down 2024-03-14 14:09:06.901 UTC [37615][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:06.901 UTC [37615][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:06.901 UTC [37615][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:06.903 UTC [37618][startup] LOG: database system was shut down at 2024-03-14 14:09:06 UTC 2024-03-14 14:09:06.906 UTC [37615][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:07.004 UTC [37624][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34904 2024-03-14 14:09:07.009 UTC [37624][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:07.009 UTC [37624][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-14 14:09:07.013 UTC [37624][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-14 14:09:07.013 UTC [37624][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=34904 2024-03-14 14:09:07.021 UTC [37626][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34905 2024-03-14 14:09:07.026 UTC [37626][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:07.026 UTC [37626][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-14 14:09:07.026 UTC [37626][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-14 14:09:07.027 UTC [37626][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=34905 2024-03-14 14:09:07.034 UTC [37628][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34906 2024-03-14 14:09:07.046 UTC [37615][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:07.046 UTC [37615][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:07.047 UTC [37615][postmaster] LOG: background worker "logical replication launcher" (PID 37621) exited with exit code 1 2024-03-14 14:09:07.047 UTC [37616][checkpointer] LOG: shutting down 2024-03-14 14:09:07.047 UTC [37616][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:07.047 UTC [37616][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413EC38, redo lsn=0/413EC38 2024-03-14 14:09:07.049 UTC [37615][postmaster] LOG: database system is shut down 2024-03-14 14:09:07.164 UTC [37631][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:07.164 UTC [37631][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:07.164 UTC [37631][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:07.166 UTC [37634][startup] LOG: database system was shut down at 2024-03-14 14:09:07 UTC 2024-03-14 14:09:07.169 UTC [37631][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:07.265 UTC [37639][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34907 2024-03-14 14:09:07.269 UTC [37639][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:07.269 UTC [37639][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-14 14:09:07.274 UTC [37639][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$ 2024-03-14 14:09:07.274 UTC [37639][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=34907 2024-03-14 14:09:07.281 UTC [37641][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34908 2024-03-14 14:09:07.285 UTC [37641][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:07.285 UTC [37641][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-14 14:09:07.285 UTC [37641][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-14 14:09:07.286 UTC [37641][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=34908 2024-03-14 14:09:07.292 UTC [37643][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34909 2024-03-14 14:09:07.297 UTC [37643][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:07.297 UTC [37643][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-14 14:09:07.297 UTC [37643][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$ 2024-03-14 14:09:07.298 UTC [37643][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=34909 2024-03-14 14:09:07.301 UTC [37631][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:07.301 UTC [37631][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:07.302 UTC [37631][postmaster] LOG: background worker "logical replication launcher" (PID 37637) exited with exit code 1 2024-03-14 14:09:07.302 UTC [37632][checkpointer] LOG: shutting down 2024-03-14 14:09:07.302 UTC [37632][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:07.302 UTC [37632][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413ECB0, redo lsn=0/413ECB0 2024-03-14 14:09:07.304 UTC [37631][postmaster] LOG: database system is shut down 2024-03-14 14:09:07.418 UTC [37645][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:07.418 UTC [37645][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:07.418 UTC [37645][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:07.419 UTC [37648][startup] LOG: database system was shut down at 2024-03-14 14:09:07 UTC 2024-03-14 14:09:07.422 UTC [37645][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:07.520 UTC [37653][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34910 2024-03-14 14:09:07.524 UTC [37653][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:07.524 UTC [37653][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-14 14:09:07.529 UTC [37653][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.2$$ 2024-03-14 14:09:07.529 UTC [37653][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=34910 2024-03-14 14:09:07.536 UTC [37655][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34911 2024-03-14 14:09:07.540 UTC [37655][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:07.540 UTC [37655][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-14 14:09:07.541 UTC [37655][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$ 2024-03-14 14:09:07.541 UTC [37655][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=34911 2024-03-14 14:09:07.548 UTC [37657][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34912 2024-03-14 14:09:07.554 UTC [37645][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:07.554 UTC [37645][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:07.555 UTC [37645][postmaster] LOG: background worker "logical replication launcher" (PID 37651) exited with exit code 1 2024-03-14 14:09:07.555 UTC [37646][checkpointer] LOG: shutting down 2024-03-14 14:09:07.556 UTC [37646][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:07.556 UTC [37646][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413ED28, redo lsn=0/413ED28 2024-03-14 14:09:07.557 UTC [37645][postmaster] LOG: database system is shut down 2024-03-14 14:09:07.672 UTC [37659][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:07.672 UTC [37659][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:07.672 UTC [37659][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:07.674 UTC [37662][startup] LOG: database system was shut down at 2024-03-14 14:09:07 UTC 2024-03-14 14:09:07.677 UTC [37659][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:07.774 UTC [37667][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34913 2024-03-14 14:09:07.778 UTC [37667][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:07.778 UTC [37667][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-14 14:09:07.783 UTC [37667][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-14 14:09:07.783 UTC [37667][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=34913 2024-03-14 14:09:07.791 UTC [37669][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34914 2024-03-14 14:09:07.796 UTC [37669][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:07.796 UTC [37669][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-14 14:09:07.797 UTC [37669][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-14 14:09:07.797 UTC [37669][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=34914 2024-03-14 14:09:07.805 UTC [37671][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34915 2024-03-14 14:09:07.810 UTC [37671][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:07.810 UTC [37671][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-14 14:09:07.811 UTC [37671][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-14 14:09:07.811 UTC [37671][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=34915 2024-03-14 14:09:07.815 UTC [37659][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:07.815 UTC [37659][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:07.816 UTC [37659][postmaster] LOG: background worker "logical replication launcher" (PID 37665) exited with exit code 1 2024-03-14 14:09:07.816 UTC [37660][checkpointer] LOG: shutting down 2024-03-14 14:09:07.816 UTC [37660][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:07.817 UTC [37660][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413EDA0, redo lsn=0/413EDA0 2024-03-14 14:09:07.818 UTC [37659][postmaster] LOG: database system is shut down 2024-03-14 14:09:07.931 UTC [37673][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:07.931 UTC [37673][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:07.931 UTC [37673][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:07.933 UTC [37676][startup] LOG: database system was shut down at 2024-03-14 14:09:07 UTC 2024-03-14 14:09:07.936 UTC [37673][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:08.032 UTC [37681][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34916 2024-03-14 14:09:08.037 UTC [37681][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:08.037 UTC [37681][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-14 14:09:08.041 UTC [37681][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-14 14:09:08.042 UTC [37681][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=34916 2024-03-14 14:09:08.049 UTC [37683][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34917 2024-03-14 14:09:08.057 UTC [37673][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:08.057 UTC [37673][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:08.058 UTC [37673][postmaster] LOG: background worker "logical replication launcher" (PID 37679) exited with exit code 1 2024-03-14 14:09:08.058 UTC [37674][checkpointer] LOG: shutting down 2024-03-14 14:09:08.058 UTC [37674][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:08.058 UTC [37674][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413EE18, redo lsn=0/413EE18 2024-03-14 14:09:08.060 UTC [37673][postmaster] LOG: database system is shut down 2024-03-14 14:09:08.174 UTC [37685][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:08.175 UTC [37685][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:08.175 UTC [37685][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:08.176 UTC [37688][startup] LOG: database system was shut down at 2024-03-14 14:09:08 UTC 2024-03-14 14:09:08.179 UTC [37685][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:08.277 UTC [37693][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34918 2024-03-14 14:09:08.281 UTC [37693][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-14 14:09:08.293 UTC [37696][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34919 2024-03-14 14:09:08.298 UTC [37696][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:08.298 UTC [37696][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-14 14:09:08.302 UTC [37696][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-14 14:09:08.302 UTC [37696][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=34919 2024-03-14 14:09:08.309 UTC [37698][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34920 2024-03-14 14:09:08.316 UTC [37685][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:08.316 UTC [37685][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:08.317 UTC [37685][postmaster] LOG: background worker "logical replication launcher" (PID 37691) exited with exit code 1 2024-03-14 14:09:08.318 UTC [37686][checkpointer] LOG: shutting down 2024-03-14 14:09:08.318 UTC [37686][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:08.318 UTC [37686][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413EE90, redo lsn=0/413EE90 2024-03-14 14:09:08.319 UTC [37685][postmaster] LOG: database system is shut down 2024-03-14 14:09:08.433 UTC [37700][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:08.433 UTC [37700][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:08.433 UTC [37700][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:08.435 UTC [37703][startup] LOG: database system was shut down at 2024-03-14 14:09:08 UTC 2024-03-14 14:09:08.438 UTC [37700][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:08.533 UTC [37708][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34921 2024-03-14 14:09:08.537 UTC [37708][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:08.538 UTC [37708][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-14 14:09:08.542 UTC [37708][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-14 14:09:08.542 UTC [37708][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=34921 2024-03-14 14:09:08.550 UTC [37710][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34922 2024-03-14 14:09:08.554 UTC [37710][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-14 14:09:08.562 UTC [37712][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34923 2024-03-14 14:09:08.566 UTC [37712][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-14 14:09:08.574 UTC [37714][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34924 2024-03-14 14:09:08.578 UTC [37714][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:08.578 UTC [37714][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-14 14:09:08.578 UTC [37714][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-14 14:09:08.580 UTC [37714][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=34924 2024-03-14 14:09:08.588 UTC [37716][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34925 2024-03-14 14:09:08.592 UTC [37716][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:08.592 UTC [37716][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-14 14:09:08.592 UTC [37716][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-14 14:09:08.593 UTC [37716][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=34925 2024-03-14 14:09:08.618 UTC [37721][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34926 2024-03-14 14:09:08.623 UTC [37721][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-03-14 14:09:08.631 UTC [37723][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34927 2024-03-14 14:09:08.637 UTC [37723][client backend] [[unknown]][4/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-14 14:09:08.637 UTC [37723][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-14 14:09:08.641 UTC [37723][client backend] [001_ssltests.pl][4/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_Q7Yd/client.key$$ 2024-03-14 14:09:08.642 UTC [37723][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=34927 2024-03-14 14:09:08.649 UTC [37725][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34928 2024-03-14 14:09:08.655 UTC [37725][client backend] [[unknown]][5/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-14 14:09:08.655 UTC [37725][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-14 14:09:08.656 UTC [37725][client backend] [001_ssltests.pl][5/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_Q7Yd/client-der.key$$ 2024-03-14 14:09:08.657 UTC [37725][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=34928 2024-03-14 14:09:08.664 UTC [37727][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34929 2024-03-14 14:09:08.672 UTC [37727][client backend] [[unknown]][6/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-14 14:09:08.672 UTC [37727][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-14 14:09:08.672 UTC [37727][client backend] [001_ssltests.pl][6/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_Q7Yd/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-03-14 14:09:08.673 UTC [37727][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=34929 2024-03-14 14:09:08.680 UTC [37729][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34930 2024-03-14 14:09:08.686 UTC [37729][client backend] [[unknown]][7/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-14 14:09:08.686 UTC [37729][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-14 14:09:08.687 UTC [37729][client backend] [001_ssltests.pl][7/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_Q7Yd/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-03-14 14:09:08.688 UTC [37729][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=34930 2024-03-14 14:09:08.696 UTC [37731][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34931 2024-03-14 14:09:08.701 UTC [37731][client backend] [[unknown]][8/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-14 14:09:08.701 UTC [37731][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-14 14:09:08.702 UTC [37731][client backend] [001_ssltests.pl][8/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcertmode=require sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_Q7Yd/client.key$$ 2024-03-14 14:09:08.703 UTC [37731][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=34931 2024-03-14 14:09:08.710 UTC [37733][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34932 2024-03-14 14:09:08.716 UTC [37733][client backend] [[unknown]][9/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-14 14:09:08.716 UTC [37733][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-14 14:09:08.716 UTC [37733][client backend] [001_ssltests.pl][9/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcertmode=allow sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_Q7Yd/client.key$$ 2024-03-14 14:09:08.717 UTC [37733][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=34932 2024-03-14 14:09:08.724 UTC [37735][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34933 2024-03-14 14:09:08.728 UTC [37735][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-03-14 14:09:08.736 UTC [37737][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34934 2024-03-14 14:09:08.738 UTC [37737][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-14 14:09:08.745 UTC [37739][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34935 2024-03-14 14:09:08.751 UTC [37739][client backend] [[unknown]][11/1:0] LOG: connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:8) 2024-03-14 14:09:08.752 UTC [37739][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-14 14:09:08.756 UTC [37739][client backend] [001_ssltests.pl][11/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost dbname=certdb_dn user=ssltestuser sslcert=ssl/client-dn.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_Q7Yd/client-dn.key$$ 2024-03-14 14:09:08.756 UTC [37739][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_dn host=localhost port=34935 2024-03-14 14:09:08.764 UTC [37741][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34936 2024-03-14 14:09:08.770 UTC [37741][client backend] [[unknown]][12/1:0] LOG: connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:9) 2024-03-14 14:09:08.770 UTC [37741][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-14 14:09:08.774 UTC [37741][client backend] [001_ssltests.pl][12/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost dbname=certdb_dn_re user=ssltestuser sslcert=ssl/client-dn.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_Q7Yd/client-dn.key$$ 2024-03-14 14:09:08.775 UTC [37741][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_dn_re host=localhost port=34936 2024-03-14 14:09:08.782 UTC [37743][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34937 2024-03-14 14:09:08.787 UTC [37743][client backend] [[unknown]][13/1:0] LOG: connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:10) 2024-03-14 14:09:08.787 UTC [37743][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-14 14:09:08.791 UTC [37743][client backend] [001_ssltests.pl][13/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=certdb hostaddr=127.0.0.1 host=localhost dbname=certdb_cn user=ssltestuser sslcert=ssl/client-dn.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_Q7Yd/client-dn.key$$ 2024-03-14 14:09:08.792 UTC [37743][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_cn host=localhost port=34937 2024-03-14 14:09:08.816 UTC [37746][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34938 2024-03-14 14:09:08.821 UTC [37746][client backend] [[unknown]][14/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-14 14:09:08.821 UTC [37746][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-14 14:09:08.822 UTC [37746][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-14 14:09:08.824 UTC [37746][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=34938 2024-03-14 14:09:08.832 UTC [37748][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34939 2024-03-14 14:09:08.834 UTC [37748][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-14 14:09:08.841 UTC [37750][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34940 2024-03-14 14:09:08.847 UTC [37750][client backend] [[unknown]][15/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-14 14:09:08.847 UTC [37750][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-14 14:09:08.847 UTC [37750][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-03-14 14:09:08.847 UTC [37750][client backend] [[unknown]][15/1:0] DETAIL: Connection matched file "/tmp/cirrus-ci-build/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-14 14:09:08.855 UTC [37752][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34941 2024-03-14 14:09:08.860 UTC [37752][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-14 14:09:08.860 UTC [37752][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-14 14:09:08.869 UTC [37754][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34942 2024-03-14 14:09:08.874 UTC [37754][client backend] [[unknown]][16/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:4) 2024-03-14 14:09:08.874 UTC [37754][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-14 14:09:08.878 UTC [37754][client backend] [001_ssltests.pl][16/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=verifydb hostaddr=127.0.0.1 host=localhost user=ssltestuser sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_Q7Yd/client.key$$ 2024-03-14 14:09:08.879 UTC [37754][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=verifydb host=localhost port=34942 2024-03-14 14:09:08.887 UTC [37756][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34943 2024-03-14 14:09:08.892 UTC [37756][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-14 14:09:08.892 UTC [37756][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-03-14 14:09:08.892 UTC [37756][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-03-14 14:09:08.892 UTC [37756][client backend] [[unknown]][17/1:0] DETAIL: Connection matched file "/tmp/cirrus-ci-build/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-14 14:09:08.900 UTC [37758][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34944 2024-03-14 14:09:08.905 UTC [37758][client backend] [[unknown]][18/1:0] LOG: connection authenticated: user="yetanotheruser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:6) 2024-03-14 14:09:08.905 UTC [37758][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-14 14:09:08.906 UTC [37758][client backend] [001_ssltests.pl][18/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid sslrootcert=ssl/root+server_ca.crt sslmode=require dbname=verifydb hostaddr=127.0.0.1 host=localhost user=yetanotheruser sslcert=ssl/client.crt sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_Q7Yd/client.key$$ 2024-03-14 14:09:08.906 UTC [37758][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=yetanotheruser database=verifydb host=localhost port=34944 2024-03-14 14:09:08.910 UTC [37700][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:08.910 UTC [37700][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:08.912 UTC [37700][postmaster] LOG: background worker "logical replication launcher" (PID 37706) exited with exit code 1 2024-03-14 14:09:08.912 UTC [37701][checkpointer] LOG: shutting down 2024-03-14 14:09:08.912 UTC [37701][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:08.912 UTC [37701][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413EF08, redo lsn=0/413EF08 2024-03-14 14:09:08.914 UTC [37700][postmaster] LOG: database system is shut down 2024-03-14 14:09:09.021 UTC [37760][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:09.021 UTC [37760][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:09.021 UTC [37760][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:09.023 UTC [37763][startup] LOG: database system was shut down at 2024-03-14 14:09:08 UTC 2024-03-14 14:09:09.026 UTC [37760][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:09.125 UTC [37768][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34945 2024-03-14 14:09:09.131 UTC [37768][client backend] [[unknown]][0/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-14 14:09:09.131 UTC [37768][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-14 14:09:09.135 UTC [37768][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=certdb sslkey=/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/tmp_test_Q7Yd/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-14 14:09:09.136 UTC [37768][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=34945 2024-03-14 14:09:09.143 UTC [37770][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34946 2024-03-14 14:09:09.148 UTC [37770][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-14 14:09:09.148 UTC [37770][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-14 14:09:09.155 UTC [37772][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34947 2024-03-14 14:09:09.160 UTC [37772][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-14 14:09:09.160 UTC [37772][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-14 14:09:09.163 UTC [37760][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:09.163 UTC [37760][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:09.165 UTC [37760][postmaster] LOG: background worker "logical replication launcher" (PID 37766) exited with exit code 1 2024-03-14 14:09:09.165 UTC [37761][checkpointer] LOG: shutting down 2024-03-14 14:09:09.165 UTC [37761][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:09.165 UTC [37761][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413EF80, redo lsn=0/413EF80 2024-03-14 14:09:09.167 UTC [37760][postmaster] LOG: database system is shut down 2024-03-14 14:09:09.281 UTC [37774][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:09.281 UTC [37774][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:09.281 UTC [37774][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:09.282 UTC [37777][startup] LOG: database system was shut down at 2024-03-14 14:09:09 UTC 2024-03-14 14:09:09.285 UTC [37774][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:09.383 UTC [37782][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34948 2024-03-14 14:09:09.388 UTC [37782][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-14 14:09:09.388 UTC [37782][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-14 14:09:09.392 UTC [37774][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:09.392 UTC [37774][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:09.393 UTC [37774][postmaster] LOG: background worker "logical replication launcher" (PID 37780) exited with exit code 1 2024-03-14 14:09:09.393 UTC [37775][checkpointer] LOG: shutting down 2024-03-14 14:09:09.393 UTC [37775][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:09.394 UTC [37775][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413EFF8, redo lsn=0/413EFF8 2024-03-14 14:09:09.395 UTC [37774][postmaster] LOG: database system is shut down 2024-03-14 14:09:09.510 UTC [37784][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:09.510 UTC [37784][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:09.510 UTC [37784][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:09.511 UTC [37787][startup] LOG: database system was shut down at 2024-03-14 14:09:09 UTC 2024-03-14 14:09:09.514 UTC [37784][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:09.611 UTC [37792][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34949 2024-03-14 14:09:09.616 UTC [37792][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-14 14:09:09.616 UTC [37792][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-14 14:09:09.623 UTC [37794][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34950 2024-03-14 14:09:09.628 UTC [37794][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-14 14:09:09.628 UTC [37794][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-14 14:09:09.631 UTC [37784][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:09.631 UTC [37784][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:09.632 UTC [37784][postmaster] LOG: background worker "logical replication launcher" (PID 37790) exited with exit code 1 2024-03-14 14:09:09.632 UTC [37785][checkpointer] LOG: shutting down 2024-03-14 14:09:09.632 UTC [37785][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:09.632 UTC [37785][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413F070, redo lsn=0/413F070 2024-03-14 14:09:09.634 UTC [37784][postmaster] LOG: database system is shut down 2024-03-14 14:09:09.749 UTC [37796][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:09.749 UTC [37796][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:09.749 UTC [37796][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:09.751 UTC [37799][startup] LOG: database system was shut down at 2024-03-14 14:09:09 UTC 2024-03-14 14:09:09.753 UTC [37796][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:09.850 UTC [37804][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34951 2024-03-14 14:09:09.852 UTC [37804][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:09:09.852 UTC [37804][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:09:09.859 UTC [37806][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34952 2024-03-14 14:09:09.863 UTC [37806][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:09.863 UTC [37806][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-14 14:09:09.868 UTC [37806][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-14 14:09:09.868 UTC [37806][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=34952 2024-03-14 14:09:09.871 UTC [37796][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:09.871 UTC [37796][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:09.872 UTC [37796][postmaster] LOG: background worker "logical replication launcher" (PID 37802) exited with exit code 1 2024-03-14 14:09:09.872 UTC [37797][checkpointer] LOG: shutting down 2024-03-14 14:09:09.872 UTC [37797][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:09.872 UTC [37797][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413F0E8, redo lsn=0/413F0E8 2024-03-14 14:09:09.873 UTC [37796][postmaster] LOG: database system is shut down 2024-03-14 14:09:09.987 UTC [37808][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:09.987 UTC [37808][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:09.987 UTC [37808][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:09.989 UTC [37811][startup] LOG: database system was shut down at 2024-03-14 14:09:09 UTC 2024-03-14 14:09:09.991 UTC [37808][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:10.086 UTC [37816][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34953 2024-03-14 14:09:10.088 UTC [37816][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:09:10.088 UTC [37816][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:09:10.096 UTC [37818][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34954 2024-03-14 14:09:10.100 UTC [37818][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:10.100 UTC [37818][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-14 14:09:10.104 UTC [37818][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-14 14:09:10.104 UTC [37818][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=34954 2024-03-14 14:09:10.108 UTC [37808][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:10.108 UTC [37808][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:10.109 UTC [37808][postmaster] LOG: background worker "logical replication launcher" (PID 37814) exited with exit code 1 2024-03-14 14:09:10.110 UTC [37809][checkpointer] LOG: shutting down 2024-03-14 14:09:10.110 UTC [37809][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:10.110 UTC [37809][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413F160, redo lsn=0/413F160 2024-03-14 14:09:10.111 UTC [37808][postmaster] LOG: database system is shut down 2024-03-14 14:09:10.225 UTC [37820][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:10.225 UTC [37820][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:10.226 UTC [37820][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:10.227 UTC [37823][startup] LOG: database system was shut down at 2024-03-14 14:09:10 UTC 2024-03-14 14:09:10.229 UTC [37820][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:10.328 UTC [37828][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34955 2024-03-14 14:09:10.329 UTC [37828][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:09:10.329 UTC [37828][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:09:10.337 UTC [37830][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34956 2024-03-14 14:09:10.341 UTC [37830][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:10.341 UTC [37830][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-14 14:09:10.346 UTC [37830][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-14 14:09:10.346 UTC [37830][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=34956 2024-03-14 14:09:10.349 UTC [37820][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:10.350 UTC [37820][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:10.351 UTC [37820][postmaster] LOG: background worker "logical replication launcher" (PID 37826) exited with exit code 1 2024-03-14 14:09:10.351 UTC [37821][checkpointer] LOG: shutting down 2024-03-14 14:09:10.351 UTC [37821][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:10.351 UTC [37821][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413F1D8, redo lsn=0/413F1D8 2024-03-14 14:09:10.353 UTC [37820][postmaster] LOG: database system is shut down 2024-03-14 14:09:10.467 UTC [37832][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:10.467 UTC [37832][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:10.467 UTC [37832][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:10.469 UTC [37835][startup] LOG: database system was shut down at 2024-03-14 14:09:10 UTC 2024-03-14 14:09:10.472 UTC [37832][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:10.570 UTC [37840][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34957 2024-03-14 14:09:10.571 UTC [37840][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:09:10.571 UTC [37840][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:09:10.580 UTC [37842][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34958 2024-03-14 14:09:10.584 UTC [37842][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:10.584 UTC [37842][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-14 14:09:10.589 UTC [37842][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-14 14:09:10.589 UTC [37842][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=34958 2024-03-14 14:09:10.593 UTC [37832][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:10.593 UTC [37832][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:10.594 UTC [37832][postmaster] LOG: background worker "logical replication launcher" (PID 37838) exited with exit code 1 2024-03-14 14:09:10.595 UTC [37833][checkpointer] LOG: shutting down 2024-03-14 14:09:10.595 UTC [37833][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:10.595 UTC [37833][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413F250, redo lsn=0/413F250 2024-03-14 14:09:10.597 UTC [37832][postmaster] LOG: database system is shut down 2024-03-14 14:09:10.711 UTC [37844][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:10.711 UTC [37844][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:10.711 UTC [37844][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:10.713 UTC [37847][startup] LOG: database system was shut down at 2024-03-14 14:09:10 UTC 2024-03-14 14:09:10.717 UTC [37844][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:10.813 UTC [37852][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34959 2024-03-14 14:09:10.815 UTC [37852][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:09:10.815 UTC [37852][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:09:10.825 UTC [37854][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34960 2024-03-14 14:09:10.829 UTC [37854][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:10.829 UTC [37854][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-14 14:09:10.833 UTC [37854][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-14 14:09:10.834 UTC [37854][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=34960 2024-03-14 14:09:10.837 UTC [37844][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:10.837 UTC [37844][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:10.839 UTC [37844][postmaster] LOG: background worker "logical replication launcher" (PID 37850) exited with exit code 1 2024-03-14 14:09:10.839 UTC [37845][checkpointer] LOG: shutting down 2024-03-14 14:09:10.839 UTC [37845][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:10.839 UTC [37845][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413F2C8, redo lsn=0/413F2C8 2024-03-14 14:09:10.841 UTC [37844][postmaster] LOG: database system is shut down 2024-03-14 14:09:10.955 UTC [37856][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:10.955 UTC [37856][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:10.955 UTC [37856][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:10.956 UTC [37859][startup] LOG: database system was shut down at 2024-03-14 14:09:10 UTC 2024-03-14 14:09:10.960 UTC [37856][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:11.057 UTC [37864][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34961 2024-03-14 14:09:11.059 UTC [37864][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:09:11.059 UTC [37864][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:09:11.067 UTC [37866][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34962 2024-03-14 14:09:11.071 UTC [37866][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:11.071 UTC [37866][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-14 14:09:11.075 UTC [37866][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-14 14:09:11.075 UTC [37866][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=34962 2024-03-14 14:09:11.079 UTC [37856][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:11.079 UTC [37856][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:11.080 UTC [37856][postmaster] LOG: background worker "logical replication launcher" (PID 37862) exited with exit code 1 2024-03-14 14:09:11.080 UTC [37857][checkpointer] LOG: shutting down 2024-03-14 14:09:11.080 UTC [37857][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:11.081 UTC [37857][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413F340, redo lsn=0/413F340 2024-03-14 14:09:11.082 UTC [37856][postmaster] LOG: database system is shut down 2024-03-14 14:09:11.196 UTC [37868][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:11.196 UTC [37868][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:11.196 UTC [37868][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:11.198 UTC [37871][startup] LOG: database system was shut down at 2024-03-14 14:09:11 UTC 2024-03-14 14:09:11.201 UTC [37868][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:11.299 UTC [37876][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34963 2024-03-14 14:09:11.300 UTC [37876][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:09:11.301 UTC [37876][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:09:11.309 UTC [37878][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34964 2024-03-14 14:09:11.314 UTC [37878][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:11.314 UTC [37878][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-14 14:09:11.318 UTC [37878][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-14 14:09:11.318 UTC [37878][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=34964 2024-03-14 14:09:11.323 UTC [37868][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:11.323 UTC [37868][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:11.324 UTC [37868][postmaster] LOG: background worker "logical replication launcher" (PID 37874) exited with exit code 1 2024-03-14 14:09:11.324 UTC [37869][checkpointer] LOG: shutting down 2024-03-14 14:09:11.324 UTC [37869][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:11.325 UTC [37869][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413F3B8, redo lsn=0/413F3B8 2024-03-14 14:09:11.326 UTC [37868][postmaster] LOG: database system is shut down 2024-03-14 14:09:11.440 UTC [37880][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:11.440 UTC [37880][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:11.440 UTC [37880][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:11.441 UTC [37883][startup] LOG: database system was shut down at 2024-03-14 14:09:11 UTC 2024-03-14 14:09:11.444 UTC [37880][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:11.543 UTC [37888][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34965 2024-03-14 14:09:11.545 UTC [37888][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:09:11.545 UTC [37888][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:09:11.553 UTC [37890][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34966 2024-03-14 14:09:11.557 UTC [37890][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:11.557 UTC [37890][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-14 14:09:11.561 UTC [37890][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-14 14:09:11.562 UTC [37890][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=34966 2024-03-14 14:09:11.566 UTC [37880][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:11.566 UTC [37880][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:11.567 UTC [37880][postmaster] LOG: background worker "logical replication launcher" (PID 37886) exited with exit code 1 2024-03-14 14:09:11.567 UTC [37881][checkpointer] LOG: shutting down 2024-03-14 14:09:11.567 UTC [37881][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:11.568 UTC [37881][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413F430, redo lsn=0/413F430 2024-03-14 14:09:11.570 UTC [37880][postmaster] LOG: database system is shut down 2024-03-14 14:09:11.683 UTC [37892][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-14 14:09:11.683 UTC [37892][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59400 2024-03-14 14:09:11.683 UTC [37892][postmaster] LOG: listening on Unix socket "/tmp/ZRzs18G8YM/.s.PGSQL.59400" 2024-03-14 14:09:11.685 UTC [37895][startup] LOG: database system was shut down at 2024-03-14 14:09:11 UTC 2024-03-14 14:09:11.688 UTC [37892][postmaster] LOG: database system is ready to accept connections 2024-03-14 14:09:11.785 UTC [37900][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34967 2024-03-14 14:09:11.787 UTC [37900][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-14 14:09:11.787 UTC [37900][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-14 14:09:11.795 UTC [37902][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=34968 2024-03-14 14:09:11.799 UTC [37902][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-14 14:09:11.799 UTC [37902][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-14 14:09:11.804 UTC [37902][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-14 14:09:11.804 UTC [37902][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=34968 2024-03-14 14:09:11.808 UTC [37892][postmaster] LOG: received fast shutdown request 2024-03-14 14:09:11.808 UTC [37892][postmaster] LOG: aborting any active transactions 2024-03-14 14:09:11.809 UTC [37892][postmaster] LOG: background worker "logical replication launcher" (PID 37898) exited with exit code 1 2024-03-14 14:09:11.809 UTC [37893][checkpointer] LOG: shutting down 2024-03-14 14:09:11.809 UTC [37893][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-14 14:09:11.810 UTC [37893][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/413F4A8, redo lsn=0/413F4A8 2024-03-14 14:09:11.811 UTC [37892][postmaster] LOG: database system is shut down 2024-03-14 14:09:11.922 UTC [37904][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-03-14 14:09:11.923 UTC [37904][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [14:09:12.022](0.216s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"