[00:37:53.102](0.036s) # setting up data directory # Checking port 61783 # Found port 61783 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=61783 host=/tmp/gb_pIcEHyE Log file: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [00:37:53.108](0.006s) # 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 48595 [00:37:53.338](0.230s) ok 1 - ssl_library parameter ### 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 48910 [00:37:54.417](1.079s) # 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-06 00:37:53.235 UTC [48595][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:53.235 UTC [48595][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:53.237 UTC [48602][startup] LOG: database system was shut down at 2024-03-06 00:35:30 UTC 2024-03-06 00:37:53.240 UTC [48595][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:53.330 UTC [48642][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.331 UTC [48642][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-06 00:37:53.331 UTC [48642][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.336 UTC [48642][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-06 00:37:53.337 UTC [48642][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.345 UTC [48652][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.346 UTC [48652][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-06 00:37:53.346 UTC [48652][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.348 UTC [48652][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-06 00:37:53.349 UTC [48652][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.416 UTC [48668][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.418 UTC [48668][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-06 00:37:53.418 UTC [48668][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.419 UTC [48668][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-06 00:37:53.420 UTC [48668][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.464 UTC [48692][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.465 UTC [48692][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-06 00:37:53.465 UTC [48692][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.466 UTC [48692][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-06 00:37:53.466 UTC [48692][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.499 UTC [48711][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.500 UTC [48711][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-06 00:37:53.500 UTC [48711][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.501 UTC [48711][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-06 00:37:53.502 UTC [48711][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.545 UTC [48720][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.546 UTC [48720][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-06 00:37:53.546 UTC [48720][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.547 UTC [48720][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-06 00:37:53.578 UTC [48720][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.033 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.684 UTC [48764][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.685 UTC [48764][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-06 00:37:53.685 UTC [48764][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.686 UTC [48764][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-06 00:37:53.733 UTC [48764][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.049 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.796 UTC [48796][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.797 UTC [48796][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-06 00:37:53.797 UTC [48796][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.797 UTC [48796][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-06 00:37:53.823 UTC [48796][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.842 UTC [48803][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.842 UTC [48803][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-06 00:37:53.842 UTC [48803][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.843 UTC [48803][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-06 00:37:53.940 UTC [48803][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.098 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.969 UTC [48821][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.969 UTC [48821][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-06 00:37:53.969 UTC [48821][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.970 UTC [48821][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-06 00:37:54.009 UTC [48821][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local] 2024-03-06 00:37:54.107 UTC [48843][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:54.108 UTC [48843][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-06 00:37:54.108 UTC [48843][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:54.108 UTC [48843][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-06 00:37:54.180 UTC [48843][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.073 user=postgres database=postgres host=[local] 2024-03-06 00:37:54.213 UTC [48595][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:54.213 UTC [48595][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:54.215 UTC [48595][postmaster] LOG: background worker "logical replication launcher" (PID 48605) exited with exit code 1 2024-03-06 00:37:54.216 UTC [48600][checkpointer] LOG: shutting down 2024-03-06 00:37:54.216 UTC [48600][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:54.253 UTC [48600][checkpointer] LOG: checkpoint complete: wrote 5650 buffers (34.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.037 s, sync=0.001 s, total=0.038 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45479 kB, estimate=45479 kB; lsn=0/4196518, redo lsn=0/4196518 2024-03-06 00:37:54.264 UTC [48595][postmaster] LOG: database system is shut down 2024-03-06 00:37:54.335 UTC [48910][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:54.335 UTC [48910][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:54.335 UTC [48910][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:54.337 UTC [48913][startup] LOG: database system was shut down at 2024-03-06 00:37:54 UTC 2024-03-06 00:37:54.340 UTC [48910][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:54.419 UTC [48910][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:54.419 UTC [48910][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:54.421 UTC [48910][postmaster] LOG: background worker "logical replication launcher" (PID 48916) exited with exit code 1 2024-03-06 00:37:54.421 UTC [48911][checkpointer] LOG: shutting down 2024-03-06 00:37:54.421 UTC [48911][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:54.422 UTC [48911][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/4196590, redo lsn=0/4196590 2024-03-06 00:37:54.424 UTC [48910][postmaster] LOG: database system is shut down 2024-03-06 00:37:54.546 UTC [48966][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-06 00:37:54.546 UTC [48966][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [00:37:54.622](0.205s) 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 48991 [00:37:54.726](0.104s) 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-06 00:37:53.235 UTC [48595][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:53.235 UTC [48595][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:53.237 UTC [48602][startup] LOG: database system was shut down at 2024-03-06 00:35:30 UTC 2024-03-06 00:37:53.240 UTC [48595][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:53.330 UTC [48642][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.331 UTC [48642][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-06 00:37:53.331 UTC [48642][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.336 UTC [48642][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-06 00:37:53.337 UTC [48642][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.345 UTC [48652][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.346 UTC [48652][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-06 00:37:53.346 UTC [48652][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.348 UTC [48652][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-06 00:37:53.349 UTC [48652][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.416 UTC [48668][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.418 UTC [48668][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-06 00:37:53.418 UTC [48668][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.419 UTC [48668][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-06 00:37:53.420 UTC [48668][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.464 UTC [48692][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.465 UTC [48692][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-06 00:37:53.465 UTC [48692][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.466 UTC [48692][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-06 00:37:53.466 UTC [48692][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.499 UTC [48711][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.500 UTC [48711][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-06 00:37:53.500 UTC [48711][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.501 UTC [48711][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-06 00:37:53.502 UTC [48711][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.545 UTC [48720][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.546 UTC [48720][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-06 00:37:53.546 UTC [48720][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.547 UTC [48720][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-06 00:37:53.578 UTC [48720][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.033 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.684 UTC [48764][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.685 UTC [48764][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-06 00:37:53.685 UTC [48764][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.686 UTC [48764][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-06 00:37:53.733 UTC [48764][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.049 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.796 UTC [48796][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.797 UTC [48796][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-06 00:37:53.797 UTC [48796][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.797 UTC [48796][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-06 00:37:53.823 UTC [48796][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.842 UTC [48803][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.842 UTC [48803][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-06 00:37:53.842 UTC [48803][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.843 UTC [48803][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-06 00:37:53.940 UTC [48803][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.098 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.969 UTC [48821][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.969 UTC [48821][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-06 00:37:53.969 UTC [48821][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.970 UTC [48821][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-06 00:37:54.009 UTC [48821][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local] 2024-03-06 00:37:54.107 UTC [48843][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:54.108 UTC [48843][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-06 00:37:54.108 UTC [48843][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:54.108 UTC [48843][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-06 00:37:54.180 UTC [48843][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.073 user=postgres database=postgres host=[local] 2024-03-06 00:37:54.213 UTC [48595][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:54.213 UTC [48595][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:54.215 UTC [48595][postmaster] LOG: background worker "logical replication launcher" (PID 48605) exited with exit code 1 2024-03-06 00:37:54.216 UTC [48600][checkpointer] LOG: shutting down 2024-03-06 00:37:54.216 UTC [48600][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:54.253 UTC [48600][checkpointer] LOG: checkpoint complete: wrote 5650 buffers (34.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.037 s, sync=0.001 s, total=0.038 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45479 kB, estimate=45479 kB; lsn=0/4196518, redo lsn=0/4196518 2024-03-06 00:37:54.264 UTC [48595][postmaster] LOG: database system is shut down 2024-03-06 00:37:54.335 UTC [48910][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:54.335 UTC [48910][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:54.335 UTC [48910][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:54.337 UTC [48913][startup] LOG: database system was shut down at 2024-03-06 00:37:54 UTC 2024-03-06 00:37:54.340 UTC [48910][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:54.419 UTC [48910][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:54.419 UTC [48910][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:54.421 UTC [48910][postmaster] LOG: background worker "logical replication launcher" (PID 48916) exited with exit code 1 2024-03-06 00:37:54.421 UTC [48911][checkpointer] LOG: shutting down 2024-03-06 00:37:54.421 UTC [48911][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:54.422 UTC [48911][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/4196590, redo lsn=0/4196590 2024-03-06 00:37:54.424 UTC [48910][postmaster] LOG: database system is shut down 2024-03-06 00:37:54.546 UTC [48966][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-06 00:37:54.546 UTC [48966][postmaster] LOG: database system is shut down 2024-03-06 00:37:54.660 UTC [48991][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:54.660 UTC [48991][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:54.661 UTC [48991][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:54.663 UTC [49005][startup] LOG: database system was shut down at 2024-03-06 00:37:54 UTC 2024-03-06 00:37:54.668 UTC [48991][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:54.728 UTC [48991][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:54.729 UTC [48991][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:54.730 UTC [48991][postmaster] LOG: background worker "logical replication launcher" (PID 49009) exited with exit code 1 2024-03-06 00:37:54.731 UTC [49003][checkpointer] LOG: shutting down 2024-03-06 00:37:54.731 UTC [49003][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:54.731 UTC [49003][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/4196608, redo lsn=0/4196608 2024-03-06 00:37:54.735 UTC [48991][postmaster] LOG: database system is shut down 2024-03-06 00:37:54.843 UTC [49058][postmaster] FATAL: could not set SSL protocol version range 2024-03-06 00:37:54.843 UTC [49058][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-06 00:37:54.843 UTC [49058][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [00:37:54.930](0.204s) 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 49077 [00:37:55.033](0.103s) ok 5 - restart succeeds with correct SSL protocol bounds [00:37:55.034](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 49122 [00:37:55.248](0.214s) ok 6 - server doesn't accept non-SSL connections [00:37:55.248](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [00:37:55.266](0.018s) ok 8 - connect without server root cert sslmode=require [00:37:55.266](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [00:37:55.277](0.011s) ok 10 - connect without server root cert sslmode=verify-ca [00:37:55.277](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [00:37:55.287](0.010s) ok 12 - connect without server root cert sslmode=verify-full [00:37:55.288](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [00:37:55.314](0.026s) ok 14 - connect with wrong server root cert sslmode=require [00:37:55.314](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [00:37:55.328](0.014s) ok 16 - connect with wrong server root cert sslmode=verify-ca [00:37:55.329](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [00:37:55.341](0.013s) ok 18 - connect with wrong server root cert sslmode=verify-full [00:37:55.341](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [00:37:55.356](0.014s) ok 20 - connect with server CA cert, without root CA [00:37:55.356](0.000s) ok 21 - connect with server CA cert, without root CA: matches [00:37:55.371](0.015s) ok 22 - connect with correct server CA cert file sslmode=require [00:37:55.372](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [00:37:55.386](0.015s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [00:37:55.387](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [00:37:55.402](0.016s) ok 26 - connect with correct server CA cert file sslmode=verify-full [00:37:55.403](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [00:37:55.421](0.019s) ok 28 - cert root file that contains two certificates, order 1 [00:37:55.422](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [00:37:55.437](0.016s) ok 30 - cert root file that contains two certificates, order 2 [00:37:55.438](0.001s) ok 31 - cert root file that contains two certificates, order 2: no stderr [00:37:55.453](0.016s) ok 32 - connect with sslcertmode=disable [00:37:55.454](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [00:37:55.468](0.014s) ok 34 - connect with sslcertmode=allow [00:37:55.468](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [00:37:55.482](0.014s) ok 36 - connect with sslcertmode=require fails without a client certificate [00:37:55.482](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [00:37:55.496](0.014s) ok 38 - sslcrl option with invalid file name [00:37:55.497](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [00:37:55.508](0.012s) ok 40 - CRL belonging to a different CA [00:37:55.509](0.000s) ok 41 - CRL belonging to a different CA: matches [00:37:55.522](0.014s) ok 42 - directory CRL belonging to a different CA [00:37:55.523](0.000s) ok 43 - directory CRL belonging to a different CA: matches [00:37:55.539](0.017s) ok 44 - CRL with a non-revoked cert [00:37:55.539](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [00:37:55.555](0.016s) ok 46 - directory CRL with a non-revoked cert [00:37:55.555](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [00:37:55.571](0.016s) ok 48 - mismatch between host name and server certificate sslmode=require [00:37:55.571](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [00:37:55.586](0.015s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [00:37:55.586](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [00:37:55.598](0.012s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [00:37:55.599](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 49239 [00:37:55.824](0.225s) ok 54 - IP address in the Common Name [00:37:55.824](0.000s) ok 55 - IP address in the Common Name: no stderr [00:37:55.838](0.013s) ok 56 - mismatch between host name and server certificate IP address [00:37:55.838](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 49265 [00:37:56.063](0.225s) ok 58 - IP address in a dNSName [00:37:56.063](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 49287 [00:37:56.287](0.224s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [00:37:56.287](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [00:37:56.302](0.015s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [00:37:56.302](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [00:37:56.316](0.013s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [00:37:56.316](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [00:37:56.330](0.014s) ok 66 - host name not matching with X.509 Subject Alternative Names [00:37:56.330](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [00:37:56.342](0.012s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [00:37:56.342](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 49317 [00:37:56.565](0.223s) ok 70 - host name matching with a single X.509 Subject Alternative Name [00:37:56.565](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [00:37:56.578](0.013s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [00:37:56.578](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [00:37:56.591](0.012s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [00:37:56.591](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 49348 [00:37:56.817](0.226s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [00:37:56.817](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [00:37:56.832](0.015s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [00:37:56.832](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [00:37:56.844](0.012s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [00:37:56.845](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [00:37:56.858](0.013s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [00:37:56.858](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [00:37:56.873](0.015s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [00:37:56.873](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [00:37:56.888](0.015s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [00:37:56.888](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [00:37:56.901](0.013s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [00:37:56.902](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [00:37:56.914](0.013s) ok 90 - IPv6 host with CIDR mask does not match [00:37:56.915](0.000s) ok 91 - IPv6 host with CIDR mask does not match: matches ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 49375 [00:37:57.138](0.223s) ok 92 - certificate with both a CN and SANs 1 [00:37:57.138](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [00:37:57.154](0.015s) ok 94 - certificate with both a CN and SANs 2 [00:37:57.154](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [00:37:57.167](0.013s) ok 96 - certificate with both a CN and SANs ignores CN [00:37:57.167](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 49390 [00:37:57.393](0.226s) ok 98 - certificate with both a CN and IP SANs matches CN [00:37:57.393](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [00:37:57.407](0.014s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [00:37:57.407](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [00:37:57.421](0.014s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [00:37:57.421](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 49404 [00:37:57.646](0.224s) ok 104 - certificate with both an IP CN and IP SANs 1 [00:37:57.646](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [00:37:57.660](0.015s) ok 106 - certificate with both an IP CN and IP SANs 2 [00:37:57.661](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [00:37:57.673](0.012s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [00:37:57.673](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 49418 [00:37:57.896](0.223s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [00:37:57.896](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [00:37:57.912](0.015s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [00:37:57.912](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [00:37:57.927](0.014s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [00:37:57.927](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 49432 [00:37:58.150](0.223s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [00:37:58.150](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [00:37:58.163](0.013s) ok 118 - server certificate without CN or SANs sslmode=verify-full [00:37:58.164](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 49444 [00:37:58.381](0.218s) ok 120 - sslrootcert=system does not connect with private CA [00:37:58.381](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [00:37:58.388](0.007s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [00:37:58.389](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [00:37:58.408](0.019s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [00:37:58.408](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [00:37:58.420](0.012s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [00:37:58.420](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 49459 [00:37:58.644](0.224s) ok 128 - connects without client-side CRL [00:37:58.644](0.000s) ok 129 - connects without client-side CRL: no stderr [00:37:58.657](0.013s) ok 130 - does not connect with client-side CRL file [00:37:58.657](0.000s) ok 131 - does not connect with client-side CRL file: matches [00:37:58.670](0.012s) ok 132 - does not connect with client-side CRL directory [00:37:58.670](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() [00:37:58.686](0.016s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [00:37:58.686](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [00:37:58.686](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [00:37:58.701](0.015s) ok 137 - connection success with correct range of TLS protocol versions [00:37:58.701](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [00:37:58.710](0.009s) ok 139 - connection failure with incorrect range of TLS protocol versions [00:37:58.710](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [00:37:58.717](0.008s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [00:37:58.718](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [00:37:58.725](0.007s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [00:37:58.725](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [00:37:58.726](0.000s) # running server tests [00:37:58.739](0.014s) ok 145 - certificate authorization fails without client cert [00:37:58.740](0.000s) ok 146 - certificate authorization fails without client cert: matches [00:37:58.761](0.021s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [00:37:58.761](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [00:37:58.778](0.017s) ok 149 - certificate authorization succeeds with correct client cert in DER format [00:37:58.778](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [00:37:58.797](0.019s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [00:37:58.797](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [00:37:58.816](0.018s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [00:37:58.816](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [00:37:58.834](0.018s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [00:37:58.834](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [00:37:58.852](0.018s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [00:37:58.852](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [00:37:58.868](0.015s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [00:37:58.868](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [00:37:58.880](0.012s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [00:37:58.880](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [00:37:58.902](0.022s) ok 163 - certificate authorization succeeds with DN mapping [00:37:58.903](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [00:37:58.903](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [00:37:58.924](0.021s) ok 166 - certificate authorization succeeds with DN regex mapping [00:37:58.924](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [00:37:58.947](0.023s) ok 168 - certificate authorization succeeds with CN mapping [00:37:58.947](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [00:37:58.947](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [00:37:58.948](0.000s) not ok 171 # TODO & SKIP Need Pty support [00:37:58.948](0.000s) not ok 172 # TODO & SKIP Need Pty support [00:37:58.948](0.000s) not ok 173 # TODO & SKIP Need Pty support [00:37:58.948](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_QruS/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [00:37:58.978](0.030s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [00:37:58.978](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [00:37:58.978](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [00:37:58.988](0.010s) ok 178 - certificate authorization fails because of file permissions [00:37:58.988](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [00:37:59.005](0.017s) ok 180 - certificate authorization fails with client cert belonging to another user [00:37:59.005](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [00:37:59.020](0.015s) ok 182 - certificate authorization fails with revoked client cert [00:37:59.020](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [00:37:59.020](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [00:37:59.042](0.021s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [00:37:59.042](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [00:37:59.042](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [00:37:59.058](0.015s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [00:37:59.058](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [00:37:59.058](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [00:37:59.074](0.016s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [00:37:59.074](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [00:37:59.075](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 49519 [00:37:59.301](0.226s) ok 194 - intermediate client certificate is provided by client [00:37:59.301](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [00:37:59.315](0.014s) ok 196 - intermediate client certificate is missing [00:37:59.316](0.000s) ok 197 - intermediate client certificate is missing: matches [00:37:59.330](0.015s) ok 198 - logged client certificate Subjects are truncated if they're too long [00:37:59.331](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 49533 [00:37:59.549](0.218s) ok 200 - intermediate client certificate is untrusted [00:37:59.549](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 49543 [00:37:59.768](0.219s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [00:37:59.768](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [00:37:59.782](0.014s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [00:37:59.782](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 49555 [00:37:59.999](0.217s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [00:37:59.999](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. [00:38:00.000](0.000s) # got: '2' # expected: '0' [00:38:00.000](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [00:38:00.000](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. [00:38:00.000](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 61783 failed: server closed the connection unexpectedly # This probably means the server terminated abnormally # before or while processing the request. # SSL SYSCALL error: Connection reset by peer' # expected: '' [00:38:00.020](0.020s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [00:38:00.020](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 49567 [00:38:00.237](0.217s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [00:38:00.256](0.019s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [00:38:00.256](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 49579 [00:38:00.473](0.216s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [00:38:00.492](0.019s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [00:38:00.492](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 49591 [00:38:00.712](0.220s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [00:38:00.732](0.020s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [00:38:00.732](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 49603 [00:38:00.949](0.216s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [00:38:00.949](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. [00:38:00.949](0.000s) # got: '2' # expected: '0' [00:38:00.949](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [00:38:00.949](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. [00:38:00.949](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 61783 failed: server closed the connection unexpectedly # This probably means the server terminated abnormally # before or while processing the request. # SSL SYSCALL error: Connection reset by peer' # expected: '' [00:38:00.969](0.019s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [00:38:00.969](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 49615 [00:38:01.187](0.218s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [00:38:01.209](0.022s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [00:38:01.209](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 49627 [00:38:01.426](0.217s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [00:38:01.445](0.019s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [00:38:01.445](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 49639 [00:38:01.661](0.216s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [00:38:01.680](0.018s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [00:38:01.680](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 49651 [00:38:01.896](0.217s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [00:38:01.917](0.020s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [00:38:01.917](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-06 00:37:53.235 UTC [48595][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:53.235 UTC [48595][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:53.237 UTC [48602][startup] LOG: database system was shut down at 2024-03-06 00:35:30 UTC 2024-03-06 00:37:53.240 UTC [48595][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:53.330 UTC [48642][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.331 UTC [48642][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-06 00:37:53.331 UTC [48642][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.336 UTC [48642][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-06 00:37:53.337 UTC [48642][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.345 UTC [48652][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.346 UTC [48652][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-06 00:37:53.346 UTC [48652][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.348 UTC [48652][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-06 00:37:53.349 UTC [48652][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.416 UTC [48668][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.418 UTC [48668][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-06 00:37:53.418 UTC [48668][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.419 UTC [48668][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-06 00:37:53.420 UTC [48668][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.464 UTC [48692][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.465 UTC [48692][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-06 00:37:53.465 UTC [48692][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.466 UTC [48692][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-06 00:37:53.466 UTC [48692][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.499 UTC [48711][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.500 UTC [48711][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-06 00:37:53.500 UTC [48711][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.501 UTC [48711][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-06 00:37:53.502 UTC [48711][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.545 UTC [48720][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.546 UTC [48720][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-06 00:37:53.546 UTC [48720][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.547 UTC [48720][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-06 00:37:53.578 UTC [48720][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.033 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.684 UTC [48764][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.685 UTC [48764][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-06 00:37:53.685 UTC [48764][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.686 UTC [48764][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-06 00:37:53.733 UTC [48764][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.049 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.796 UTC [48796][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.797 UTC [48796][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-06 00:37:53.797 UTC [48796][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.797 UTC [48796][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-06 00:37:53.823 UTC [48796][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.026 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.842 UTC [48803][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.842 UTC [48803][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-06 00:37:53.842 UTC [48803][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.843 UTC [48803][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-06 00:37:53.940 UTC [48803][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.098 user=postgres database=postgres host=[local] 2024-03-06 00:37:53.969 UTC [48821][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:53.969 UTC [48821][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-06 00:37:53.969 UTC [48821][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:53.970 UTC [48821][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-06 00:37:54.009 UTC [48821][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.040 user=postgres database=postgres host=[local] 2024-03-06 00:37:54.107 UTC [48843][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-06 00:37:54.108 UTC [48843][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-06 00:37:54.108 UTC [48843][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-06 00:37:54.108 UTC [48843][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-06 00:37:54.180 UTC [48843][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.073 user=postgres database=postgres host=[local] 2024-03-06 00:37:54.213 UTC [48595][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:54.213 UTC [48595][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:54.215 UTC [48595][postmaster] LOG: background worker "logical replication launcher" (PID 48605) exited with exit code 1 2024-03-06 00:37:54.216 UTC [48600][checkpointer] LOG: shutting down 2024-03-06 00:37:54.216 UTC [48600][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:54.253 UTC [48600][checkpointer] LOG: checkpoint complete: wrote 5650 buffers (34.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.037 s, sync=0.001 s, total=0.038 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45479 kB, estimate=45479 kB; lsn=0/4196518, redo lsn=0/4196518 2024-03-06 00:37:54.264 UTC [48595][postmaster] LOG: database system is shut down 2024-03-06 00:37:54.335 UTC [48910][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:54.335 UTC [48910][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:54.335 UTC [48910][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:54.337 UTC [48913][startup] LOG: database system was shut down at 2024-03-06 00:37:54 UTC 2024-03-06 00:37:54.340 UTC [48910][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:54.419 UTC [48910][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:54.419 UTC [48910][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:54.421 UTC [48910][postmaster] LOG: background worker "logical replication launcher" (PID 48916) exited with exit code 1 2024-03-06 00:37:54.421 UTC [48911][checkpointer] LOG: shutting down 2024-03-06 00:37:54.421 UTC [48911][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:54.422 UTC [48911][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/4196590, redo lsn=0/4196590 2024-03-06 00:37:54.424 UTC [48910][postmaster] LOG: database system is shut down 2024-03-06 00:37:54.546 UTC [48966][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-06 00:37:54.546 UTC [48966][postmaster] LOG: database system is shut down 2024-03-06 00:37:54.660 UTC [48991][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:54.660 UTC [48991][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:54.661 UTC [48991][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:54.663 UTC [49005][startup] LOG: database system was shut down at 2024-03-06 00:37:54 UTC 2024-03-06 00:37:54.668 UTC [48991][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:54.728 UTC [48991][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:54.729 UTC [48991][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:54.730 UTC [48991][postmaster] LOG: background worker "logical replication launcher" (PID 49009) exited with exit code 1 2024-03-06 00:37:54.731 UTC [49003][checkpointer] LOG: shutting down 2024-03-06 00:37:54.731 UTC [49003][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:54.731 UTC [49003][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/4196608, redo lsn=0/4196608 2024-03-06 00:37:54.735 UTC [48991][postmaster] LOG: database system is shut down 2024-03-06 00:37:54.843 UTC [49058][postmaster] FATAL: could not set SSL protocol version range 2024-03-06 00:37:54.843 UTC [49058][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-06 00:37:54.843 UTC [49058][postmaster] LOG: database system is shut down 2024-03-06 00:37:54.952 UTC [49077][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:54.952 UTC [49077][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:54.952 UTC [49077][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:54.954 UTC [49082][startup] LOG: database system was shut down at 2024-03-06 00:37:54 UTC 2024-03-06 00:37:54.957 UTC [49077][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:55.036 UTC [49077][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:55.036 UTC [49077][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:55.038 UTC [49077][postmaster] LOG: background worker "logical replication launcher" (PID 49085) exited with exit code 1 2024-03-06 00:37:55.039 UTC [49080][checkpointer] LOG: shutting down 2024-03-06 00:37:55.039 UTC [49080][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:55.040 UTC [49080][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/4196680, redo lsn=0/4196680 2024-03-06 00:37:55.042 UTC [49077][postmaster] LOG: database system is shut down 2024-03-06 00:37:55.157 UTC [49122][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:55.157 UTC [49122][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:55.157 UTC [49122][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:55.160 UTC [49129][startup] LOG: database system was shut down at 2024-03-06 00:37:55 UTC 2024-03-06 00:37:55.163 UTC [49122][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:55.245 UTC [49144][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47676 2024-03-06 00:37:55.246 UTC [49144][client backend] [[unknown]][0/1:0] FATAL: no pg_hba.conf entry for host "127.0.0.1", user "ssltestuser", database "trustdb", no encryption 2024-03-06 00:37:55.246 UTC [49144][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-03-06 00:37:55.256 UTC [49147][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47682 2024-03-06 00:37:55.259 UTC [49147][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-06 00:37:55.259 UTC [49147][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:55.264 UTC [49147][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid sslmode=require$$ 2024-03-06 00:37:55.265 UTC [49147][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=47682 2024-03-06 00:37:55.275 UTC [49153][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47686 2024-03-06 00:37:55.276 UTC [49153][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-06 00:37:55.285 UTC [49156][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47700 2024-03-06 00:37:55.287 UTC [49156][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-06 00:37:55.309 UTC [49161][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47708 2024-03-06 00:37:55.313 UTC [49161][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-06 00:37:55.323 UTC [49168][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47720 2024-03-06 00:37:55.326 UTC [49168][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-06 00:37:55.336 UTC [49175][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47728 2024-03-06 00:37:55.343 UTC [49175][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-06 00:37:55.351 UTC [49178][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47736 2024-03-06 00:37:55.357 UTC [49178][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-06 00:37:55.364 UTC [49182][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47738 2024-03-06 00:37:55.368 UTC [49182][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-06 00:37:55.368 UTC [49182][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:55.369 UTC [49182][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require$$ 2024-03-06 00:37:55.370 UTC [49182][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=47738 2024-03-06 00:37:55.379 UTC [49186][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47742 2024-03-06 00:37:55.383 UTC [49186][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-06 00:37:55.383 UTC [49186][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:55.384 UTC [49186][client backend] [001_ssltests.pl][3/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$ 2024-03-06 00:37:55.385 UTC [49186][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=47742 2024-03-06 00:37:55.395 UTC [49192][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47750 2024-03-06 00:37:55.400 UTC [49192][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-06 00:37:55.400 UTC [49192][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:55.401 UTC [49192][client backend] [001_ssltests.pl][4/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-full$$ 2024-03-06 00:37:55.401 UTC [49192][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=47750 2024-03-06 00:37:55.410 UTC [49197][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47764 2024-03-06 00:37:55.416 UTC [49197][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-06 00:37:55.416 UTC [49197][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:55.417 UTC [49197][client backend] [001_ssltests.pl][5/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-1.crt sslmode=verify-ca$$ 2024-03-06 00:37:55.417 UTC [49197][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=47764 2024-03-06 00:37:55.430 UTC [49202][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47768 2024-03-06 00:37:55.434 UTC [49202][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-06 00:37:55.434 UTC [49202][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:55.435 UTC [49202][client backend] [001_ssltests.pl][6/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-2.crt sslmode=verify-ca$$ 2024-03-06 00:37:55.435 UTC [49202][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=47768 2024-03-06 00:37:55.446 UTC [49205][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47774 2024-03-06 00:37:55.451 UTC [49205][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-06 00:37:55.451 UTC [49205][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:55.452 UTC [49205][client backend] [001_ssltests.pl][7/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=disable$$ 2024-03-06 00:37:55.452 UTC [49205][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=47774 2024-03-06 00:37:55.461 UTC [49207][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47782 2024-03-06 00:37:55.465 UTC [49207][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-06 00:37:55.465 UTC [49207][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:55.466 UTC [49207][client backend] [001_ssltests.pl][8/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=allow$$ 2024-03-06 00:37:55.466 UTC [49207][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=47782 2024-03-06 00:37:55.476 UTC [49209][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47794 2024-03-06 00:37:55.480 UTC [49209][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-06 00:37:55.480 UTC [49209][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:55.481 UTC [49209][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=47794 2024-03-06 00:37:55.490 UTC [49211][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47802 2024-03-06 00:37:55.494 UTC [49211][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-06 00:37:55.494 UTC [49211][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:55.495 UTC [49211][client backend] [001_ssltests.pl][10/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=invalid$$ 2024-03-06 00:37:55.495 UTC [49211][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=47802 2024-03-06 00:37:55.504 UTC [49213][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47804 2024-03-06 00:37:55.507 UTC [49213][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-06 00:37:55.516 UTC [49215][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47806 2024-03-06 00:37:55.520 UTC [49215][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-06 00:37:55.531 UTC [49218][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47818 2024-03-06 00:37:55.536 UTC [49218][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-06 00:37:55.536 UTC [49218][client backend] [[unknown]][11/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:55.537 UTC [49218][client backend] [001_ssltests.pl][11/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=ssl/root+server.crl$$ 2024-03-06 00:37:55.538 UTC [49218][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=47818 2024-03-06 00:37:55.548 UTC [49226][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47826 2024-03-06 00:37:55.552 UTC [49226][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-06 00:37:55.552 UTC [49226][client backend] [[unknown]][12/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:55.553 UTC [49226][client backend] [001_ssltests.pl][12/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrldir=ssl/root+server-crldir$$ 2024-03-06 00:37:55.554 UTC [49226][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=47826 2024-03-06 00:37:55.563 UTC [49228][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47840 2024-03-06 00:37:55.568 UTC [49228][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-06 00:37:55.568 UTC [49228][client backend] [[unknown]][13/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:55.569 UTC [49228][client backend] [001_ssltests.pl][13/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=require host=wronghost.test$$ 2024-03-06 00:37:55.569 UTC [49228][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=47840 2024-03-06 00:37:55.579 UTC [49230][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47856 2024-03-06 00:37:55.583 UTC [49230][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-06 00:37:55.583 UTC [49230][client backend] [[unknown]][14/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:55.584 UTC [49230][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=wronghost.test$$ 2024-03-06 00:37:55.585 UTC [49230][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=47856 2024-03-06 00:37:55.594 UTC [49234][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47870 2024-03-06 00:37:55.601 UTC [49122][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:55.601 UTC [49122][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:55.604 UTC [49122][postmaster] LOG: background worker "logical replication launcher" (PID 49133) exited with exit code 1 2024-03-06 00:37:55.604 UTC [49127][checkpointer] LOG: shutting down 2024-03-06 00:37:55.604 UTC [49127][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:55.604 UTC [49127][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/41966F8, redo lsn=0/41966F8 2024-03-06 00:37:55.607 UTC [49122][postmaster] LOG: database system is shut down 2024-03-06 00:37:55.723 UTC [49239][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:55.723 UTC [49239][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:55.723 UTC [49239][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:55.726 UTC [49242][startup] LOG: database system was shut down at 2024-03-06 00:37:55 UTC 2024-03-06 00:37:55.730 UTC [49239][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:55.812 UTC [49256][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47878 2024-03-06 00:37:55.817 UTC [49256][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-06 00:37:55.817 UTC [49256][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:55.822 UTC [49256][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-06 00:37:55.822 UTC [49256][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=47878 2024-03-06 00:37:55.832 UTC [49258][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47888 2024-03-06 00:37:55.841 UTC [49239][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:55.841 UTC [49239][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:55.842 UTC [49239][postmaster] LOG: background worker "logical replication launcher" (PID 49245) exited with exit code 1 2024-03-06 00:37:55.843 UTC [49240][checkpointer] LOG: shutting down 2024-03-06 00:37:55.843 UTC [49240][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:55.843 UTC [49240][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/4196770, redo lsn=0/4196770 2024-03-06 00:37:55.846 UTC [49239][postmaster] LOG: database system is shut down 2024-03-06 00:37:55.961 UTC [49265][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:55.961 UTC [49265][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:55.961 UTC [49265][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:55.963 UTC [49269][startup] LOG: database system was shut down at 2024-03-06 00:37:55 UTC 2024-03-06 00:37:55.966 UTC [49265][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:56.051 UTC [49280][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47892 2024-03-06 00:37:56.055 UTC [49280][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-06 00:37:56.055 UTC [49280][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:56.060 UTC [49280][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-06 00:37:56.061 UTC [49280][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=47892 2024-03-06 00:37:56.066 UTC [49265][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:56.066 UTC [49265][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:56.067 UTC [49265][postmaster] LOG: background worker "logical replication launcher" (PID 49272) exited with exit code 1 2024-03-06 00:37:56.068 UTC [49267][checkpointer] LOG: shutting down 2024-03-06 00:37:56.068 UTC [49267][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:56.068 UTC [49267][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/41967E8, redo lsn=0/41967E8 2024-03-06 00:37:56.072 UTC [49265][postmaster] LOG: database system is shut down 2024-03-06 00:37:56.185 UTC [49287][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:56.185 UTC [49287][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:56.185 UTC [49287][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:56.187 UTC [49290][startup] LOG: database system was shut down at 2024-03-06 00:37:56 UTC 2024-03-06 00:37:56.190 UTC [49287][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:56.276 UTC [49303][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47908 2024-03-06 00:37:56.280 UTC [49303][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-06 00:37:56.280 UTC [49303][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:56.285 UTC [49303][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-03-06 00:37:56.285 UTC [49303][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=47908 2024-03-06 00:37:56.295 UTC [49305][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47910 2024-03-06 00:37:56.299 UTC [49305][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-06 00:37:56.299 UTC [49305][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:56.300 UTC [49305][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-03-06 00:37:56.301 UTC [49305][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=47910 2024-03-06 00:37:56.309 UTC [49309][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47912 2024-03-06 00:37:56.313 UTC [49309][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-06 00:37:56.313 UTC [49309][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:56.314 UTC [49309][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=foo.wildcard.pg-ssltest.test$$ 2024-03-06 00:37:56.314 UTC [49309][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=47912 2024-03-06 00:37:56.323 UTC [49311][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47918 2024-03-06 00:37:56.337 UTC [49314][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47928 2024-03-06 00:37:56.344 UTC [49287][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:56.344 UTC [49287][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:56.346 UTC [49287][postmaster] LOG: background worker "logical replication launcher" (PID 49293) exited with exit code 1 2024-03-06 00:37:56.346 UTC [49288][checkpointer] LOG: shutting down 2024-03-06 00:37:56.346 UTC [49288][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:56.346 UTC [49288][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/4196860, redo lsn=0/4196860 2024-03-06 00:37:56.349 UTC [49287][postmaster] LOG: database system is shut down 2024-03-06 00:37:56.464 UTC [49317][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:56.464 UTC [49317][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:56.464 UTC [49317][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:56.467 UTC [49326][startup] LOG: database system was shut down at 2024-03-06 00:37:56 UTC 2024-03-06 00:37:56.470 UTC [49317][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:56.553 UTC [49333][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47934 2024-03-06 00:37:56.557 UTC [49333][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-06 00:37:56.557 UTC [49333][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:56.563 UTC [49333][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=single.alt-name.pg-ssltest.test$$ 2024-03-06 00:37:56.564 UTC [49333][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=47934 2024-03-06 00:37:56.573 UTC [49336][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47936 2024-03-06 00:37:56.586 UTC [49338][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47942 2024-03-06 00:37:56.596 UTC [49317][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:56.597 UTC [49317][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:56.598 UTC [49317][postmaster] LOG: background worker "logical replication launcher" (PID 49329) exited with exit code 1 2024-03-06 00:37:56.598 UTC [49324][checkpointer] LOG: shutting down 2024-03-06 00:37:56.598 UTC [49324][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:56.599 UTC [49324][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/41968D8, redo lsn=0/41968D8 2024-03-06 00:37:56.602 UTC [49317][postmaster] LOG: database system is shut down 2024-03-06 00:37:56.717 UTC [49348][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:56.717 UTC [49348][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:56.717 UTC [49348][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:56.719 UTC [49351][startup] LOG: database system was shut down at 2024-03-06 00:37:56 UTC 2024-03-06 00:37:56.722 UTC [49348][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:56.805 UTC [49359][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47958 2024-03-06 00:37:56.810 UTC [49359][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-06 00:37:56.810 UTC [49359][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:56.815 UTC [49359][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-06 00:37:56.815 UTC [49359][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=47958 2024-03-06 00:37:56.825 UTC [49361][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47974 2024-03-06 00:37:56.829 UTC [49361][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-06 00:37:56.829 UTC [49361][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:56.830 UTC [49361][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-06 00:37:56.831 UTC [49361][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=47974 2024-03-06 00:37:56.840 UTC [49363][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47982 2024-03-06 00:37:56.852 UTC [49365][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=47998 2024-03-06 00:37:56.855 UTC [49365][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-06 00:37:56.855 UTC [49365][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:56.856 UTC [49365][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-06 00:37:56.857 UTC [49365][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=47998 2024-03-06 00:37:56.866 UTC [49367][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48010 2024-03-06 00:37:56.870 UTC [49367][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-06 00:37:56.870 UTC [49367][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:56.871 UTC [49367][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-06 00:37:56.872 UTC [49367][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=48010 2024-03-06 00:37:56.881 UTC [49369][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48018 2024-03-06 00:37:56.885 UTC [49369][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-06 00:37:56.885 UTC [49369][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:56.886 UTC [49369][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-06 00:37:56.887 UTC [49369][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=48018 2024-03-06 00:37:56.896 UTC [49371][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48024 2024-03-06 00:37:56.909 UTC [49373][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48036 2024-03-06 00:37:56.917 UTC [49348][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:56.917 UTC [49348][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:56.919 UTC [49348][postmaster] LOG: background worker "logical replication launcher" (PID 49354) exited with exit code 1 2024-03-06 00:37:56.919 UTC [49349][checkpointer] LOG: shutting down 2024-03-06 00:37:56.919 UTC [49349][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:56.920 UTC [49349][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/4196950, redo lsn=0/4196950 2024-03-06 00:37:56.923 UTC [49348][postmaster] LOG: database system is shut down 2024-03-06 00:37:57.037 UTC [49375][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:57.037 UTC [49375][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:57.037 UTC [49375][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:57.039 UTC [49378][startup] LOG: database system was shut down at 2024-03-06 00:37:56 UTC 2024-03-06 00:37:57.043 UTC [49375][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:57.127 UTC [49383][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48044 2024-03-06 00:37:57.131 UTC [49383][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-06 00:37:57.131 UTC [49383][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:57.136 UTC [49383][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-03-06 00:37:57.137 UTC [49383][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=48044 2024-03-06 00:37:57.146 UTC [49385][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48050 2024-03-06 00:37:57.151 UTC [49385][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-06 00:37:57.151 UTC [49385][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:57.152 UTC [49385][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-03-06 00:37:57.152 UTC [49385][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=48050 2024-03-06 00:37:57.162 UTC [49387][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48064 2024-03-06 00:37:57.173 UTC [49375][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:57.173 UTC [49375][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:57.174 UTC [49375][postmaster] LOG: background worker "logical replication launcher" (PID 49381) exited with exit code 1 2024-03-06 00:37:57.175 UTC [49376][checkpointer] LOG: shutting down 2024-03-06 00:37:57.175 UTC [49376][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:57.175 UTC [49376][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/41969C8, redo lsn=0/41969C8 2024-03-06 00:37:57.178 UTC [49375][postmaster] LOG: database system is shut down 2024-03-06 00:37:57.292 UTC [49390][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:57.292 UTC [49390][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:57.293 UTC [49390][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:57.295 UTC [49393][startup] LOG: database system was shut down at 2024-03-06 00:37:57 UTC 2024-03-06 00:37:57.299 UTC [49390][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:57.382 UTC [49398][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48072 2024-03-06 00:37:57.386 UTC [49398][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-06 00:37:57.386 UTC [49398][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:57.391 UTC [49398][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-06 00:37:57.392 UTC [49398][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=48072 2024-03-06 00:37:57.401 UTC [49400][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48084 2024-03-06 00:37:57.405 UTC [49400][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-06 00:37:57.405 UTC [49400][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:57.406 UTC [49400][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-06 00:37:57.406 UTC [49400][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=48084 2024-03-06 00:37:57.414 UTC [49402][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48090 2024-03-06 00:37:57.418 UTC [49402][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-06 00:37:57.418 UTC [49402][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:57.419 UTC [49402][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-06 00:37:57.420 UTC [49402][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=48090 2024-03-06 00:37:57.424 UTC [49390][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:57.424 UTC [49390][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:57.426 UTC [49390][postmaster] LOG: background worker "logical replication launcher" (PID 49396) exited with exit code 1 2024-03-06 00:37:57.426 UTC [49391][checkpointer] LOG: shutting down 2024-03-06 00:37:57.426 UTC [49391][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:57.427 UTC [49391][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/4196A40, redo lsn=0/4196A40 2024-03-06 00:37:57.430 UTC [49390][postmaster] LOG: database system is shut down 2024-03-06 00:37:57.544 UTC [49404][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:57.544 UTC [49404][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:57.544 UTC [49404][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:57.546 UTC [49407][startup] LOG: database system was shut down at 2024-03-06 00:37:57 UTC 2024-03-06 00:37:57.550 UTC [49404][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:57.634 UTC [49412][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48102 2024-03-06 00:37:57.638 UTC [49412][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-06 00:37:57.638 UTC [49412][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:57.643 UTC [49412][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-06 00:37:57.644 UTC [49412][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=48102 2024-03-06 00:37:57.653 UTC [49414][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48112 2024-03-06 00:37:57.657 UTC [49414][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-06 00:37:57.657 UTC [49414][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:57.658 UTC [49414][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-06 00:37:57.659 UTC [49414][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=48112 2024-03-06 00:37:57.668 UTC [49416][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48116 2024-03-06 00:37:57.675 UTC [49404][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:57.675 UTC [49404][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:57.677 UTC [49404][postmaster] LOG: background worker "logical replication launcher" (PID 49410) exited with exit code 1 2024-03-06 00:37:57.677 UTC [49405][checkpointer] LOG: shutting down 2024-03-06 00:37:57.677 UTC [49405][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:57.677 UTC [49405][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/4196AB8, redo lsn=0/4196AB8 2024-03-06 00:37:57.680 UTC [49404][postmaster] LOG: database system is shut down 2024-03-06 00:37:57.794 UTC [49418][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:57.795 UTC [49418][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:57.795 UTC [49418][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:57.797 UTC [49421][startup] LOG: database system was shut down at 2024-03-06 00:37:57 UTC 2024-03-06 00:37:57.800 UTC [49418][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:57.885 UTC [49426][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48132 2024-03-06 00:37:57.889 UTC [49426][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-06 00:37:57.889 UTC [49426][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:57.894 UTC [49426][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-06 00:37:57.895 UTC [49426][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=48132 2024-03-06 00:37:57.904 UTC [49428][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48144 2024-03-06 00:37:57.909 UTC [49428][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-06 00:37:57.909 UTC [49428][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:57.910 UTC [49428][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-03-06 00:37:57.910 UTC [49428][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=48144 2024-03-06 00:37:57.920 UTC [49430][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48154 2024-03-06 00:37:57.924 UTC [49430][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-06 00:37:57.924 UTC [49430][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:57.925 UTC [49430][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-03-06 00:37:57.925 UTC [49430][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=48154 2024-03-06 00:37:57.929 UTC [49418][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:57.929 UTC [49418][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:57.930 UTC [49418][postmaster] LOG: background worker "logical replication launcher" (PID 49424) exited with exit code 1 2024-03-06 00:37:57.930 UTC [49419][checkpointer] LOG: shutting down 2024-03-06 00:37:57.930 UTC [49419][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:57.931 UTC [49419][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/4196B30, redo lsn=0/4196B30 2024-03-06 00:37:57.934 UTC [49418][postmaster] LOG: database system is shut down 2024-03-06 00:37:58.049 UTC [49432][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:58.049 UTC [49432][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:58.049 UTC [49432][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:58.051 UTC [49435][startup] LOG: database system was shut down at 2024-03-06 00:37:57 UTC 2024-03-06 00:37:58.054 UTC [49432][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:58.139 UTC [49440][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48170 2024-03-06 00:37:58.143 UTC [49440][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-06 00:37:58.143 UTC [49440][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:58.148 UTC [49440][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=common-name.pg-ssltest.test$$ 2024-03-06 00:37:58.149 UTC [49440][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=48170 2024-03-06 00:37:58.158 UTC [49442][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48174 2024-03-06 00:37:58.166 UTC [49432][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:58.166 UTC [49432][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:58.168 UTC [49432][postmaster] LOG: background worker "logical replication launcher" (PID 49438) exited with exit code 1 2024-03-06 00:37:58.168 UTC [49433][checkpointer] LOG: shutting down 2024-03-06 00:37:58.168 UTC [49433][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:58.169 UTC [49433][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/4196BA8, redo lsn=0/4196BA8 2024-03-06 00:37:58.173 UTC [49432][postmaster] LOG: database system is shut down 2024-03-06 00:37:58.286 UTC [49444][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:58.286 UTC [49444][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:58.286 UTC [49444][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:58.288 UTC [49447][startup] LOG: database system was shut down at 2024-03-06 00:37:58 UTC 2024-03-06 00:37:58.292 UTC [49444][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:58.376 UTC [49452][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48190 2024-03-06 00:37:58.380 UTC [49452][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-06 00:37:58.396 UTC [49455][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48206 2024-03-06 00:37:58.400 UTC [49455][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-06 00:37:58.400 UTC [49455][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:58.406 UTC [49455][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=system hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$ 2024-03-06 00:37:58.406 UTC [49455][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=48206 2024-03-06 00:37:58.415 UTC [49457][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48218 2024-03-06 00:37:58.423 UTC [49444][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:58.423 UTC [49444][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:58.424 UTC [49444][postmaster] LOG: background worker "logical replication launcher" (PID 49450) exited with exit code 1 2024-03-06 00:37:58.425 UTC [49445][checkpointer] LOG: shutting down 2024-03-06 00:37:58.425 UTC [49445][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:58.425 UTC [49445][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/4196C20, redo lsn=0/4196C20 2024-03-06 00:37:58.428 UTC [49444][postmaster] LOG: database system is shut down 2024-03-06 00:37:58.542 UTC [49459][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:58.542 UTC [49459][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:58.542 UTC [49459][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:58.544 UTC [49462][startup] LOG: database system was shut down at 2024-03-06 00:37:58 UTC 2024-03-06 00:37:58.547 UTC [49459][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:58.632 UTC [49467][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48220 2024-03-06 00:37:58.637 UTC [49467][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-06 00:37:58.637 UTC [49467][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:58.642 UTC [49467][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$ 2024-03-06 00:37:58.643 UTC [49467][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=48220 2024-03-06 00:37:58.652 UTC [49469][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48226 2024-03-06 00:37:58.656 UTC [49469][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-06 00:37:58.665 UTC [49471][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48240 2024-03-06 00:37:58.668 UTC [49471][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-06 00:37:58.678 UTC [49473][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48250 2024-03-06 00:37:58.682 UTC [49473][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-06 00:37:58.682 UTC [49473][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:58.683 UTC [49473][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-06 00:37:58.685 UTC [49473][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=48250 2024-03-06 00:37:58.694 UTC [49475][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48256 2024-03-06 00:37:58.698 UTC [49475][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-06 00:37:58.698 UTC [49475][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.2, cipher=ECDHE-RSA-AES256-GCM-SHA384, bits=256) 2024-03-06 00:37:58.699 UTC [49475][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require ssl_min_protocol_version=TLSv1.2 ssl_max_protocol_version=TLSv1.2$$ 2024-03-06 00:37:58.699 UTC [49475][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=48256 2024-03-06 00:37:58.733 UTC [49480][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48270 2024-03-06 00:37:58.737 UTC [49480][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-03-06 00:37:58.748 UTC [49482][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48282 2024-03-06 00:37:58.754 UTC [49482][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-06 00:37:58.754 UTC [49482][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:58.759 UTC [49482][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_QruS/client.key$$ 2024-03-06 00:37:58.759 UTC [49482][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=48282 2024-03-06 00:37:58.769 UTC [49484][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48290 2024-03-06 00:37:58.775 UTC [49484][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-06 00:37:58.775 UTC [49484][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:58.776 UTC [49484][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_QruS/client-der.key$$ 2024-03-06 00:37:58.777 UTC [49484][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=48290 2024-03-06 00:37:58.787 UTC [49486][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=48292 2024-03-06 00:37:58.794 UTC [49486][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-06 00:37:58.794 UTC [49486][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:58.795 UTC [49486][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_QruS/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-03-06 00:37:58.795 UTC [49486][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=48292 2024-03-06 00:37:58.806 UTC [49488][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=60886 2024-03-06 00:37:58.812 UTC [49488][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-06 00:37:58.812 UTC [49488][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:58.813 UTC [49488][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_QruS/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-03-06 00:37:58.814 UTC [49488][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=60886 2024-03-06 00:37:58.825 UTC [49490][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=60892 2024-03-06 00:37:58.830 UTC [49490][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-06 00:37:58.831 UTC [49490][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:58.832 UTC [49490][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_QruS/client.key$$ 2024-03-06 00:37:58.832 UTC [49490][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=60892 2024-03-06 00:37:58.842 UTC [49492][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=60894 2024-03-06 00:37:58.849 UTC [49492][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-06 00:37:58.849 UTC [49492][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:58.850 UTC [49492][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_QruS/client.key$$ 2024-03-06 00:37:58.850 UTC [49492][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=60894 2024-03-06 00:37:58.861 UTC [49494][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=60906 2024-03-06 00:37:58.865 UTC [49494][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-03-06 00:37:58.877 UTC [49496][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=60914 2024-03-06 00:37:58.879 UTC [49496][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-06 00:37:58.889 UTC [49498][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=60920 2024-03-06 00:37:58.895 UTC [49498][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-06 00:37:58.895 UTC [49498][client backend] [[unknown]][11/1:0] LOG: connection authorized: user=ssltestuser database=certdb_dn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:58.900 UTC [49498][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_QruS/client-dn.key$$ 2024-03-06 00:37:58.901 UTC [49498][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb_dn host=localhost port=60920 2024-03-06 00:37:58.911 UTC [49500][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=60930 2024-03-06 00:37:58.916 UTC [49500][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-06 00:37:58.916 UTC [49500][client backend] [[unknown]][12/1:0] LOG: connection authorized: user=ssltestuser database=certdb_dn_re application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:58.922 UTC [49500][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_QruS/client-dn.key$$ 2024-03-06 00:37:58.922 UTC [49500][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_dn_re host=localhost port=60930 2024-03-06 00:37:58.933 UTC [49502][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=60946 2024-03-06 00:37:58.939 UTC [49502][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-06 00:37:58.939 UTC [49502][client backend] [[unknown]][13/1:0] LOG: connection authorized: user=ssltestuser database=certdb_cn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:58.944 UTC [49502][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_QruS/client-dn.key$$ 2024-03-06 00:37:58.945 UTC [49502][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb_cn host=localhost port=60946 2024-03-06 00:37:58.968 UTC [49505][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=60948 2024-03-06 00:37:58.974 UTC [49505][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-06 00:37:58.974 UTC [49505][client backend] [[unknown]][14/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:58.975 UTC [49505][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-06 00:37:58.977 UTC [49505][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=60948 2024-03-06 00:37:58.986 UTC [49507][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=60956 2024-03-06 00:37:58.987 UTC [49507][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-06 00:37:58.997 UTC [49509][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=60966 2024-03-06 00:37:59.003 UTC [49509][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-06 00:37:59.003 UTC [49509][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-06 00:37:59.003 UTC [49509][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-03-06 00:37:59.003 UTC [49509][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-06 00:37:59.013 UTC [49511][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=60974 2024-03-06 00:37:59.018 UTC [49511][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-06 00:37:59.018 UTC [49511][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-06 00:37:59.029 UTC [49513][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=60976 2024-03-06 00:37:59.034 UTC [49513][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-06 00:37:59.034 UTC [49513][client backend] [[unknown]][16/1:0] LOG: connection authorized: user=ssltestuser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:59.039 UTC [49513][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_QruS/client.key$$ 2024-03-06 00:37:59.040 UTC [49513][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=verifydb host=localhost port=60976 2024-03-06 00:37:59.050 UTC [49515][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=60978 2024-03-06 00:37:59.056 UTC [49515][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-06 00:37:59.056 UTC [49515][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-03-06 00:37:59.056 UTC [49515][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-03-06 00:37:59.056 UTC [49515][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-06 00:37:59.066 UTC [49517][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=60986 2024-03-06 00:37:59.071 UTC [49517][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-06 00:37:59.071 UTC [49517][client backend] [[unknown]][18/1:0] LOG: connection authorized: user=yetanotheruser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:59.072 UTC [49517][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_QruS/client.key$$ 2024-03-06 00:37:59.073 UTC [49517][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=yetanotheruser database=verifydb host=localhost port=60986 2024-03-06 00:37:59.077 UTC [49459][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:59.077 UTC [49459][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:59.079 UTC [49459][postmaster] LOG: background worker "logical replication launcher" (PID 49465) exited with exit code 1 2024-03-06 00:37:59.079 UTC [49460][checkpointer] LOG: shutting down 2024-03-06 00:37:59.079 UTC [49460][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:59.080 UTC [49460][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/4196C98, redo lsn=0/4196C98 2024-03-06 00:37:59.083 UTC [49459][postmaster] LOG: database system is shut down 2024-03-06 00:37:59.197 UTC [49519][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:59.197 UTC [49519][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:59.197 UTC [49519][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:59.199 UTC [49522][startup] LOG: database system was shut down at 2024-03-06 00:37:59 UTC 2024-03-06 00:37:59.203 UTC [49519][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:59.287 UTC [49527][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=60996 2024-03-06 00:37:59.293 UTC [49527][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-06 00:37:59.293 UTC [49527][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:37:59.298 UTC [49527][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_QruS/client.key sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 host=localhost sslmode=require sslcert=ssl/client+client_ca.crt$$ 2024-03-06 00:37:59.299 UTC [49527][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb host=localhost port=60996 2024-03-06 00:37:59.309 UTC [49529][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=60998 2024-03-06 00:37:59.314 UTC [49529][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-06 00:37:59.314 UTC [49529][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: unable to get local issuer certificate. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921344, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-06 00:37:59.324 UTC [49531][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=32774 2024-03-06 00:37:59.329 UTC [49531][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-06 00:37:59.329 UTC [49531][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: unable to get local issuer certificate. Failed certificate data (unverified): subject ".../CN=ssl-123456789012345678901234567890123456789012345678901234567890", serial number 2315702411956921348, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-06 00:37:59.333 UTC [49519][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:59.333 UTC [49519][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:59.335 UTC [49519][postmaster] LOG: background worker "logical replication launcher" (PID 49525) exited with exit code 1 2024-03-06 00:37:59.335 UTC [49520][checkpointer] LOG: shutting down 2024-03-06 00:37:59.335 UTC [49520][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:59.335 UTC [49520][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/4196D10, redo lsn=0/4196D10 2024-03-06 00:37:59.338 UTC [49519][postmaster] LOG: database system is shut down 2024-03-06 00:37:59.453 UTC [49533][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:59.453 UTC [49533][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:59.453 UTC [49533][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:59.455 UTC [49536][startup] LOG: database system was shut down at 2024-03-06 00:37:59 UTC 2024-03-06 00:37:59.459 UTC [49533][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:59.543 UTC [49541][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=32778 2024-03-06 00:37:59.547 UTC [49541][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-06 00:37:59.547 UTC [49541][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 1: unable to get local issuer certificate. Failed certificate data (unverified): subject "/CN=Test CA for PostgreSQL SSL regression test client certs", serial number 2315702411956921089, issuer "/CN=Test root CA for PostgreSQL SSL regression test suite". 2024-03-06 00:37:59.552 UTC [49533][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:59.552 UTC [49533][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:59.553 UTC [49533][postmaster] LOG: background worker "logical replication launcher" (PID 49539) exited with exit code 1 2024-03-06 00:37:59.554 UTC [49534][checkpointer] LOG: shutting down 2024-03-06 00:37:59.554 UTC [49534][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:59.554 UTC [49534][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/4196D88, redo lsn=0/4196D88 2024-03-06 00:37:59.557 UTC [49533][postmaster] LOG: database system is shut down 2024-03-06 00:37:59.671 UTC [49543][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:59.672 UTC [49543][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:59.672 UTC [49543][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:59.674 UTC [49546][startup] LOG: database system was shut down at 2024-03-06 00:37:59 UTC 2024-03-06 00:37:59.677 UTC [49543][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:59.761 UTC [49551][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=32786 2024-03-06 00:37:59.767 UTC [49551][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-06 00:37:59.767 UTC [49551][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-06 00:37:59.776 UTC [49553][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=32800 2024-03-06 00:37:59.780 UTC [49553][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-06 00:37:59.780 UTC [49553][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=\xce\x9f\xce\xb4\xcf\x85\xcf\x83\xcf\x83\xce\xad\xce\xb1\xcf\x82", serial number 2315702411956921349, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-06 00:37:59.784 UTC [49543][postmaster] LOG: received fast shutdown request 2024-03-06 00:37:59.784 UTC [49543][postmaster] LOG: aborting any active transactions 2024-03-06 00:37:59.786 UTC [49543][postmaster] LOG: background worker "logical replication launcher" (PID 49549) exited with exit code 1 2024-03-06 00:37:59.786 UTC [49544][checkpointer] LOG: shutting down 2024-03-06 00:37:59.786 UTC [49544][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:37:59.787 UTC [49544][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/4196E00, redo lsn=0/4196E00 2024-03-06 00:37:59.790 UTC [49543][postmaster] LOG: database system is shut down 2024-03-06 00:37:59.904 UTC [49555][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:37:59.904 UTC [49555][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:37:59.904 UTC [49555][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:37:59.906 UTC [49558][startup] LOG: database system was shut down at 2024-03-06 00:37:59 UTC 2024-03-06 00:37:59.909 UTC [49555][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:37:59.995 UTC [49563][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=32814 2024-03-06 00:37:59.996 UTC [49563][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-06 00:37:59.996 UTC [49563][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-06 00:38:00.008 UTC [49565][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=32824 2024-03-06 00:38:00.012 UTC [49565][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-06 00:38:00.012 UTC [49565][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:38:00.018 UTC [49565][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-06 00:38:00.018 UTC [49565][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=32824 2024-03-06 00:38:00.023 UTC [49555][postmaster] LOG: received fast shutdown request 2024-03-06 00:38:00.023 UTC [49555][postmaster] LOG: aborting any active transactions 2024-03-06 00:38:00.024 UTC [49555][postmaster] LOG: background worker "logical replication launcher" (PID 49561) exited with exit code 1 2024-03-06 00:38:00.024 UTC [49556][checkpointer] LOG: shutting down 2024-03-06 00:38:00.025 UTC [49556][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:38:00.025 UTC [49556][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/4196E78, redo lsn=0/4196E78 2024-03-06 00:38:00.029 UTC [49555][postmaster] LOG: database system is shut down 2024-03-06 00:38:00.143 UTC [49567][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:38:00.143 UTC [49567][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:38:00.143 UTC [49567][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:38:00.145 UTC [49570][startup] LOG: database system was shut down at 2024-03-06 00:38:00 UTC 2024-03-06 00:38:00.149 UTC [49567][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:38:00.233 UTC [49575][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=32826 2024-03-06 00:38:00.234 UTC [49575][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-06 00:38:00.234 UTC [49575][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-06 00:38:00.245 UTC [49577][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=32834 2024-03-06 00:38:00.249 UTC [49577][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-06 00:38:00.249 UTC [49577][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:38:00.254 UTC [49577][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-06 00:38:00.254 UTC [49577][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=32834 2024-03-06 00:38:00.259 UTC [49567][postmaster] LOG: received fast shutdown request 2024-03-06 00:38:00.259 UTC [49567][postmaster] LOG: aborting any active transactions 2024-03-06 00:38:00.261 UTC [49567][postmaster] LOG: background worker "logical replication launcher" (PID 49573) exited with exit code 1 2024-03-06 00:38:00.261 UTC [49568][checkpointer] LOG: shutting down 2024-03-06 00:38:00.261 UTC [49568][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:38:00.261 UTC [49568][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/4196EF0, redo lsn=0/4196EF0 2024-03-06 00:38:00.265 UTC [49567][postmaster] LOG: database system is shut down 2024-03-06 00:38:00.379 UTC [49579][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:38:00.379 UTC [49579][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:38:00.379 UTC [49579][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:38:00.382 UTC [49582][startup] LOG: database system was shut down at 2024-03-06 00:38:00 UTC 2024-03-06 00:38:00.386 UTC [49579][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:38:00.469 UTC [49587][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=32842 2024-03-06 00:38:00.470 UTC [49587][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-06 00:38:00.470 UTC [49587][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-06 00:38:00.480 UTC [49589][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=32856 2024-03-06 00:38:00.484 UTC [49589][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-06 00:38:00.484 UTC [49589][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:38:00.489 UTC [49589][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-06 00:38:00.490 UTC [49589][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=32856 2024-03-06 00:38:00.495 UTC [49579][postmaster] LOG: received fast shutdown request 2024-03-06 00:38:00.495 UTC [49579][postmaster] LOG: aborting any active transactions 2024-03-06 00:38:00.497 UTC [49579][postmaster] LOG: background worker "logical replication launcher" (PID 49585) exited with exit code 1 2024-03-06 00:38:00.497 UTC [49580][checkpointer] LOG: shutting down 2024-03-06 00:38:00.497 UTC [49580][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:38:00.498 UTC [49580][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/4196F68, redo lsn=0/4196F68 2024-03-06 00:38:00.501 UTC [49579][postmaster] LOG: database system is shut down 2024-03-06 00:38:00.615 UTC [49591][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:38:00.615 UTC [49591][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:38:00.615 UTC [49591][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:38:00.617 UTC [49594][startup] LOG: database system was shut down at 2024-03-06 00:38:00 UTC 2024-03-06 00:38:00.621 UTC [49591][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:38:00.705 UTC [49599][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=32862 2024-03-06 00:38:00.707 UTC [49599][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-06 00:38:00.709 UTC [49599][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-06 00:38:00.720 UTC [49601][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=32864 2024-03-06 00:38:00.725 UTC [49601][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-06 00:38:00.725 UTC [49601][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:38:00.730 UTC [49601][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-06 00:38:00.730 UTC [49601][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=32864 2024-03-06 00:38:00.735 UTC [49591][postmaster] LOG: received fast shutdown request 2024-03-06 00:38:00.735 UTC [49591][postmaster] LOG: aborting any active transactions 2024-03-06 00:38:00.737 UTC [49591][postmaster] LOG: background worker "logical replication launcher" (PID 49597) exited with exit code 1 2024-03-06 00:38:00.737 UTC [49592][checkpointer] LOG: shutting down 2024-03-06 00:38:00.737 UTC [49592][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:38:00.738 UTC [49592][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/4196FE0, redo lsn=0/4196FE0 2024-03-06 00:38:00.742 UTC [49591][postmaster] LOG: database system is shut down 2024-03-06 00:38:00.854 UTC [49603][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:38:00.854 UTC [49603][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:38:00.854 UTC [49603][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:38:00.856 UTC [49606][startup] LOG: database system was shut down at 2024-03-06 00:38:00 UTC 2024-03-06 00:38:00.860 UTC [49603][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:38:00.944 UTC [49611][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=32870 2024-03-06 00:38:00.946 UTC [49611][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-06 00:38:00.946 UTC [49611][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-06 00:38:00.957 UTC [49613][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=32886 2024-03-06 00:38:00.961 UTC [49613][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-06 00:38:00.961 UTC [49613][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:38:00.967 UTC [49613][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-06 00:38:00.967 UTC [49613][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=32886 2024-03-06 00:38:00.971 UTC [49603][postmaster] LOG: received fast shutdown request 2024-03-06 00:38:00.971 UTC [49603][postmaster] LOG: aborting any active transactions 2024-03-06 00:38:00.973 UTC [49603][postmaster] LOG: background worker "logical replication launcher" (PID 49609) exited with exit code 1 2024-03-06 00:38:00.973 UTC [49604][checkpointer] LOG: shutting down 2024-03-06 00:38:00.973 UTC [49604][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:38:00.974 UTC [49604][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/4197058, redo lsn=0/4197058 2024-03-06 00:38:00.977 UTC [49603][postmaster] LOG: database system is shut down 2024-03-06 00:38:01.090 UTC [49615][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:38:01.090 UTC [49615][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:38:01.090 UTC [49615][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:38:01.093 UTC [49618][startup] LOG: database system was shut down at 2024-03-06 00:38:00 UTC 2024-03-06 00:38:01.097 UTC [49615][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:38:01.182 UTC [49623][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=32892 2024-03-06 00:38:01.184 UTC [49623][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-06 00:38:01.184 UTC [49623][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-06 00:38:01.196 UTC [49625][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=32904 2024-03-06 00:38:01.201 UTC [49625][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-06 00:38:01.201 UTC [49625][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:38:01.206 UTC [49625][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-06 00:38:01.207 UTC [49625][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=32904 2024-03-06 00:38:01.212 UTC [49615][postmaster] LOG: received fast shutdown request 2024-03-06 00:38:01.212 UTC [49615][postmaster] LOG: aborting any active transactions 2024-03-06 00:38:01.213 UTC [49615][postmaster] LOG: background worker "logical replication launcher" (PID 49621) exited with exit code 1 2024-03-06 00:38:01.213 UTC [49616][checkpointer] LOG: shutting down 2024-03-06 00:38:01.213 UTC [49616][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:38:01.214 UTC [49616][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/41970D0, redo lsn=0/41970D0 2024-03-06 00:38:01.217 UTC [49615][postmaster] LOG: database system is shut down 2024-03-06 00:38:01.331 UTC [49627][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:38:01.331 UTC [49627][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:38:01.331 UTC [49627][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:38:01.334 UTC [49630][startup] LOG: database system was shut down at 2024-03-06 00:38:01 UTC 2024-03-06 00:38:01.337 UTC [49627][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:38:01.421 UTC [49635][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=32918 2024-03-06 00:38:01.423 UTC [49635][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-06 00:38:01.423 UTC [49635][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-06 00:38:01.434 UTC [49637][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=32932 2024-03-06 00:38:01.438 UTC [49637][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-06 00:38:01.438 UTC [49637][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:38:01.443 UTC [49637][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-06 00:38:01.444 UTC [49637][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=32932 2024-03-06 00:38:01.448 UTC [49627][postmaster] LOG: received fast shutdown request 2024-03-06 00:38:01.448 UTC [49627][postmaster] LOG: aborting any active transactions 2024-03-06 00:38:01.450 UTC [49627][postmaster] LOG: background worker "logical replication launcher" (PID 49633) exited with exit code 1 2024-03-06 00:38:01.450 UTC [49628][checkpointer] LOG: shutting down 2024-03-06 00:38:01.450 UTC [49628][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:38:01.451 UTC [49628][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/4197148, redo lsn=0/4197148 2024-03-06 00:38:01.454 UTC [49627][postmaster] LOG: database system is shut down 2024-03-06 00:38:01.568 UTC [49639][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:38:01.568 UTC [49639][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:38:01.568 UTC [49639][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:38:01.570 UTC [49642][startup] LOG: database system was shut down at 2024-03-06 00:38:01 UTC 2024-03-06 00:38:01.574 UTC [49639][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:38:01.658 UTC [49647][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=32944 2024-03-06 00:38:01.659 UTC [49647][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-06 00:38:01.659 UTC [49647][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-06 00:38:01.669 UTC [49649][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=32958 2024-03-06 00:38:01.673 UTC [49649][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-06 00:38:01.673 UTC [49649][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:38:01.678 UTC [49649][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-06 00:38:01.678 UTC [49649][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=32958 2024-03-06 00:38:01.682 UTC [49639][postmaster] LOG: received fast shutdown request 2024-03-06 00:38:01.682 UTC [49639][postmaster] LOG: aborting any active transactions 2024-03-06 00:38:01.684 UTC [49639][postmaster] LOG: background worker "logical replication launcher" (PID 49645) exited with exit code 1 2024-03-06 00:38:01.684 UTC [49640][checkpointer] LOG: shutting down 2024-03-06 00:38:01.684 UTC [49640][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:38:01.685 UTC [49640][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/41971C0, redo lsn=0/41971C0 2024-03-06 00:38:01.687 UTC [49639][postmaster] LOG: database system is shut down 2024-03-06 00:38:01.801 UTC [49651][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-06 00:38:01.801 UTC [49651][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 61783 2024-03-06 00:38:01.801 UTC [49651][postmaster] LOG: listening on Unix socket "/tmp/gb_pIcEHyE/.s.PGSQL.61783" 2024-03-06 00:38:01.804 UTC [49654][startup] LOG: database system was shut down at 2024-03-06 00:38:01 UTC 2024-03-06 00:38:01.807 UTC [49651][postmaster] LOG: database system is ready to accept connections 2024-03-06 00:38:01.892 UTC [49659][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=32966 2024-03-06 00:38:01.893 UTC [49659][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-06 00:38:01.893 UTC [49659][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-06 00:38:01.905 UTC [49661][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=32968 2024-03-06 00:38:01.909 UTC [49661][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-06 00:38:01.909 UTC [49661][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-06 00:38:01.914 UTC [49661][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-06 00:38:01.915 UTC [49661][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=32968 2024-03-06 00:38:01.920 UTC [49651][postmaster] LOG: received fast shutdown request 2024-03-06 00:38:01.920 UTC [49651][postmaster] LOG: aborting any active transactions 2024-03-06 00:38:01.921 UTC [49651][postmaster] LOG: background worker "logical replication launcher" (PID 49657) exited with exit code 1 2024-03-06 00:38:01.921 UTC [49652][checkpointer] LOG: shutting down 2024-03-06 00:38:01.921 UTC [49652][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-06 00:38:01.922 UTC [49652][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/4197238, redo lsn=0/4197238 2024-03-06 00:38:01.925 UTC [49651][postmaster] LOG: database system is shut down 2024-03-06 00:38:02.035 UTC [49663][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-03-06 00:38:02.035 UTC [49663][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [00:38:02.121](0.205s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"