[05:42:25.360](0.024s) # setting up data directory # Checking port 53547 # Found port 53547 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=53547 host=/tmp/gWSrsIcMtf Log file: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [05:42:25.362](0.003s) # 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 36764 [05:42:25.520](0.157s) 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 36860 [05:42:26.297](0.778s) # 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-10 05:42:25.415 UTC [36764][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:25.415 UTC [36764][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:25.416 UTC [36767][startup] LOG: database system was shut down at 2024-03-10 05:38:07 UTC 2024-03-10 05:42:25.418 UTC [36764][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:25.513 UTC [36783][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.514 UTC [36783][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-10 05:42:25.514 UTC [36783][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.518 UTC [36783][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-10 05:42:25.518 UTC [36783][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.525 UTC [36786][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.526 UTC [36786][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-10 05:42:25.526 UTC [36786][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.527 UTC [36786][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-10 05:42:25.527 UTC [36786][client backend] [001_ssltests.pl][1/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-10 05:42:25.527 UTC [36786][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.569 UTC [36793][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.569 UTC [36793][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-10 05:42:25.569 UTC [36793][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.570 UTC [36793][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-10 05:42:25.570 UTC [36793][client backend] [001_ssltests.pl][2/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-10 05:42:25.570 UTC [36793][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.616 UTC [36803][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.616 UTC [36803][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-10 05:42:25.616 UTC [36803][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.617 UTC [36803][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-10 05:42:25.617 UTC [36803][client backend] [001_ssltests.pl][3/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-10 05:42:25.617 UTC [36803][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.658 UTC [36812][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.658 UTC [36812][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-10 05:42:25.658 UTC [36812][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.659 UTC [36812][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-10 05:42:25.659 UTC [36812][client backend] [001_ssltests.pl][4/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-10 05:42:25.660 UTC [36812][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.704 UTC [36815][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.705 UTC [36815][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-10 05:42:25.705 UTC [36815][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.705 UTC [36815][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-10 05:42:25.705 UTC [36815][client backend] [001_ssltests.pl][5/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-10 05:42:25.724 UTC [36815][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.020 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.750 UTC [36821][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.750 UTC [36821][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-10 05:42:25.750 UTC [36821][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.751 UTC [36821][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-10 05:42:25.751 UTC [36821][client backend] [001_ssltests.pl][6/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-10 05:42:25.776 UTC [36821][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.795 UTC [36824][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.796 UTC [36824][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-10 05:42:25.796 UTC [36824][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.797 UTC [36824][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-10 05:42:25.797 UTC [36824][client backend] [001_ssltests.pl][7/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-10 05:42:25.812 UTC [36824][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.840 UTC [36829][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.840 UTC [36829][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-10 05:42:25.840 UTC [36829][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.841 UTC [36829][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-10 05:42:25.841 UTC [36829][client backend] [001_ssltests.pl][8/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-10 05:42:25.864 UTC [36829][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.024 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.883 UTC [36832][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.884 UTC [36832][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-10 05:42:25.884 UTC [36832][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.884 UTC [36832][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-10 05:42:25.884 UTC [36832][client backend] [001_ssltests.pl][9/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-10 05:42:25.899 UTC [36832][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.929 UTC [36834][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.929 UTC [36834][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-10 05:42:25.929 UTC [36834][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.930 UTC [36834][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-10 05:42:25.930 UTC [36834][client backend] [001_ssltests.pl][10/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-10 05:42:25.954 UTC [36834][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.985 UTC [36764][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:26.021 UTC [36764][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:26.022 UTC [36764][postmaster] LOG: background worker "logical replication launcher" (PID 36770) exited with exit code 1 2024-03-10 05:42:26.022 UTC [36765][checkpointer] LOG: shutting down 2024-03-10 05:42:26.022 UTC [36765][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:26.181 UTC [36765][checkpointer] LOG: checkpoint complete: wrote 5614 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.159 s, sync=0.001 s, total=0.160 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45190 kB, estimate=45190 kB; lsn=0/413E658, redo lsn=0/413E658 2024-03-10 05:42:26.186 UTC [36764][postmaster] LOG: database system is shut down 2024-03-10 05:42:26.203 UTC [36860][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:26.203 UTC [36860][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:26.203 UTC [36860][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:26.205 UTC [36863][startup] LOG: database system was shut down at 2024-03-10 05:42:26 UTC 2024-03-10 05:42:26.207 UTC [36860][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:26.299 UTC [36860][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:26.299 UTC [36860][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:26.301 UTC [36860][postmaster] LOG: background worker "logical replication launcher" (PID 36866) exited with exit code 1 2024-03-10 05:42:26.301 UTC [36861][checkpointer] LOG: shutting down 2024-03-10 05:42:26.301 UTC [36861][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:26.301 UTC [36861][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/413E6D0, redo lsn=0/413E6D0 2024-03-10 05:42:26.302 UTC [36860][postmaster] LOG: database system is shut down 2024-03-10 05:42:26.413 UTC [36879][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-10 05:42:26.413 UTC [36879][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [05:42:26.506](0.209s) 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 36889 [05:42:26.616](0.110s) 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-10 05:42:25.415 UTC [36764][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:25.415 UTC [36764][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:25.416 UTC [36767][startup] LOG: database system was shut down at 2024-03-10 05:38:07 UTC 2024-03-10 05:42:25.418 UTC [36764][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:25.513 UTC [36783][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.514 UTC [36783][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-10 05:42:25.514 UTC [36783][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.518 UTC [36783][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-10 05:42:25.518 UTC [36783][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.525 UTC [36786][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.526 UTC [36786][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-10 05:42:25.526 UTC [36786][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.527 UTC [36786][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-10 05:42:25.527 UTC [36786][client backend] [001_ssltests.pl][1/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-10 05:42:25.527 UTC [36786][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.569 UTC [36793][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.569 UTC [36793][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-10 05:42:25.569 UTC [36793][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.570 UTC [36793][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-10 05:42:25.570 UTC [36793][client backend] [001_ssltests.pl][2/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-10 05:42:25.570 UTC [36793][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.616 UTC [36803][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.616 UTC [36803][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-10 05:42:25.616 UTC [36803][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.617 UTC [36803][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-10 05:42:25.617 UTC [36803][client backend] [001_ssltests.pl][3/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-10 05:42:25.617 UTC [36803][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.658 UTC [36812][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.658 UTC [36812][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-10 05:42:25.658 UTC [36812][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.659 UTC [36812][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-10 05:42:25.659 UTC [36812][client backend] [001_ssltests.pl][4/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-10 05:42:25.660 UTC [36812][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.704 UTC [36815][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.705 UTC [36815][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-10 05:42:25.705 UTC [36815][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.705 UTC [36815][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-10 05:42:25.705 UTC [36815][client backend] [001_ssltests.pl][5/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-10 05:42:25.724 UTC [36815][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.020 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.750 UTC [36821][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.750 UTC [36821][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-10 05:42:25.750 UTC [36821][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.751 UTC [36821][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-10 05:42:25.751 UTC [36821][client backend] [001_ssltests.pl][6/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-10 05:42:25.776 UTC [36821][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.795 UTC [36824][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.796 UTC [36824][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-10 05:42:25.796 UTC [36824][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.797 UTC [36824][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-10 05:42:25.797 UTC [36824][client backend] [001_ssltests.pl][7/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-10 05:42:25.812 UTC [36824][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.840 UTC [36829][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.840 UTC [36829][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-10 05:42:25.840 UTC [36829][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.841 UTC [36829][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-10 05:42:25.841 UTC [36829][client backend] [001_ssltests.pl][8/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-10 05:42:25.864 UTC [36829][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.024 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.883 UTC [36832][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.884 UTC [36832][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-10 05:42:25.884 UTC [36832][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.884 UTC [36832][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-10 05:42:25.884 UTC [36832][client backend] [001_ssltests.pl][9/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-10 05:42:25.899 UTC [36832][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.929 UTC [36834][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.929 UTC [36834][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-10 05:42:25.929 UTC [36834][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.930 UTC [36834][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-10 05:42:25.930 UTC [36834][client backend] [001_ssltests.pl][10/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-10 05:42:25.954 UTC [36834][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.985 UTC [36764][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:26.021 UTC [36764][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:26.022 UTC [36764][postmaster] LOG: background worker "logical replication launcher" (PID 36770) exited with exit code 1 2024-03-10 05:42:26.022 UTC [36765][checkpointer] LOG: shutting down 2024-03-10 05:42:26.022 UTC [36765][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:26.181 UTC [36765][checkpointer] LOG: checkpoint complete: wrote 5614 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.159 s, sync=0.001 s, total=0.160 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45190 kB, estimate=45190 kB; lsn=0/413E658, redo lsn=0/413E658 2024-03-10 05:42:26.186 UTC [36764][postmaster] LOG: database system is shut down 2024-03-10 05:42:26.203 UTC [36860][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:26.203 UTC [36860][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:26.203 UTC [36860][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:26.205 UTC [36863][startup] LOG: database system was shut down at 2024-03-10 05:42:26 UTC 2024-03-10 05:42:26.207 UTC [36860][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:26.299 UTC [36860][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:26.299 UTC [36860][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:26.301 UTC [36860][postmaster] LOG: background worker "logical replication launcher" (PID 36866) exited with exit code 1 2024-03-10 05:42:26.301 UTC [36861][checkpointer] LOG: shutting down 2024-03-10 05:42:26.301 UTC [36861][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:26.301 UTC [36861][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/413E6D0, redo lsn=0/413E6D0 2024-03-10 05:42:26.302 UTC [36860][postmaster] LOG: database system is shut down 2024-03-10 05:42:26.413 UTC [36879][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-10 05:42:26.413 UTC [36879][postmaster] LOG: database system is shut down 2024-03-10 05:42:26.521 UTC [36889][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:26.521 UTC [36889][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:26.521 UTC [36889][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:26.523 UTC [36893][startup] LOG: database system was shut down at 2024-03-10 05:42:26 UTC 2024-03-10 05:42:26.525 UTC [36889][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:26.618 UTC [36889][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:26.618 UTC [36889][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:26.619 UTC [36889][postmaster] LOG: background worker "logical replication launcher" (PID 36896) exited with exit code 1 2024-03-10 05:42:26.619 UTC [36891][checkpointer] LOG: shutting down 2024-03-10 05:42:26.619 UTC [36891][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:26.620 UTC [36891][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/413E748, redo lsn=0/413E748 2024-03-10 05:42:26.621 UTC [36889][postmaster] LOG: database system is shut down 2024-03-10 05:42:26.731 UTC [36911][postmaster] FATAL: could not set SSL protocol version range 2024-03-10 05:42:26.731 UTC [36911][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-10 05:42:26.731 UTC [36911][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [05:42:26.830](0.214s) 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 36917 [05:42:26.934](0.104s) ok 5 - restart succeeds with correct SSL protocol bounds [05:42:26.934](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 36937 [05:42:27.152](0.217s) ok 6 - server doesn't accept non-SSL connections [05:42:27.152](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [05:42:27.169](0.017s) ok 8 - connect without server root cert sslmode=require [05:42:27.169](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [05:42:27.179](0.009s) ok 10 - connect without server root cert sslmode=verify-ca [05:42:27.179](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [05:42:27.187](0.008s) ok 12 - connect without server root cert sslmode=verify-full [05:42:27.187](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [05:42:27.199](0.011s) ok 14 - connect with wrong server root cert sslmode=require [05:42:27.199](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [05:42:27.211](0.011s) ok 16 - connect with wrong server root cert sslmode=verify-ca [05:42:27.211](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [05:42:27.223](0.012s) ok 18 - connect with wrong server root cert sslmode=verify-full [05:42:27.223](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [05:42:27.236](0.013s) ok 20 - connect with server CA cert, without root CA [05:42:27.236](0.000s) ok 21 - connect with server CA cert, without root CA: matches [05:42:27.249](0.013s) ok 22 - connect with correct server CA cert file sslmode=require [05:42:27.249](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [05:42:27.261](0.012s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [05:42:27.261](0.001s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [05:42:27.274](0.013s) ok 26 - connect with correct server CA cert file sslmode=verify-full [05:42:27.274](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [05:42:27.286](0.012s) ok 28 - cert root file that contains two certificates, order 1 [05:42:27.286](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [05:42:27.298](0.011s) ok 30 - cert root file that contains two certificates, order 2 [05:42:27.298](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [05:42:27.309](0.011s) ok 32 - connect with sslcertmode=disable [05:42:27.309](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [05:42:27.321](0.012s) ok 34 - connect with sslcertmode=allow [05:42:27.322](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [05:42:27.333](0.011s) ok 36 - connect with sslcertmode=require fails without a client certificate [05:42:27.333](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [05:42:27.344](0.011s) ok 38 - sslcrl option with invalid file name [05:42:27.344](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [05:42:27.353](0.009s) ok 40 - CRL belonging to a different CA [05:42:27.353](0.000s) ok 41 - CRL belonging to a different CA: matches [05:42:27.363](0.010s) ok 42 - directory CRL belonging to a different CA [05:42:27.364](0.000s) ok 43 - directory CRL belonging to a different CA: matches [05:42:27.375](0.011s) ok 44 - CRL with a non-revoked cert [05:42:27.375](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [05:42:27.388](0.013s) ok 46 - directory CRL with a non-revoked cert [05:42:27.389](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [05:42:27.402](0.013s) ok 48 - mismatch between host name and server certificate sslmode=require [05:42:27.403](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [05:42:27.415](0.012s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [05:42:27.415](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [05:42:27.425](0.010s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [05:42:27.425](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 37035 [05:42:27.648](0.223s) ok 54 - IP address in the Common Name [05:42:27.648](0.000s) ok 55 - IP address in the Common Name: no stderr [05:42:27.658](0.010s) ok 56 - mismatch between host name and server certificate IP address [05:42:27.658](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 37051 [05:42:27.889](0.231s) ok 58 - IP address in a dNSName [05:42:27.889](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 37080 [05:42:28.114](0.225s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [05:42:28.114](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [05:42:28.127](0.012s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [05:42:28.127](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [05:42:28.140](0.013s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [05:42:28.140](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [05:42:28.152](0.012s) ok 66 - host name not matching with X.509 Subject Alternative Names [05:42:28.152](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [05:42:28.164](0.012s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [05:42:28.165](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 37111 [05:42:28.390](0.225s) ok 70 - host name matching with a single X.509 Subject Alternative Name [05:42:28.391](0.001s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [05:42:28.403](0.012s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [05:42:28.403](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [05:42:28.414](0.011s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [05:42:28.414](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 37142 [05:42:28.651](0.236s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [05:42:28.651](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [05:42:28.663](0.012s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [05:42:28.663](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [05:42:28.673](0.010s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [05:42:28.673](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [05:42:28.685](0.012s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [05:42:28.685](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [05:42:28.698](0.013s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [05:42:28.698](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [05:42:28.712](0.013s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [05:42:28.712](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [05:42:28.726](0.014s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [05:42:28.726](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [05:42:28.739](0.014s) ok 90 - IPv6 host with CIDR mask does not match [05:42:28.740](0.001s) 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 37203 [05:42:28.970](0.230s) ok 92 - certificate with both a CN and SANs 1 [05:42:28.970](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [05:42:28.982](0.012s) ok 94 - certificate with both a CN and SANs 2 [05:42:28.982](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [05:42:28.994](0.012s) ok 96 - certificate with both a CN and SANs ignores CN [05:42:28.994](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 37244 [05:42:29.226](0.232s) ok 98 - certificate with both a CN and IP SANs matches CN [05:42:29.227](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [05:42:29.240](0.013s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [05:42:29.240](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [05:42:29.254](0.014s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [05:42:29.254](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 37306 [05:42:29.472](0.218s) ok 104 - certificate with both an IP CN and IP SANs 1 [05:42:29.472](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [05:42:29.483](0.010s) ok 106 - certificate with both an IP CN and IP SANs 2 [05:42:29.483](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [05:42:29.492](0.009s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [05:42:29.492](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 37344 [05:42:29.722](0.230s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [05:42:29.722](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [05:42:29.736](0.014s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [05:42:29.736](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [05:42:29.751](0.014s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [05:42:29.751](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 37381 [05:42:29.977](0.226s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [05:42:29.978](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [05:42:29.989](0.011s) ok 118 - server certificate without CN or SANs sslmode=verify-full [05:42:29.989](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 37422 [05:42:30.216](0.227s) ok 120 - sslrootcert=system does not connect with private CA [05:42:30.216](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [05:42:30.223](0.006s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [05:42:30.223](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [05:42:30.239](0.017s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [05:42:30.240](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [05:42:30.251](0.011s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [05:42:30.251](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 37465 [05:42:30.483](0.232s) ok 128 - connects without client-side CRL [05:42:30.483](0.000s) ok 129 - connects without client-side CRL: no stderr [05:42:30.492](0.009s) ok 130 - does not connect with client-side CRL file [05:42:30.492](0.000s) ok 131 - does not connect with client-side CRL file: matches [05:42:30.503](0.010s) ok 132 - does not connect with client-side CRL directory [05:42:30.503](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() [05:42:30.514](0.012s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [05:42:30.515](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [05:42:30.515](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [05:42:30.526](0.011s) ok 137 - connection success with correct range of TLS protocol versions [05:42:30.527](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [05:42:30.531](0.005s) ok 139 - connection failure with incorrect range of TLS protocol versions [05:42:30.531](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [05:42:30.535](0.004s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [05:42:30.536](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [05:42:30.540](0.005s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [05:42:30.541](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [05:42:30.541](0.000s) # running server tests [05:42:30.553](0.012s) ok 145 - certificate authorization fails without client cert [05:42:30.553](0.000s) ok 146 - certificate authorization fails without client cert: matches [05:42:30.570](0.018s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [05:42:30.571](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [05:42:30.586](0.015s) ok 149 - certificate authorization succeeds with correct client cert in DER format [05:42:30.586](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [05:42:30.602](0.015s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [05:42:30.602](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [05:42:30.617](0.015s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [05:42:30.618](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [05:42:30.633](0.016s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [05:42:30.634](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [05:42:30.649](0.015s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [05:42:30.649](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [05:42:30.662](0.013s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [05:42:30.662](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [05:42:30.671](0.008s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [05:42:30.671](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [05:42:30.690](0.019s) ok 163 - certificate authorization succeeds with DN mapping [05:42:30.690](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [05:42:30.690](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [05:42:30.709](0.018s) ok 166 - certificate authorization succeeds with DN regex mapping [05:42:30.709](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [05:42:30.725](0.016s) ok 168 - certificate authorization succeeds with CN mapping [05:42:30.725](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [05:42:30.726](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [05:42:30.726](0.000s) not ok 171 # TODO & SKIP Need Pty support [05:42:30.726](0.000s) not ok 172 # TODO & SKIP Need Pty support [05:42:30.726](0.000s) not ok 173 # TODO & SKIP Need Pty support [05:42:30.726](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_iRfI/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [05:42:30.760](0.034s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [05:42:30.760](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [05:42:30.761](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [05:42:30.770](0.009s) ok 178 - certificate authorization fails because of file permissions [05:42:30.770](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [05:42:30.783](0.013s) ok 180 - certificate authorization fails with client cert belonging to another user [05:42:30.783](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [05:42:30.795](0.012s) ok 182 - certificate authorization fails with revoked client cert [05:42:30.795](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [05:42:30.796](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [05:42:30.813](0.017s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [05:42:30.813](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [05:42:30.813](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [05:42:30.827](0.013s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [05:42:30.827](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [05:42:30.827](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [05:42:30.842](0.014s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [05:42:30.842](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [05:42:30.842](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 37573 [05:42:31.078](0.236s) ok 194 - intermediate client certificate is provided by client [05:42:31.079](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [05:42:31.092](0.014s) ok 196 - intermediate client certificate is missing [05:42:31.092](0.000s) ok 197 - intermediate client certificate is missing: matches [05:42:31.105](0.013s) ok 198 - logged client certificate Subjects are truncated if they're too long [05:42:31.105](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 37607 [05:42:31.329](0.223s) ok 200 - intermediate client certificate is untrusted [05:42:31.329](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 37627 [05:42:31.559](0.230s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [05:42:31.559](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [05:42:31.571](0.012s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [05:42:31.572](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 37648 [05:42:31.800](0.228s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [05:42:31.800](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. [05:42:31.800](0.000s) # got: '2' # expected: '0' [05:42:31.801](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [05:42:31.801](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. [05:42:31.801](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 53547 failed: SSL SYSCALL error: EOF detected' # expected: '' [05:42:31.818](0.017s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [05:42:31.818](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 37660 [05:42:32.045](0.227s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [05:42:32.062](0.017s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [05:42:32.062](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 37672 [05:42:32.287](0.226s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [05:42:32.304](0.016s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [05:42:32.304](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 37684 [05:42:32.527](0.223s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [05:42:32.544](0.017s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [05:42:32.544](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 37696 [05:42:32.770](0.226s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [05:42:32.770](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. [05:42:32.770](0.000s) # got: '2' # expected: '0' [05:42:32.771](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [05:42:32.771](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. [05:42:32.771](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 53547 failed: SSL SYSCALL error: EOF detected' # expected: '' [05:42:32.788](0.016s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [05:42:32.788](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 37708 [05:42:33.014](0.226s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [05:42:33.032](0.017s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [05:42:33.032](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 37720 [05:42:33.257](0.225s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [05:42:33.273](0.015s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [05:42:33.273](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 37732 [05:42:33.497](0.225s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [05:42:33.512](0.015s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [05:42:33.512](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 37756 [05:42:33.739](0.227s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [05:42:33.754](0.015s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [05:42:33.755](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-10 05:42:25.415 UTC [36764][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:25.415 UTC [36764][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:25.416 UTC [36767][startup] LOG: database system was shut down at 2024-03-10 05:38:07 UTC 2024-03-10 05:42:25.418 UTC [36764][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:25.513 UTC [36783][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.514 UTC [36783][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-10 05:42:25.514 UTC [36783][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.518 UTC [36783][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-10 05:42:25.518 UTC [36783][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.525 UTC [36786][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.526 UTC [36786][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-10 05:42:25.526 UTC [36786][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.527 UTC [36786][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-10 05:42:25.527 UTC [36786][client backend] [001_ssltests.pl][1/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-10 05:42:25.527 UTC [36786][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.569 UTC [36793][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.569 UTC [36793][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-10 05:42:25.569 UTC [36793][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.570 UTC [36793][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-10 05:42:25.570 UTC [36793][client backend] [001_ssltests.pl][2/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-10 05:42:25.570 UTC [36793][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.616 UTC [36803][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.616 UTC [36803][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-10 05:42:25.616 UTC [36803][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.617 UTC [36803][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-10 05:42:25.617 UTC [36803][client backend] [001_ssltests.pl][3/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-10 05:42:25.617 UTC [36803][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.658 UTC [36812][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.658 UTC [36812][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-10 05:42:25.658 UTC [36812][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.659 UTC [36812][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-10 05:42:25.659 UTC [36812][client backend] [001_ssltests.pl][4/2:0] WARNING: roles created by regression test cases should have names starting with "regress_" 2024-03-10 05:42:25.660 UTC [36812][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.704 UTC [36815][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.705 UTC [36815][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-10 05:42:25.705 UTC [36815][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.705 UTC [36815][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-10 05:42:25.705 UTC [36815][client backend] [001_ssltests.pl][5/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-10 05:42:25.724 UTC [36815][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.020 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.750 UTC [36821][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.750 UTC [36821][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-10 05:42:25.750 UTC [36821][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.751 UTC [36821][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-10 05:42:25.751 UTC [36821][client backend] [001_ssltests.pl][6/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-10 05:42:25.776 UTC [36821][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.795 UTC [36824][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.796 UTC [36824][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-10 05:42:25.796 UTC [36824][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.797 UTC [36824][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-10 05:42:25.797 UTC [36824][client backend] [001_ssltests.pl][7/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-10 05:42:25.812 UTC [36824][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.840 UTC [36829][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.840 UTC [36829][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-10 05:42:25.840 UTC [36829][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.841 UTC [36829][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-10 05:42:25.841 UTC [36829][client backend] [001_ssltests.pl][8/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-10 05:42:25.864 UTC [36829][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.024 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.883 UTC [36832][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.884 UTC [36832][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-10 05:42:25.884 UTC [36832][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.884 UTC [36832][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-10 05:42:25.884 UTC [36832][client backend] [001_ssltests.pl][9/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-10 05:42:25.899 UTC [36832][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.929 UTC [36834][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:42:25.929 UTC [36834][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-10 05:42:25.929 UTC [36834][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:42:25.930 UTC [36834][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-10 05:42:25.930 UTC [36834][client backend] [001_ssltests.pl][10/2:0] WARNING: databases created by regression test cases should have names including "regression" 2024-03-10 05:42:25.954 UTC [36834][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.025 user=postgres database=postgres host=[local] 2024-03-10 05:42:25.985 UTC [36764][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:26.021 UTC [36764][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:26.022 UTC [36764][postmaster] LOG: background worker "logical replication launcher" (PID 36770) exited with exit code 1 2024-03-10 05:42:26.022 UTC [36765][checkpointer] LOG: shutting down 2024-03-10 05:42:26.022 UTC [36765][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:26.181 UTC [36765][checkpointer] LOG: checkpoint complete: wrote 5614 buffers (34.3%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.159 s, sync=0.001 s, total=0.160 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45190 kB, estimate=45190 kB; lsn=0/413E658, redo lsn=0/413E658 2024-03-10 05:42:26.186 UTC [36764][postmaster] LOG: database system is shut down 2024-03-10 05:42:26.203 UTC [36860][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:26.203 UTC [36860][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:26.203 UTC [36860][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:26.205 UTC [36863][startup] LOG: database system was shut down at 2024-03-10 05:42:26 UTC 2024-03-10 05:42:26.207 UTC [36860][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:26.299 UTC [36860][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:26.299 UTC [36860][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:26.301 UTC [36860][postmaster] LOG: background worker "logical replication launcher" (PID 36866) exited with exit code 1 2024-03-10 05:42:26.301 UTC [36861][checkpointer] LOG: shutting down 2024-03-10 05:42:26.301 UTC [36861][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:26.301 UTC [36861][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/413E6D0, redo lsn=0/413E6D0 2024-03-10 05:42:26.302 UTC [36860][postmaster] LOG: database system is shut down 2024-03-10 05:42:26.413 UTC [36879][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-10 05:42:26.413 UTC [36879][postmaster] LOG: database system is shut down 2024-03-10 05:42:26.521 UTC [36889][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:26.521 UTC [36889][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:26.521 UTC [36889][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:26.523 UTC [36893][startup] LOG: database system was shut down at 2024-03-10 05:42:26 UTC 2024-03-10 05:42:26.525 UTC [36889][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:26.618 UTC [36889][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:26.618 UTC [36889][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:26.619 UTC [36889][postmaster] LOG: background worker "logical replication launcher" (PID 36896) exited with exit code 1 2024-03-10 05:42:26.619 UTC [36891][checkpointer] LOG: shutting down 2024-03-10 05:42:26.619 UTC [36891][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:26.620 UTC [36891][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/413E748, redo lsn=0/413E748 2024-03-10 05:42:26.621 UTC [36889][postmaster] LOG: database system is shut down 2024-03-10 05:42:26.731 UTC [36911][postmaster] FATAL: could not set SSL protocol version range 2024-03-10 05:42:26.731 UTC [36911][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-10 05:42:26.731 UTC [36911][postmaster] LOG: database system is shut down 2024-03-10 05:42:26.845 UTC [36917][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:26.845 UTC [36917][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:26.845 UTC [36917][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:26.847 UTC [36928][startup] LOG: database system was shut down at 2024-03-10 05:42:26 UTC 2024-03-10 05:42:26.849 UTC [36917][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:26.936 UTC [36917][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:26.936 UTC [36917][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:26.937 UTC [36917][postmaster] LOG: background worker "logical replication launcher" (PID 36931) exited with exit code 1 2024-03-10 05:42:26.937 UTC [36926][checkpointer] LOG: shutting down 2024-03-10 05:42:26.937 UTC [36926][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:26.938 UTC [36926][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/413E7C0, redo lsn=0/413E7C0 2024-03-10 05:42:26.939 UTC [36917][postmaster] LOG: database system is shut down 2024-03-10 05:42:27.053 UTC [36937][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:27.053 UTC [36937][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:27.053 UTC [36937][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:27.055 UTC [36946][startup] LOG: database system was shut down at 2024-03-10 05:42:26 UTC 2024-03-10 05:42:27.058 UTC [36937][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:27.150 UTC [36953][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20554 2024-03-10 05:42:27.151 UTC [36953][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-10 05:42:27.151 UTC [36953][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-03-10 05:42:27.158 UTC [36956][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20555 2024-03-10 05:42:27.162 UTC [36956][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-10 05:42:27.162 UTC [36956][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-10 05:42:27.167 UTC [36956][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-10 05:42:27.168 UTC [36956][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20555 2024-03-10 05:42:27.176 UTC [36962][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20557 2024-03-10 05:42:27.178 UTC [36962][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-10 05:42:27.185 UTC [36965][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20558 2024-03-10 05:42:27.186 UTC [36965][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-10 05:42:27.194 UTC [36968][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20560 2024-03-10 05:42:27.197 UTC [36968][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-10 05:42:27.206 UTC [36972][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20562 2024-03-10 05:42:27.209 UTC [36972][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-10 05:42:27.217 UTC [36976][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20564 2024-03-10 05:42:27.221 UTC [36976][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-10 05:42:27.230 UTC [36979][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20565 2024-03-10 05:42:27.234 UTC [36979][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-10 05:42:27.242 UTC [36982][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20567 2024-03-10 05:42:27.246 UTC [36982][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-10 05:42:27.246 UTC [36982][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-10 05:42:27.247 UTC [36982][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-10 05:42:27.248 UTC [36982][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=20567 2024-03-10 05:42:27.254 UTC [36986][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20569 2024-03-10 05:42:27.258 UTC [36986][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-10 05:42:27.258 UTC [36986][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-10 05:42:27.259 UTC [36986][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-10 05:42:27.259 UTC [36986][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=20569 2024-03-10 05:42:27.267 UTC [36990][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20571 2024-03-10 05:42:27.271 UTC [36990][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-10 05:42:27.271 UTC [36990][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-10 05:42:27.272 UTC [36990][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-10 05:42:27.273 UTC [36990][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=20571 2024-03-10 05:42:27.280 UTC [36994][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20573 2024-03-10 05:42:27.284 UTC [36994][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-10 05:42:27.284 UTC [36994][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-10 05:42:27.284 UTC [36994][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-10 05:42:27.285 UTC [36994][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=20573 2024-03-10 05:42:27.291 UTC [36997][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20574 2024-03-10 05:42:27.296 UTC [36997][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-10 05:42:27.296 UTC [36997][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-10 05:42:27.296 UTC [36997][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-10 05:42:27.297 UTC [36997][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=20574 2024-03-10 05:42:27.303 UTC [36999][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20575 2024-03-10 05:42:27.307 UTC [36999][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-10 05:42:27.307 UTC [36999][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-10 05:42:27.308 UTC [36999][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-10 05:42:27.308 UTC [36999][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=20575 2024-03-10 05:42:27.315 UTC [37001][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20576 2024-03-10 05:42:27.319 UTC [37001][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-10 05:42:27.319 UTC [37001][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-10 05:42:27.320 UTC [37001][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-10 05:42:27.320 UTC [37001][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=20576 2024-03-10 05:42:27.327 UTC [37005][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20577 2024-03-10 05:42:27.331 UTC [37005][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-10 05:42:27.331 UTC [37005][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-10 05:42:27.331 UTC [37005][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=20577 2024-03-10 05:42:27.338 UTC [37007][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20578 2024-03-10 05:42:27.342 UTC [37007][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-10 05:42:27.342 UTC [37007][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-10 05:42:27.342 UTC [37007][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-10 05:42:27.343 UTC [37007][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=20578 2024-03-10 05:42:27.349 UTC [37009][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20579 2024-03-10 05:42:27.352 UTC [37009][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-10 05:42:27.358 UTC [37011][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20580 2024-03-10 05:42:27.362 UTC [37011][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-10 05:42:27.369 UTC [37013][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20581 2024-03-10 05:42:27.373 UTC [37013][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-10 05:42:27.373 UTC [37013][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-10 05:42:27.373 UTC [37013][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-10 05:42:27.374 UTC [37013][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=20581 2024-03-10 05:42:27.381 UTC [37015][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20582 2024-03-10 05:42:27.386 UTC [37015][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-10 05:42:27.386 UTC [37015][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-10 05:42:27.387 UTC [37015][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-10 05:42:27.387 UTC [37015][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20582 2024-03-10 05:42:27.395 UTC [37018][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20583 2024-03-10 05:42:27.399 UTC [37018][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-10 05:42:27.399 UTC [37018][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-10 05:42:27.400 UTC [37018][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-10 05:42:27.401 UTC [37018][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20583 2024-03-10 05:42:27.409 UTC [37026][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20584 2024-03-10 05:42:27.413 UTC [37026][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-10 05:42:27.413 UTC [37026][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-10 05:42:27.413 UTC [37026][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-10 05:42:27.414 UTC [37026][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=20584 2024-03-10 05:42:27.420 UTC [37028][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20585 2024-03-10 05:42:27.426 UTC [36937][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:27.426 UTC [36937][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:27.427 UTC [36937][postmaster] LOG: background worker "logical replication launcher" (PID 36949) exited with exit code 1 2024-03-10 05:42:27.428 UTC [36944][checkpointer] LOG: shutting down 2024-03-10 05:42:27.428 UTC [36944][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:27.428 UTC [36944][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/413E838, redo lsn=0/413E838 2024-03-10 05:42:27.430 UTC [36937][postmaster] LOG: database system is shut down 2024-03-10 05:42:27.541 UTC [37035][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:27.541 UTC [37035][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:27.541 UTC [37035][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:27.542 UTC [37038][startup] LOG: database system was shut down at 2024-03-10 05:42:27 UTC 2024-03-10 05:42:27.544 UTC [37035][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:27.637 UTC [37043][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20587 2024-03-10 05:42:27.641 UTC [37043][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-10 05:42:27.642 UTC [37043][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-10 05:42:27.646 UTC [37043][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-10 05:42:27.646 UTC [37043][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20587 2024-03-10 05:42:27.653 UTC [37047][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20588 2024-03-10 05:42:27.660 UTC [37035][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:27.660 UTC [37035][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:27.661 UTC [37035][postmaster] LOG: background worker "logical replication launcher" (PID 37041) exited with exit code 1 2024-03-10 05:42:27.661 UTC [37036][checkpointer] LOG: shutting down 2024-03-10 05:42:27.662 UTC [37036][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:27.662 UTC [37036][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/413E8B0, redo lsn=0/413E8B0 2024-03-10 05:42:27.663 UTC [37035][postmaster] LOG: database system is shut down 2024-03-10 05:42:27.778 UTC [37051][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:27.778 UTC [37051][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:27.778 UTC [37051][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:27.781 UTC [37054][startup] LOG: database system was shut down at 2024-03-10 05:42:27 UTC 2024-03-10 05:42:27.784 UTC [37051][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:27.879 UTC [37074][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20589 2024-03-10 05:42:27.883 UTC [37074][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-10 05:42:27.883 UTC [37074][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-10 05:42:27.887 UTC [37074][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-10 05:42:27.887 UTC [37074][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20589 2024-03-10 05:42:27.891 UTC [37051][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:27.891 UTC [37051][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:27.892 UTC [37051][postmaster] LOG: background worker "logical replication launcher" (PID 37057) exited with exit code 1 2024-03-10 05:42:27.893 UTC [37052][checkpointer] LOG: shutting down 2024-03-10 05:42:27.893 UTC [37052][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:27.893 UTC [37052][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/413E928, redo lsn=0/413E928 2024-03-10 05:42:27.895 UTC [37051][postmaster] LOG: database system is shut down 2024-03-10 05:42:28.005 UTC [37080][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:28.005 UTC [37080][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:28.005 UTC [37080][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:28.007 UTC [37083][startup] LOG: database system was shut down at 2024-03-10 05:42:27 UTC 2024-03-10 05:42:28.010 UTC [37080][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:28.104 UTC [37094][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20590 2024-03-10 05:42:28.108 UTC [37094][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-10 05:42:28.108 UTC [37094][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-10 05:42:28.113 UTC [37094][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-10 05:42:28.113 UTC [37094][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20590 2024-03-10 05:42:28.121 UTC [37096][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20591 2024-03-10 05:42:28.125 UTC [37096][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-10 05:42:28.125 UTC [37096][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-10 05:42:28.125 UTC [37096][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-10 05:42:28.126 UTC [37096][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=20591 2024-03-10 05:42:28.133 UTC [37099][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20592 2024-03-10 05:42:28.137 UTC [37099][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-10 05:42:28.137 UTC [37099][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-10 05:42:28.138 UTC [37099][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=foo.wildcard.pg-ssltest.test$$ 2024-03-10 05:42:28.138 UTC [37099][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=20592 2024-03-10 05:42:28.146 UTC [37103][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20593 2024-03-10 05:42:28.159 UTC [37105][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20594 2024-03-10 05:42:28.167 UTC [37080][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:28.167 UTC [37080][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:28.168 UTC [37080][postmaster] LOG: background worker "logical replication launcher" (PID 37087) exited with exit code 1 2024-03-10 05:42:28.169 UTC [37081][checkpointer] LOG: shutting down 2024-03-10 05:42:28.169 UTC [37081][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:28.169 UTC [37081][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/413E9A0, redo lsn=0/413E9A0 2024-03-10 05:42:28.170 UTC [37080][postmaster] LOG: database system is shut down 2024-03-10 05:42:28.282 UTC [37111][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:28.282 UTC [37111][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:28.282 UTC [37111][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:28.284 UTC [37117][startup] LOG: database system was shut down at 2024-03-10 05:42:28 UTC 2024-03-10 05:42:28.286 UTC [37111][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:28.379 UTC [37127][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20595 2024-03-10 05:42:28.384 UTC [37127][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-10 05:42:28.384 UTC [37127][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-10 05:42:28.388 UTC [37127][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-10 05:42:28.389 UTC [37127][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20595 2024-03-10 05:42:28.397 UTC [37129][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20596 2024-03-10 05:42:28.409 UTC [37132][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20597 2024-03-10 05:42:28.420 UTC [37111][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:28.420 UTC [37111][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:28.423 UTC [37111][postmaster] LOG: background worker "logical replication launcher" (PID 37120) exited with exit code 1 2024-03-10 05:42:28.423 UTC [37114][checkpointer] LOG: shutting down 2024-03-10 05:42:28.423 UTC [37114][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:28.423 UTC [37114][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/413EA18, redo lsn=0/413EA18 2024-03-10 05:42:28.425 UTC [37111][postmaster] LOG: database system is shut down 2024-03-10 05:42:28.538 UTC [37142][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:28.538 UTC [37142][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:28.538 UTC [37142][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:28.540 UTC [37146][startup] LOG: database system was shut down at 2024-03-10 05:42:28 UTC 2024-03-10 05:42:28.542 UTC [37142][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:28.640 UTC [37170][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20602 2024-03-10 05:42:28.644 UTC [37170][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-10 05:42:28.644 UTC [37170][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-10 05:42:28.649 UTC [37170][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-10 05:42:28.649 UTC [37170][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20602 2024-03-10 05:42:28.656 UTC [37174][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20603 2024-03-10 05:42:28.660 UTC [37174][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-10 05:42:28.660 UTC [37174][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-10 05:42:28.661 UTC [37174][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-10 05:42:28.662 UTC [37174][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=20603 2024-03-10 05:42:28.668 UTC [37176][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20604 2024-03-10 05:42:28.679 UTC [37178][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20605 2024-03-10 05:42:28.683 UTC [37178][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-10 05:42:28.683 UTC [37178][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-10 05:42:28.684 UTC [37178][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-10 05:42:28.684 UTC [37178][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=20605 2024-03-10 05:42:28.691 UTC [37181][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20606 2024-03-10 05:42:28.696 UTC [37181][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-10 05:42:28.696 UTC [37181][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-10 05:42:28.696 UTC [37181][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-10 05:42:28.697 UTC [37181][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=20606 2024-03-10 05:42:28.703 UTC [37183][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20607 2024-03-10 05:42:28.708 UTC [37183][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-10 05:42:28.708 UTC [37183][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-10 05:42:28.709 UTC [37183][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-10 05:42:28.710 UTC [37183][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20607 2024-03-10 05:42:28.719 UTC [37187][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20608 2024-03-10 05:42:28.733 UTC [37192][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20609 2024-03-10 05:42:28.742 UTC [37142][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:28.742 UTC [37142][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:28.743 UTC [37142][postmaster] LOG: background worker "logical replication launcher" (PID 37150) exited with exit code 1 2024-03-10 05:42:28.744 UTC [37144][checkpointer] LOG: shutting down 2024-03-10 05:42:28.744 UTC [37144][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:28.744 UTC [37144][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/413EA90, redo lsn=0/413EA90 2024-03-10 05:42:28.746 UTC [37142][postmaster] LOG: database system is shut down 2024-03-10 05:42:28.857 UTC [37203][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:28.857 UTC [37203][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:28.857 UTC [37203][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:28.859 UTC [37211][startup] LOG: database system was shut down at 2024-03-10 05:42:28 UTC 2024-03-10 05:42:28.861 UTC [37203][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:28.959 UTC [37221][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20610 2024-03-10 05:42:28.964 UTC [37221][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-10 05:42:28.964 UTC [37221][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-10 05:42:28.968 UTC [37221][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-10 05:42:28.969 UTC [37221][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20610 2024-03-10 05:42:28.975 UTC [37226][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20613 2024-03-10 05:42:28.980 UTC [37226][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-10 05:42:28.980 UTC [37226][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-10 05:42:28.980 UTC [37226][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-10 05:42:28.981 UTC [37226][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=20613 2024-03-10 05:42:28.988 UTC [37230][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20615 2024-03-10 05:42:29.000 UTC [37203][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:29.000 UTC [37203][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:29.001 UTC [37203][postmaster] LOG: background worker "logical replication launcher" (PID 37216) exited with exit code 1 2024-03-10 05:42:29.001 UTC [37208][checkpointer] LOG: shutting down 2024-03-10 05:42:29.001 UTC [37208][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:29.002 UTC [37208][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/413EB08, redo lsn=0/413EB08 2024-03-10 05:42:29.003 UTC [37203][postmaster] LOG: database system is shut down 2024-03-10 05:42:29.117 UTC [37244][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:29.117 UTC [37244][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:29.117 UTC [37244][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:29.120 UTC [37254][startup] LOG: database system was shut down at 2024-03-10 05:42:29 UTC 2024-03-10 05:42:29.123 UTC [37244][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:29.215 UTC [37274][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20624 2024-03-10 05:42:29.220 UTC [37274][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-10 05:42:29.220 UTC [37274][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-10 05:42:29.224 UTC [37274][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-10 05:42:29.225 UTC [37274][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20624 2024-03-10 05:42:29.232 UTC [37282][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20628 2024-03-10 05:42:29.237 UTC [37282][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-10 05:42:29.237 UTC [37282][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-10 05:42:29.238 UTC [37282][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-10 05:42:29.238 UTC [37282][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20628 2024-03-10 05:42:29.247 UTC [37286][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20630 2024-03-10 05:42:29.251 UTC [37286][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-10 05:42:29.251 UTC [37286][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-10 05:42:29.252 UTC [37286][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-10 05:42:29.252 UTC [37286][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20630 2024-03-10 05:42:29.256 UTC [37244][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:29.256 UTC [37244][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:29.257 UTC [37244][postmaster] LOG: background worker "logical replication launcher" (PID 37259) exited with exit code 1 2024-03-10 05:42:29.257 UTC [37251][checkpointer] LOG: shutting down 2024-03-10 05:42:29.257 UTC [37251][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:29.258 UTC [37251][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/413EB80, redo lsn=0/413EB80 2024-03-10 05:42:29.259 UTC [37244][postmaster] LOG: database system is shut down 2024-03-10 05:42:29.366 UTC [37306][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:29.366 UTC [37306][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:29.366 UTC [37306][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:29.367 UTC [37313][startup] LOG: database system was shut down at 2024-03-10 05:42:29 UTC 2024-03-10 05:42:29.370 UTC [37306][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:29.462 UTC [37325][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20641 2024-03-10 05:42:29.466 UTC [37325][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-10 05:42:29.466 UTC [37325][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-10 05:42:29.470 UTC [37325][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-10 05:42:29.471 UTC [37325][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=20641 2024-03-10 05:42:29.477 UTC [37330][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20644 2024-03-10 05:42:29.480 UTC [37330][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-10 05:42:29.481 UTC [37330][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-10 05:42:29.481 UTC [37330][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-10 05:42:29.482 UTC [37330][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=20644 2024-03-10 05:42:29.487 UTC [37333][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20645 2024-03-10 05:42:29.493 UTC [37306][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:29.493 UTC [37306][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:29.494 UTC [37306][postmaster] LOG: background worker "logical replication launcher" (PID 37317) exited with exit code 1 2024-03-10 05:42:29.494 UTC [37311][checkpointer] LOG: shutting down 2024-03-10 05:42:29.494 UTC [37311][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:29.495 UTC [37311][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/413EBF8, redo lsn=0/413EBF8 2024-03-10 05:42:29.496 UTC [37306][postmaster] LOG: database system is shut down 2024-03-10 05:42:29.611 UTC [37344][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:29.611 UTC [37344][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:29.611 UTC [37344][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:29.613 UTC [37347][startup] LOG: database system was shut down at 2024-03-10 05:42:29 UTC 2024-03-10 05:42:29.617 UTC [37344][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:29.711 UTC [37360][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20649 2024-03-10 05:42:29.716 UTC [37360][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-10 05:42:29.716 UTC [37360][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-10 05:42:29.720 UTC [37360][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-10 05:42:29.721 UTC [37360][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20649 2024-03-10 05:42:29.729 UTC [37363][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20650 2024-03-10 05:42:29.734 UTC [37363][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-10 05:42:29.734 UTC [37363][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-10 05:42:29.734 UTC [37363][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-10 05:42:29.735 UTC [37363][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20650 2024-03-10 05:42:29.743 UTC [37365][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20651 2024-03-10 05:42:29.748 UTC [37365][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-10 05:42:29.748 UTC [37365][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-10 05:42:29.749 UTC [37365][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-10 05:42:29.749 UTC [37365][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20651 2024-03-10 05:42:29.753 UTC [37344][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:29.753 UTC [37344][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:29.755 UTC [37344][postmaster] LOG: background worker "logical replication launcher" (PID 37350) exited with exit code 1 2024-03-10 05:42:29.755 UTC [37345][checkpointer] LOG: shutting down 2024-03-10 05:42:29.755 UTC [37345][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:29.756 UTC [37345][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/413EC70, redo lsn=0/413EC70 2024-03-10 05:42:29.757 UTC [37344][postmaster] LOG: database system is shut down 2024-03-10 05:42:29.866 UTC [37381][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:29.866 UTC [37381][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:29.866 UTC [37381][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:29.868 UTC [37387][startup] LOG: database system was shut down at 2024-03-10 05:42:29 UTC 2024-03-10 05:42:29.871 UTC [37381][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:29.967 UTC [37403][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20658 2024-03-10 05:42:29.971 UTC [37403][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-10 05:42:29.971 UTC [37403][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-10 05:42:29.976 UTC [37403][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-10 05:42:29.976 UTC [37403][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20658 2024-03-10 05:42:29.983 UTC [37408][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20659 2024-03-10 05:42:29.992 UTC [37381][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:29.992 UTC [37381][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:29.993 UTC [37381][postmaster] LOG: background worker "logical replication launcher" (PID 37390) exited with exit code 1 2024-03-10 05:42:29.993 UTC [37385][checkpointer] LOG: shutting down 2024-03-10 05:42:29.993 UTC [37385][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:29.994 UTC [37385][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/413ECE8, redo lsn=0/413ECE8 2024-03-10 05:42:29.995 UTC [37381][postmaster] LOG: database system is shut down 2024-03-10 05:42:30.109 UTC [37422][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:30.109 UTC [37422][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:30.109 UTC [37422][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:30.111 UTC [37428][startup] LOG: database system was shut down at 2024-03-10 05:42:29 UTC 2024-03-10 05:42:30.114 UTC [37422][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:30.211 UTC [37443][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20662 2024-03-10 05:42:30.215 UTC [37443][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-10 05:42:30.229 UTC [37447][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20664 2024-03-10 05:42:30.233 UTC [37447][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-10 05:42:30.233 UTC [37447][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-10 05:42:30.238 UTC [37447][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-10 05:42:30.238 UTC [37447][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20664 2024-03-10 05:42:30.245 UTC [37449][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20665 2024-03-10 05:42:30.253 UTC [37422][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:30.253 UTC [37422][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:30.255 UTC [37422][postmaster] LOG: background worker "logical replication launcher" (PID 37434) exited with exit code 1 2024-03-10 05:42:30.255 UTC [37426][checkpointer] LOG: shutting down 2024-03-10 05:42:30.255 UTC [37426][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:30.255 UTC [37426][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/413ED60, redo lsn=0/413ED60 2024-03-10 05:42:30.257 UTC [37422][postmaster] LOG: database system is shut down 2024-03-10 05:42:30.371 UTC [37465][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:30.371 UTC [37465][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:30.371 UTC [37465][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:30.373 UTC [37468][startup] LOG: database system was shut down at 2024-03-10 05:42:30 UTC 2024-03-10 05:42:30.376 UTC [37465][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:30.473 UTC [37479][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20668 2024-03-10 05:42:30.477 UTC [37479][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-10 05:42:30.477 UTC [37479][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-10 05:42:30.481 UTC [37479][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-10 05:42:30.481 UTC [37479][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20668 2024-03-10 05:42:30.488 UTC [37481][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20669 2024-03-10 05:42:30.491 UTC [37481][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-10 05:42:30.497 UTC [37483][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20670 2024-03-10 05:42:30.502 UTC [37483][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-10 05:42:30.507 UTC [37485][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20671 2024-03-10 05:42:30.511 UTC [37485][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-10 05:42:30.511 UTC [37485][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-10 05:42:30.512 UTC [37485][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-10 05:42:30.514 UTC [37485][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=20671 2024-03-10 05:42:30.520 UTC [37487][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20672 2024-03-10 05:42:30.524 UTC [37487][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-10 05:42:30.524 UTC [37487][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-10 05:42:30.525 UTC [37487][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-10 05:42:30.525 UTC [37487][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=20672 2024-03-10 05:42:30.547 UTC [37499][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20673 2024-03-10 05:42:30.551 UTC [37499][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-03-10 05:42:30.559 UTC [37501][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20674 2024-03-10 05:42:30.564 UTC [37501][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-10 05:42:30.565 UTC [37501][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-10 05:42:30.569 UTC [37501][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_iRfI/client.key$$ 2024-03-10 05:42:30.569 UTC [37501][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=20674 2024-03-10 05:42:30.577 UTC [37504][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20675 2024-03-10 05:42:30.583 UTC [37504][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-10 05:42:30.583 UTC [37504][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-10 05:42:30.584 UTC [37504][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_iRfI/client-der.key$$ 2024-03-10 05:42:30.585 UTC [37504][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=20675 2024-03-10 05:42:30.593 UTC [37506][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20676 2024-03-10 05:42:30.599 UTC [37506][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-10 05:42:30.599 UTC [37506][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-10 05:42:30.600 UTC [37506][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_iRfI/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-03-10 05:42:30.601 UTC [37506][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=20676 2024-03-10 05:42:30.608 UTC [37509][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20677 2024-03-10 05:42:30.614 UTC [37509][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-10 05:42:30.614 UTC [37509][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-10 05:42:30.615 UTC [37509][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_iRfI/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-03-10 05:42:30.616 UTC [37509][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=20677 2024-03-10 05:42:30.624 UTC [37511][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20678 2024-03-10 05:42:30.631 UTC [37511][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-10 05:42:30.631 UTC [37511][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-10 05:42:30.631 UTC [37511][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_iRfI/client.key$$ 2024-03-10 05:42:30.632 UTC [37511][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=20678 2024-03-10 05:42:30.640 UTC [37516][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20680 2024-03-10 05:42:30.646 UTC [37516][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-10 05:42:30.646 UTC [37516][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-10 05:42:30.647 UTC [37516][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_iRfI/client.key$$ 2024-03-10 05:42:30.647 UTC [37516][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=20680 2024-03-10 05:42:30.656 UTC [37518][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20681 2024-03-10 05:42:30.660 UTC [37518][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-03-10 05:42:30.668 UTC [37520][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20682 2024-03-10 05:42:30.670 UTC [37520][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-10 05:42:30.677 UTC [37522][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20683 2024-03-10 05:42:30.683 UTC [37522][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-10 05:42:30.683 UTC [37522][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-10 05:42:30.688 UTC [37522][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_iRfI/client-dn.key$$ 2024-03-10 05:42:30.688 UTC [37522][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_dn host=localhost port=20683 2024-03-10 05:42:30.696 UTC [37526][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20684 2024-03-10 05:42:30.702 UTC [37526][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-10 05:42:30.703 UTC [37526][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-10 05:42:30.707 UTC [37526][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_iRfI/client-dn.key$$ 2024-03-10 05:42:30.707 UTC [37526][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_dn_re host=localhost port=20684 2024-03-10 05:42:30.714 UTC [37528][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20685 2024-03-10 05:42:30.720 UTC [37528][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-10 05:42:30.720 UTC [37528][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-10 05:42:30.724 UTC [37528][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_iRfI/client-dn.key$$ 2024-03-10 05:42:30.724 UTC [37528][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_cn host=localhost port=20685 2024-03-10 05:42:30.749 UTC [37539][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20686 2024-03-10 05:42:30.756 UTC [37539][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-10 05:42:30.756 UTC [37539][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-10 05:42:30.757 UTC [37539][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-10 05:42:30.759 UTC [37539][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=20686 2024-03-10 05:42:30.767 UTC [37550][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20687 2024-03-10 05:42:30.769 UTC [37550][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-10 05:42:30.776 UTC [37552][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20688 2024-03-10 05:42:30.782 UTC [37552][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-10 05:42:30.782 UTC [37552][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-10 05:42:30.782 UTC [37552][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-03-10 05:42:30.782 UTC [37552][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-10 05:42:30.788 UTC [37554][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20689 2024-03-10 05:42:30.794 UTC [37554][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-10 05:42:30.794 UTC [37554][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-10 05:42:30.802 UTC [37556][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20690 2024-03-10 05:42:30.808 UTC [37556][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-10 05:42:30.808 UTC [37556][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-10 05:42:30.812 UTC [37556][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_iRfI/client.key$$ 2024-03-10 05:42:30.812 UTC [37556][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=verifydb host=localhost port=20690 2024-03-10 05:42:30.820 UTC [37558][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20691 2024-03-10 05:42:30.825 UTC [37558][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-10 05:42:30.825 UTC [37558][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-03-10 05:42:30.825 UTC [37558][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-03-10 05:42:30.825 UTC [37558][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-10 05:42:30.834 UTC [37560][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20692 2024-03-10 05:42:30.839 UTC [37560][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-10 05:42:30.839 UTC [37560][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-10 05:42:30.840 UTC [37560][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_iRfI/client.key$$ 2024-03-10 05:42:30.840 UTC [37560][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=yetanotheruser database=verifydb host=localhost port=20692 2024-03-10 05:42:30.844 UTC [37465][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:30.844 UTC [37465][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:30.847 UTC [37465][postmaster] LOG: background worker "logical replication launcher" (PID 37471) exited with exit code 1 2024-03-10 05:42:30.848 UTC [37466][checkpointer] LOG: shutting down 2024-03-10 05:42:30.848 UTC [37466][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:30.848 UTC [37466][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/413EDD8, redo lsn=0/413EDD8 2024-03-10 05:42:30.850 UTC [37465][postmaster] LOG: database system is shut down 2024-03-10 05:42:30.963 UTC [37573][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:30.963 UTC [37573][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:30.963 UTC [37573][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:30.965 UTC [37578][startup] LOG: database system was shut down at 2024-03-10 05:42:30 UTC 2024-03-10 05:42:30.968 UTC [37573][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:31.066 UTC [37596][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20694 2024-03-10 05:42:31.072 UTC [37596][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-10 05:42:31.072 UTC [37596][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-10 05:42:31.077 UTC [37596][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_iRfI/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-10 05:42:31.077 UTC [37596][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=20694 2024-03-10 05:42:31.085 UTC [37598][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20695 2024-03-10 05:42:31.090 UTC [37598][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-10 05:42:31.090 UTC [37598][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-10 05:42:31.099 UTC [37603][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20697 2024-03-10 05:42:31.104 UTC [37603][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-10 05:42:31.104 UTC [37603][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-10 05:42:31.108 UTC [37573][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:31.108 UTC [37573][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:31.109 UTC [37573][postmaster] LOG: background worker "logical replication launcher" (PID 37581) exited with exit code 1 2024-03-10 05:42:31.109 UTC [37576][checkpointer] LOG: shutting down 2024-03-10 05:42:31.109 UTC [37576][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:31.110 UTC [37576][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/413EE50, redo lsn=0/413EE50 2024-03-10 05:42:31.111 UTC [37573][postmaster] LOG: database system is shut down 2024-03-10 05:42:31.220 UTC [37607][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:31.220 UTC [37607][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:31.220 UTC [37607][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:31.222 UTC [37615][startup] LOG: database system was shut down at 2024-03-10 05:42:31 UTC 2024-03-10 05:42:31.224 UTC [37607][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:31.322 UTC [37623][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20698 2024-03-10 05:42:31.327 UTC [37623][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-10 05:42:31.327 UTC [37623][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-10 05:42:31.331 UTC [37607][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:31.331 UTC [37607][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:31.333 UTC [37607][postmaster] LOG: background worker "logical replication launcher" (PID 37619) exited with exit code 1 2024-03-10 05:42:31.333 UTC [37611][checkpointer] LOG: shutting down 2024-03-10 05:42:31.333 UTC [37611][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:31.334 UTC [37611][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/413EEC8, redo lsn=0/413EEC8 2024-03-10 05:42:31.335 UTC [37607][postmaster] LOG: database system is shut down 2024-03-10 05:42:31.450 UTC [37627][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:31.450 UTC [37627][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:31.450 UTC [37627][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:31.451 UTC [37633][startup] LOG: database system was shut down at 2024-03-10 05:42:31 UTC 2024-03-10 05:42:31.454 UTC [37627][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:31.552 UTC [37643][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20700 2024-03-10 05:42:31.558 UTC [37643][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-10 05:42:31.558 UTC [37643][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-10 05:42:31.565 UTC [37646][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20702 2024-03-10 05:42:31.570 UTC [37646][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-10 05:42:31.570 UTC [37646][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-10 05:42:31.574 UTC [37627][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:31.574 UTC [37627][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:31.575 UTC [37627][postmaster] LOG: background worker "logical replication launcher" (PID 37639) exited with exit code 1 2024-03-10 05:42:31.575 UTC [37631][checkpointer] LOG: shutting down 2024-03-10 05:42:31.575 UTC [37631][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:31.576 UTC [37631][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/413EF40, redo lsn=0/413EF40 2024-03-10 05:42:31.577 UTC [37627][postmaster] LOG: database system is shut down 2024-03-10 05:42:31.691 UTC [37648][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:31.691 UTC [37648][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:31.691 UTC [37648][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:31.693 UTC [37651][startup] LOG: database system was shut down at 2024-03-10 05:42:31 UTC 2024-03-10 05:42:31.695 UTC [37648][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:31.796 UTC [37656][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20703 2024-03-10 05:42:31.798 UTC [37656][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:42:31.798 UTC [37656][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:42:31.807 UTC [37658][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20704 2024-03-10 05:42:31.812 UTC [37658][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-10 05:42:31.812 UTC [37658][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-10 05:42:31.816 UTC [37658][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-10 05:42:31.817 UTC [37658][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20704 2024-03-10 05:42:31.820 UTC [37648][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:31.820 UTC [37648][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:31.821 UTC [37648][postmaster] LOG: background worker "logical replication launcher" (PID 37654) exited with exit code 1 2024-03-10 05:42:31.821 UTC [37649][checkpointer] LOG: shutting down 2024-03-10 05:42:31.822 UTC [37649][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:31.822 UTC [37649][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/413EFB8, redo lsn=0/413EFB8 2024-03-10 05:42:31.823 UTC [37648][postmaster] LOG: database system is shut down 2024-03-10 05:42:31.938 UTC [37660][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:31.938 UTC [37660][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:31.938 UTC [37660][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:31.940 UTC [37663][startup] LOG: database system was shut down at 2024-03-10 05:42:31 UTC 2024-03-10 05:42:31.944 UTC [37660][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:32.041 UTC [37668][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20705 2024-03-10 05:42:32.043 UTC [37668][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:42:32.043 UTC [37668][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:42:32.051 UTC [37670][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20706 2024-03-10 05:42:32.056 UTC [37670][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-10 05:42:32.056 UTC [37670][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-10 05:42:32.060 UTC [37670][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-10 05:42:32.060 UTC [37670][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20706 2024-03-10 05:42:32.064 UTC [37660][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:32.064 UTC [37660][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:32.066 UTC [37660][postmaster] LOG: background worker "logical replication launcher" (PID 37666) exited with exit code 1 2024-03-10 05:42:32.066 UTC [37661][checkpointer] LOG: shutting down 2024-03-10 05:42:32.066 UTC [37661][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:32.066 UTC [37661][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/413F030, redo lsn=0/413F030 2024-03-10 05:42:32.068 UTC [37660][postmaster] LOG: database system is shut down 2024-03-10 05:42:32.182 UTC [37672][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:32.182 UTC [37672][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:32.182 UTC [37672][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:32.183 UTC [37675][startup] LOG: database system was shut down at 2024-03-10 05:42:32 UTC 2024-03-10 05:42:32.186 UTC [37672][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:32.284 UTC [37680][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20707 2024-03-10 05:42:32.286 UTC [37680][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:42:32.286 UTC [37680][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:42:32.294 UTC [37682][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20708 2024-03-10 05:42:32.298 UTC [37682][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-10 05:42:32.298 UTC [37682][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-10 05:42:32.302 UTC [37682][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-10 05:42:32.303 UTC [37682][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20708 2024-03-10 05:42:32.306 UTC [37672][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:32.306 UTC [37672][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:32.307 UTC [37672][postmaster] LOG: background worker "logical replication launcher" (PID 37678) exited with exit code 1 2024-03-10 05:42:32.307 UTC [37673][checkpointer] LOG: shutting down 2024-03-10 05:42:32.307 UTC [37673][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:32.308 UTC [37673][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/413F0A8, redo lsn=0/413F0A8 2024-03-10 05:42:32.309 UTC [37672][postmaster] LOG: database system is shut down 2024-03-10 05:42:32.423 UTC [37684][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:32.423 UTC [37684][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:32.424 UTC [37684][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:32.425 UTC [37687][startup] LOG: database system was shut down at 2024-03-10 05:42:32 UTC 2024-03-10 05:42:32.428 UTC [37684][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:32.524 UTC [37692][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20709 2024-03-10 05:42:32.525 UTC [37692][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:42:32.525 UTC [37692][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:42:32.533 UTC [37694][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20710 2024-03-10 05:42:32.538 UTC [37694][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-10 05:42:32.538 UTC [37694][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-10 05:42:32.542 UTC [37694][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-10 05:42:32.542 UTC [37694][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20710 2024-03-10 05:42:32.546 UTC [37684][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:32.546 UTC [37684][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:32.547 UTC [37684][postmaster] LOG: background worker "logical replication launcher" (PID 37690) exited with exit code 1 2024-03-10 05:42:32.548 UTC [37685][checkpointer] LOG: shutting down 2024-03-10 05:42:32.548 UTC [37685][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:32.548 UTC [37685][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/413F120, redo lsn=0/413F120 2024-03-10 05:42:32.549 UTC [37684][postmaster] LOG: database system is shut down 2024-03-10 05:42:32.664 UTC [37696][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:32.664 UTC [37696][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:32.664 UTC [37696][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:32.666 UTC [37699][startup] LOG: database system was shut down at 2024-03-10 05:42:32 UTC 2024-03-10 05:42:32.669 UTC [37696][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:32.767 UTC [37704][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20711 2024-03-10 05:42:32.768 UTC [37704][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:42:32.768 UTC [37704][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:42:32.778 UTC [37706][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20712 2024-03-10 05:42:32.782 UTC [37706][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-10 05:42:32.782 UTC [37706][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-10 05:42:32.786 UTC [37706][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-10 05:42:32.787 UTC [37706][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20712 2024-03-10 05:42:32.790 UTC [37696][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:32.790 UTC [37696][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:32.791 UTC [37696][postmaster] LOG: background worker "logical replication launcher" (PID 37702) exited with exit code 1 2024-03-10 05:42:32.792 UTC [37697][checkpointer] LOG: shutting down 2024-03-10 05:42:32.792 UTC [37697][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:32.792 UTC [37697][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/413F198, redo lsn=0/413F198 2024-03-10 05:42:32.794 UTC [37696][postmaster] LOG: database system is shut down 2024-03-10 05:42:32.907 UTC [37708][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:32.907 UTC [37708][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:32.907 UTC [37708][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:32.909 UTC [37711][startup] LOG: database system was shut down at 2024-03-10 05:42:32 UTC 2024-03-10 05:42:32.912 UTC [37708][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:33.011 UTC [37716][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20713 2024-03-10 05:42:33.012 UTC [37716][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:42:33.012 UTC [37716][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:42:33.021 UTC [37718][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20714 2024-03-10 05:42:33.025 UTC [37718][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-10 05:42:33.025 UTC [37718][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-10 05:42:33.030 UTC [37718][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-10 05:42:33.030 UTC [37718][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20714 2024-03-10 05:42:33.034 UTC [37708][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:33.034 UTC [37708][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:33.035 UTC [37708][postmaster] LOG: background worker "logical replication launcher" (PID 37714) exited with exit code 1 2024-03-10 05:42:33.035 UTC [37709][checkpointer] LOG: shutting down 2024-03-10 05:42:33.035 UTC [37709][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:33.035 UTC [37709][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/413F210, redo lsn=0/413F210 2024-03-10 05:42:33.037 UTC [37708][postmaster] LOG: database system is shut down 2024-03-10 05:42:33.151 UTC [37720][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:33.151 UTC [37720][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:33.151 UTC [37720][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:33.153 UTC [37723][startup] LOG: database system was shut down at 2024-03-10 05:42:33 UTC 2024-03-10 05:42:33.155 UTC [37720][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:33.254 UTC [37728][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20715 2024-03-10 05:42:33.255 UTC [37728][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:42:33.255 UTC [37728][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:42:33.263 UTC [37730][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20716 2024-03-10 05:42:33.267 UTC [37730][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-10 05:42:33.267 UTC [37730][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-10 05:42:33.271 UTC [37730][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-10 05:42:33.272 UTC [37730][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=20716 2024-03-10 05:42:33.275 UTC [37720][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:33.275 UTC [37720][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:33.275 UTC [37720][postmaster] LOG: background worker "logical replication launcher" (PID 37726) exited with exit code 1 2024-03-10 05:42:33.276 UTC [37721][checkpointer] LOG: shutting down 2024-03-10 05:42:33.276 UTC [37721][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:33.276 UTC [37721][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/413F288, redo lsn=0/413F288 2024-03-10 05:42:33.278 UTC [37720][postmaster] LOG: database system is shut down 2024-03-10 05:42:33.392 UTC [37732][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:33.392 UTC [37732][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:33.392 UTC [37732][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:33.393 UTC [37735][startup] LOG: database system was shut down at 2024-03-10 05:42:33 UTC 2024-03-10 05:42:33.396 UTC [37732][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:33.494 UTC [37740][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20717 2024-03-10 05:42:33.495 UTC [37740][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:42:33.495 UTC [37740][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:42:33.502 UTC [37742][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20718 2024-03-10 05:42:33.507 UTC [37742][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-10 05:42:33.507 UTC [37742][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-10 05:42:33.511 UTC [37742][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-10 05:42:33.511 UTC [37742][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=20718 2024-03-10 05:42:33.514 UTC [37732][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:33.514 UTC [37732][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:33.515 UTC [37732][postmaster] LOG: background worker "logical replication launcher" (PID 37738) exited with exit code 1 2024-03-10 05:42:33.515 UTC [37733][checkpointer] LOG: shutting down 2024-03-10 05:42:33.515 UTC [37733][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:33.516 UTC [37733][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/413F300, redo lsn=0/413F300 2024-03-10 05:42:33.517 UTC [37732][postmaster] LOG: database system is shut down 2024-03-10 05:42:33.631 UTC [37756][postmaster] LOG: starting PostgreSQL 17devel on x86_64-freebsd, compiled by clang-14.0.5, 64-bit 2024-03-10 05:42:33.631 UTC [37756][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 53547 2024-03-10 05:42:33.631 UTC [37756][postmaster] LOG: listening on Unix socket "/tmp/gWSrsIcMtf/.s.PGSQL.53547" 2024-03-10 05:42:33.632 UTC [37759][startup] LOG: database system was shut down at 2024-03-10 05:42:33 UTC 2024-03-10 05:42:33.635 UTC [37756][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:42:33.735 UTC [37764][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20720 2024-03-10 05:42:33.737 UTC [37764][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:42:33.737 UTC [37764][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:42:33.745 UTC [37766][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=20721 2024-03-10 05:42:33.749 UTC [37766][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-10 05:42:33.749 UTC [37766][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-10 05:42:33.753 UTC [37766][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-10 05:42:33.753 UTC [37766][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=20721 2024-03-10 05:42:33.756 UTC [37756][postmaster] LOG: received fast shutdown request 2024-03-10 05:42:33.756 UTC [37756][postmaster] LOG: aborting any active transactions 2024-03-10 05:42:33.758 UTC [37756][postmaster] LOG: background worker "logical replication launcher" (PID 37762) exited with exit code 1 2024-03-10 05:42:33.758 UTC [37757][checkpointer] LOG: shutting down 2024-03-10 05:42:33.758 UTC [37757][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:42:33.758 UTC [37757][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/413F378, redo lsn=0/413F378 2024-03-10 05:42:33.760 UTC [37756][postmaster] LOG: database system is shut down 2024-03-10 05:42:33.871 UTC [37768][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-03-10 05:42:33.871 UTC [37768][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [05:42:33.971](0.216s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"