[05:40:59.293](0.030s) # setting up data directory # Checking port 57112 # Found port 57112 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=57112 host=/tmp/XsQjfteO4d Log file: /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [05:40:59.305](0.012s) # 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 48382 [05:40:59.499](0.194s) 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 48684 [05:41:00.482](0.983s) # testing password-protected keys ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down... done server stopped waiting for server to start.... stopped waiting pg_ctl: could not start server Examine the log output. # pg_ctl restart failed; logfile: 2024-03-10 05:40:59.399 UTC [48382][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:40:59.401 UTC [48382][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:40:59.403 UTC [48396][startup] LOG: database system was shut down at 2024-03-10 05:38:41 UTC 2024-03-10 05:40:59.409 UTC [48382][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:40:59.492 UTC [48428][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.492 UTC [48428][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.492 UTC [48428][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.497 UTC [48428][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-10 05:40:59.500 UTC [48428][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.507 UTC [48440][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.508 UTC [48440][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.508 UTC [48440][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.509 UTC [48440][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-10 05:40:59.509 UTC [48440][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.559 UTC [48468][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.559 UTC [48468][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.559 UTC [48468][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.562 UTC [48468][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-10 05:40:59.565 UTC [48468][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.597 UTC [48481][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.597 UTC [48481][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.597 UTC [48481][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.598 UTC [48481][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-10 05:40:59.598 UTC [48481][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.641 UTC [48491][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.641 UTC [48491][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.641 UTC [48491][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.642 UTC [48491][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-10 05:40:59.642 UTC [48491][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.686 UTC [48502][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.687 UTC [48502][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.687 UTC [48502][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.688 UTC [48502][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-10 05:40:59.724 UTC [48502][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.037 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.819 UTC [48548][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.819 UTC [48548][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.819 UTC [48548][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.822 UTC [48548][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-10 05:40:59.875 UTC [48548][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.056 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.933 UTC [48581][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.933 UTC [48581][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.933 UTC [48581][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.934 UTC [48581][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-10 05:40:59.962 UTC [48581][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.028 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.979 UTC [48594][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.981 UTC [48594][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.981 UTC [48594][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.982 UTC [48594][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-10 05:41:00.025 UTC [48594][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.046 user=postgres database=postgres host=[local] 2024-03-10 05:41:00.113 UTC [48630][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:41:00.114 UTC [48630][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:41:00.114 UTC [48630][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:41:00.114 UTC [48630][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-10 05:41:00.137 UTC [48630][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.024 user=postgres database=postgres host=[local] 2024-03-10 05:41:00.153 UTC [48638][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:41:00.154 UTC [48638][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:41:00.154 UTC [48638][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:41:00.155 UTC [48638][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-10 05:41:00.196 UTC [48638][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.043 user=postgres database=postgres host=[local] 2024-03-10 05:41:00.281 UTC [48382][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:00.281 UTC [48382][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:00.282 UTC [48382][postmaster] LOG: background worker "logical replication launcher" (PID 48399) exited with exit code 1 2024-03-10 05:41:00.283 UTC [48394][checkpointer] LOG: shutting down 2024-03-10 05:41:00.283 UTC [48394][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:00.321 UTC [48394][checkpointer] LOG: checkpoint complete: wrote 5650 buffers (34.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.038 s, sync=0.001 s, total=0.039 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45479 kB, estimate=45479 kB; lsn=0/41961F0, redo lsn=0/41961F0 2024-03-10 05:41:00.330 UTC [48382][postmaster] LOG: database system is shut down 2024-03-10 05:41:00.420 UTC [48684][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:00.420 UTC [48684][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:00.420 UTC [48684][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:00.431 UTC [48695][startup] LOG: database system was shut down at 2024-03-10 05:41:00 UTC 2024-03-10 05:41:00.433 UTC [48684][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:00.486 UTC [48684][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:00.486 UTC [48684][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:00.487 UTC [48684][postmaster] LOG: background worker "logical replication launcher" (PID 48701) exited with exit code 1 2024-03-10 05:41:00.488 UTC [48692][checkpointer] LOG: shutting down 2024-03-10 05:41:00.488 UTC [48692][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:00.488 UTC [48692][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/4196268, redo lsn=0/4196268 2024-03-10 05:41:00.491 UTC [48684][postmaster] LOG: database system is shut down 2024-03-10 05:41:00.514 UTC [48719][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-10 05:41:00.514 UTC [48719][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [05:41:00.596](0.114s) 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 48760 [05:41:00.699](0.103s) ok 3 - restart succeeds with password-protected key file ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... stopped waiting pg_ctl: could not start server Examine the log output. # pg_ctl restart failed; logfile: 2024-03-10 05:40:59.399 UTC [48382][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:40:59.401 UTC [48382][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:40:59.403 UTC [48396][startup] LOG: database system was shut down at 2024-03-10 05:38:41 UTC 2024-03-10 05:40:59.409 UTC [48382][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:40:59.492 UTC [48428][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.492 UTC [48428][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.492 UTC [48428][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.497 UTC [48428][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-10 05:40:59.500 UTC [48428][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.507 UTC [48440][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.508 UTC [48440][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.508 UTC [48440][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.509 UTC [48440][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-10 05:40:59.509 UTC [48440][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.559 UTC [48468][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.559 UTC [48468][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.559 UTC [48468][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.562 UTC [48468][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-10 05:40:59.565 UTC [48468][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.597 UTC [48481][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.597 UTC [48481][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.597 UTC [48481][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.598 UTC [48481][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-10 05:40:59.598 UTC [48481][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.641 UTC [48491][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.641 UTC [48491][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.641 UTC [48491][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.642 UTC [48491][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-10 05:40:59.642 UTC [48491][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.686 UTC [48502][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.687 UTC [48502][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.687 UTC [48502][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.688 UTC [48502][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-10 05:40:59.724 UTC [48502][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.037 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.819 UTC [48548][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.819 UTC [48548][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.819 UTC [48548][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.822 UTC [48548][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-10 05:40:59.875 UTC [48548][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.056 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.933 UTC [48581][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.933 UTC [48581][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.933 UTC [48581][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.934 UTC [48581][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-10 05:40:59.962 UTC [48581][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.028 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.979 UTC [48594][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.981 UTC [48594][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.981 UTC [48594][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.982 UTC [48594][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-10 05:41:00.025 UTC [48594][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.046 user=postgres database=postgres host=[local] 2024-03-10 05:41:00.113 UTC [48630][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:41:00.114 UTC [48630][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:41:00.114 UTC [48630][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:41:00.114 UTC [48630][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-10 05:41:00.137 UTC [48630][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.024 user=postgres database=postgres host=[local] 2024-03-10 05:41:00.153 UTC [48638][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:41:00.154 UTC [48638][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:41:00.154 UTC [48638][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:41:00.155 UTC [48638][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-10 05:41:00.196 UTC [48638][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.043 user=postgres database=postgres host=[local] 2024-03-10 05:41:00.281 UTC [48382][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:00.281 UTC [48382][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:00.282 UTC [48382][postmaster] LOG: background worker "logical replication launcher" (PID 48399) exited with exit code 1 2024-03-10 05:41:00.283 UTC [48394][checkpointer] LOG: shutting down 2024-03-10 05:41:00.283 UTC [48394][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:00.321 UTC [48394][checkpointer] LOG: checkpoint complete: wrote 5650 buffers (34.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.038 s, sync=0.001 s, total=0.039 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45479 kB, estimate=45479 kB; lsn=0/41961F0, redo lsn=0/41961F0 2024-03-10 05:41:00.330 UTC [48382][postmaster] LOG: database system is shut down 2024-03-10 05:41:00.420 UTC [48684][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:00.420 UTC [48684][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:00.420 UTC [48684][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:00.431 UTC [48695][startup] LOG: database system was shut down at 2024-03-10 05:41:00 UTC 2024-03-10 05:41:00.433 UTC [48684][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:00.486 UTC [48684][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:00.486 UTC [48684][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:00.487 UTC [48684][postmaster] LOG: background worker "logical replication launcher" (PID 48701) exited with exit code 1 2024-03-10 05:41:00.488 UTC [48692][checkpointer] LOG: shutting down 2024-03-10 05:41:00.488 UTC [48692][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:00.488 UTC [48692][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/4196268, redo lsn=0/4196268 2024-03-10 05:41:00.491 UTC [48684][postmaster] LOG: database system is shut down 2024-03-10 05:41:00.514 UTC [48719][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-10 05:41:00.514 UTC [48719][postmaster] LOG: database system is shut down 2024-03-10 05:41:00.616 UTC [48760][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:00.616 UTC [48760][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:00.616 UTC [48760][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:00.625 UTC [48765][startup] LOG: database system was shut down at 2024-03-10 05:41:00 UTC 2024-03-10 05:41:00.628 UTC [48760][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:00.701 UTC [48760][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:00.701 UTC [48760][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:00.702 UTC [48760][postmaster] LOG: background worker "logical replication launcher" (PID 48770) exited with exit code 1 2024-03-10 05:41:00.703 UTC [48763][checkpointer] LOG: shutting down 2024-03-10 05:41:00.703 UTC [48763][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:00.704 UTC [48763][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/41962E0, redo lsn=0/41962E0 2024-03-10 05:41:00.706 UTC [48760][postmaster] LOG: database system is shut down 2024-03-10 05:41:00.816 UTC [48829][postmaster] FATAL: could not set SSL protocol version range 2024-03-10 05:41:00.816 UTC [48829][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-10 05:41:00.816 UTC [48829][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [05:41:00.902](0.203s) 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 48848 [05:41:01.005](0.103s) ok 5 - restart succeeds with correct SSL protocol bounds [05:41:01.006](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 48905 [05:41:01.217](0.212s) ok 6 - server doesn't accept non-SSL connections [05:41:01.217](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [05:41:01.234](0.016s) ok 8 - connect without server root cert sslmode=require [05:41:01.234](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [05:41:01.243](0.009s) ok 10 - connect without server root cert sslmode=verify-ca [05:41:01.243](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [05:41:01.252](0.009s) ok 12 - connect without server root cert sslmode=verify-full [05:41:01.252](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [05:41:01.263](0.011s) ok 14 - connect with wrong server root cert sslmode=require [05:41:01.264](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [05:41:01.274](0.010s) ok 16 - connect with wrong server root cert sslmode=verify-ca [05:41:01.274](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [05:41:01.286](0.012s) ok 18 - connect with wrong server root cert sslmode=verify-full [05:41:01.286](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [05:41:01.297](0.011s) ok 20 - connect with server CA cert, without root CA [05:41:01.297](0.000s) ok 21 - connect with server CA cert, without root CA: matches [05:41:01.309](0.012s) ok 22 - connect with correct server CA cert file sslmode=require [05:41:01.309](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [05:41:01.321](0.012s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [05:41:01.321](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [05:41:01.334](0.013s) ok 26 - connect with correct server CA cert file sslmode=verify-full [05:41:01.334](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [05:41:01.346](0.012s) ok 28 - cert root file that contains two certificates, order 1 [05:41:01.347](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [05:41:01.359](0.012s) ok 30 - cert root file that contains two certificates, order 2 [05:41:01.359](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [05:41:01.372](0.013s) ok 32 - connect with sslcertmode=disable [05:41:01.372](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [05:41:01.387](0.015s) ok 34 - connect with sslcertmode=allow [05:41:01.387](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [05:41:01.404](0.017s) ok 36 - connect with sslcertmode=require fails without a client certificate [05:41:01.405](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [05:41:01.421](0.017s) ok 38 - sslcrl option with invalid file name [05:41:01.422](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [05:41:01.434](0.012s) ok 40 - CRL belonging to a different CA [05:41:01.434](0.000s) ok 41 - CRL belonging to a different CA: matches [05:41:01.446](0.012s) ok 42 - directory CRL belonging to a different CA [05:41:01.447](0.000s) ok 43 - directory CRL belonging to a different CA: matches [05:41:01.460](0.013s) ok 44 - CRL with a non-revoked cert [05:41:01.460](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [05:41:01.473](0.013s) ok 46 - directory CRL with a non-revoked cert [05:41:01.473](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [05:41:01.485](0.012s) ok 48 - mismatch between host name and server certificate sslmode=require [05:41:01.485](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [05:41:01.497](0.012s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [05:41:01.498](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [05:41:01.508](0.010s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [05:41:01.508](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 49060 [05:41:01.730](0.222s) ok 54 - IP address in the Common Name [05:41:01.730](0.000s) ok 55 - IP address in the Common Name: no stderr [05:41:01.741](0.012s) ok 56 - mismatch between host name and server certificate IP address [05:41:01.742](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 49087 [05:41:01.963](0.221s) ok 58 - IP address in a dNSName [05:41:01.963](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 49110 [05:41:02.185](0.222s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [05:41:02.185](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [05:41:02.199](0.014s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [05:41:02.199](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [05:41:02.213](0.014s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [05:41:02.213](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [05:41:02.225](0.012s) ok 66 - host name not matching with X.509 Subject Alternative Names [05:41:02.225](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [05:41:02.237](0.011s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [05:41:02.237](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 49142 [05:41:02.458](0.222s) ok 70 - host name matching with a single X.509 Subject Alternative Name [05:41:02.459](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [05:41:02.471](0.012s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [05:41:02.471](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [05:41:02.482](0.011s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [05:41:02.483](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 49171 [05:41:02.707](0.225s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [05:41:02.708](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [05:41:02.722](0.014s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [05:41:02.722](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [05:41:02.735](0.013s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [05:41:02.735](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [05:41:02.750](0.014s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [05:41:02.750](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [05:41:02.765](0.015s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [05:41:02.765](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [05:41:02.779](0.013s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [05:41:02.779](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [05:41:02.790](0.012s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [05:41:02.791](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [05:41:02.802](0.012s) ok 90 - IPv6 host with CIDR mask does not match [05:41:02.802](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 49213 [05:41:03.024](0.222s) ok 92 - certificate with both a CN and SANs 1 [05:41:03.024](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [05:41:03.037](0.013s) ok 94 - certificate with both a CN and SANs 2 [05:41:03.038](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [05:41:03.049](0.011s) ok 96 - certificate with both a CN and SANs ignores CN [05:41:03.049](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 49231 [05:41:03.274](0.224s) ok 98 - certificate with both a CN and IP SANs matches CN [05:41:03.274](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [05:41:03.288](0.014s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [05:41:03.288](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [05:41:03.302](0.013s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [05:41:03.302](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 49245 [05:41:03.522](0.220s) ok 104 - certificate with both an IP CN and IP SANs 1 [05:41:03.522](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [05:41:03.535](0.013s) ok 106 - certificate with both an IP CN and IP SANs 2 [05:41:03.536](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [05:41:03.546](0.011s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [05:41:03.547](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 49259 [05:41:03.767](0.220s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [05:41:03.767](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [05:41:03.780](0.013s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [05:41:03.780](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [05:41:03.793](0.013s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [05:41:03.793](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 49273 [05:41:04.015](0.222s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [05:41:04.015](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [05:41:04.026](0.011s) ok 118 - server certificate without CN or SANs sslmode=verify-full [05:41:04.026](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 49285 [05:41:04.241](0.215s) ok 120 - sslrootcert=system does not connect with private CA [05:41:04.241](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [05:41:04.247](0.006s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [05:41:04.247](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [05:41:04.263](0.016s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [05:41:04.263](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [05:41:04.275](0.011s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [05:41:04.275](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 49300 [05:41:04.496](0.222s) ok 128 - connects without client-side CRL [05:41:04.496](0.000s) ok 129 - connects without client-side CRL: no stderr [05:41:04.509](0.012s) ok 130 - does not connect with client-side CRL file [05:41:04.509](0.000s) ok 131 - does not connect with client-side CRL file: matches [05:41:04.520](0.011s) ok 132 - does not connect with client-side CRL directory [05:41:04.520](0.000s) ok 133 - does not connect with client-side CRL directory: matches # Running: psql -X -A -F , -P null=_null_ -d sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [05:41:04.532](0.012s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [05:41:04.533](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [05:41:04.533](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [05:41:04.545](0.012s) ok 137 - connection success with correct range of TLS protocol versions [05:41:04.545](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [05:41:04.551](0.006s) ok 139 - connection failure with incorrect range of TLS protocol versions [05:41:04.552](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [05:41:04.557](0.006s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [05:41:04.558](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [05:41:04.563](0.006s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [05:41:04.563](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [05:41:04.564](0.000s) # running server tests [05:41:04.575](0.012s) ok 145 - certificate authorization fails without client cert [05:41:04.575](0.000s) ok 146 - certificate authorization fails without client cert: matches [05:41:04.593](0.018s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [05:41:04.593](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [05:41:04.607](0.014s) ok 149 - certificate authorization succeeds with correct client cert in DER format [05:41:04.607](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [05:41:04.622](0.014s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [05:41:04.622](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [05:41:04.635](0.014s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [05:41:04.636](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [05:41:04.649](0.013s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [05:41:04.649](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [05:41:04.663](0.014s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [05:41:04.663](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [05:41:04.675](0.013s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [05:41:04.676](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [05:41:04.684](0.009s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [05:41:04.685](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [05:41:04.702](0.018s) ok 163 - certificate authorization succeeds with DN mapping [05:41:04.703](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [05:41:04.703](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [05:41:04.721](0.018s) ok 166 - certificate authorization succeeds with DN regex mapping [05:41:04.721](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [05:41:04.739](0.018s) ok 168 - certificate authorization succeeds with CN mapping [05:41:04.739](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [05:41:04.739](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [05:41:04.739](0.000s) not ok 171 # TODO & SKIP Need Pty support [05:41:04.739](0.000s) not ok 172 # TODO & SKIP Need Pty support [05:41:04.739](0.000s) not ok 173 # TODO & SKIP Need Pty support [05:41:04.740](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_815s/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [05:41:04.765](0.026s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [05:41:04.765](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [05:41:04.765](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [05:41:04.775](0.009s) ok 178 - certificate authorization fails because of file permissions [05:41:04.775](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [05:41:04.788](0.013s) ok 180 - certificate authorization fails with client cert belonging to another user [05:41:04.788](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [05:41:04.800](0.012s) ok 182 - certificate authorization fails with revoked client cert [05:41:04.800](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [05:41:04.800](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [05:41:04.818](0.018s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [05:41:04.818](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [05:41:04.819](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [05:41:04.831](0.013s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [05:41:04.831](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [05:41:04.832](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [05:41:04.845](0.014s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [05:41:04.845](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [05:41:04.846](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 49360 [05:41:05.068](0.223s) ok 194 - intermediate client certificate is provided by client [05:41:05.069](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [05:41:05.081](0.012s) ok 196 - intermediate client certificate is missing [05:41:05.081](0.000s) ok 197 - intermediate client certificate is missing: matches [05:41:05.093](0.012s) ok 198 - logged client certificate Subjects are truncated if they're too long [05:41:05.093](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 49374 [05:41:05.310](0.217s) ok 200 - intermediate client certificate is untrusted [05:41:05.310](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 49384 [05:41:05.527](0.217s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [05:41:05.527](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [05:41:05.540](0.013s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [05:41:05.540](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 49396 [05:41:05.754](0.214s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [05:41:05.754](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 923. [05:41:05.755](0.000s) # got: '2' # expected: '0' [05:41:05.755](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [05:41:05.755](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 923. [05:41:05.755](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 57112 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: '' [05:41:05.772](0.017s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [05:41:05.772](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 49408 [05:41:05.986](0.214s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [05:41:06.003](0.017s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [05:41:06.003](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 49420 [05:41:06.218](0.214s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [05:41:06.234](0.017s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [05:41:06.235](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 49432 [05:41:06.449](0.215s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [05:41:06.466](0.017s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [05:41:06.466](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 49444 [05:41:06.681](0.215s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [05:41:06.681](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 1001. [05:41:06.681](0.000s) # got: '2' # expected: '0' [05:41:06.681](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [05:41:06.681](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr' # at /tmp/cirrus-ci-build/src/test/ssl/t/001_ssltests.pl line 1001. [05:41:06.681](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 57112 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: '' [05:41:06.699](0.017s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [05:41:06.699](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 49456 [05:41:06.913](0.215s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [05:41:06.930](0.017s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [05:41:06.930](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 49468 [05:41:07.144](0.214s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [05:41:07.160](0.016s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [05:41:07.161](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 49480 [05:41:07.375](0.215s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [05:41:07.393](0.018s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [05:41:07.394](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 49492 [05:41:07.609](0.215s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [05:41:07.626](0.018s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [05:41:07.627](0.000s) ok 234 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... stopped waiting pg_ctl: could not start server Examine the log output. # pg_ctl restart failed; logfile: 2024-03-10 05:40:59.399 UTC [48382][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:40:59.401 UTC [48382][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:40:59.403 UTC [48396][startup] LOG: database system was shut down at 2024-03-10 05:38:41 UTC 2024-03-10 05:40:59.409 UTC [48382][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:40:59.492 UTC [48428][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.492 UTC [48428][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.492 UTC [48428][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.497 UTC [48428][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-10 05:40:59.500 UTC [48428][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.507 UTC [48440][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.508 UTC [48440][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.508 UTC [48440][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.509 UTC [48440][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-10 05:40:59.509 UTC [48440][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.559 UTC [48468][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.559 UTC [48468][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.559 UTC [48468][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.562 UTC [48468][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-10 05:40:59.565 UTC [48468][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.597 UTC [48481][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.597 UTC [48481][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.597 UTC [48481][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.598 UTC [48481][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-10 05:40:59.598 UTC [48481][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.641 UTC [48491][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.641 UTC [48491][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.641 UTC [48491][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.642 UTC [48491][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-10 05:40:59.642 UTC [48491][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.686 UTC [48502][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.687 UTC [48502][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.687 UTC [48502][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.688 UTC [48502][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-10 05:40:59.724 UTC [48502][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.037 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.819 UTC [48548][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.819 UTC [48548][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.819 UTC [48548][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.822 UTC [48548][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-10 05:40:59.875 UTC [48548][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.056 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.933 UTC [48581][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.933 UTC [48581][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.933 UTC [48581][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.934 UTC [48581][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-10 05:40:59.962 UTC [48581][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.028 user=postgres database=postgres host=[local] 2024-03-10 05:40:59.979 UTC [48594][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:40:59.981 UTC [48594][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:40:59.981 UTC [48594][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:40:59.982 UTC [48594][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-10 05:41:00.025 UTC [48594][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.046 user=postgres database=postgres host=[local] 2024-03-10 05:41:00.113 UTC [48630][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:41:00.114 UTC [48630][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:41:00.114 UTC [48630][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:41:00.114 UTC [48630][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-10 05:41:00.137 UTC [48630][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.024 user=postgres database=postgres host=[local] 2024-03-10 05:41:00.153 UTC [48638][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:41:00.154 UTC [48638][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-10 05:41:00.154 UTC [48638][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=postgres database=postgres application_name=001_ssltests.pl 2024-03-10 05:41:00.155 UTC [48638][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-10 05:41:00.196 UTC [48638][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.043 user=postgres database=postgres host=[local] 2024-03-10 05:41:00.281 UTC [48382][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:00.281 UTC [48382][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:00.282 UTC [48382][postmaster] LOG: background worker "logical replication launcher" (PID 48399) exited with exit code 1 2024-03-10 05:41:00.283 UTC [48394][checkpointer] LOG: shutting down 2024-03-10 05:41:00.283 UTC [48394][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:00.321 UTC [48394][checkpointer] LOG: checkpoint complete: wrote 5650 buffers (34.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.038 s, sync=0.001 s, total=0.039 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45479 kB, estimate=45479 kB; lsn=0/41961F0, redo lsn=0/41961F0 2024-03-10 05:41:00.330 UTC [48382][postmaster] LOG: database system is shut down 2024-03-10 05:41:00.420 UTC [48684][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:00.420 UTC [48684][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:00.420 UTC [48684][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:00.431 UTC [48695][startup] LOG: database system was shut down at 2024-03-10 05:41:00 UTC 2024-03-10 05:41:00.433 UTC [48684][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:00.486 UTC [48684][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:00.486 UTC [48684][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:00.487 UTC [48684][postmaster] LOG: background worker "logical replication launcher" (PID 48701) exited with exit code 1 2024-03-10 05:41:00.488 UTC [48692][checkpointer] LOG: shutting down 2024-03-10 05:41:00.488 UTC [48692][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:00.488 UTC [48692][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/4196268, redo lsn=0/4196268 2024-03-10 05:41:00.491 UTC [48684][postmaster] LOG: database system is shut down 2024-03-10 05:41:00.514 UTC [48719][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-10 05:41:00.514 UTC [48719][postmaster] LOG: database system is shut down 2024-03-10 05:41:00.616 UTC [48760][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:00.616 UTC [48760][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:00.616 UTC [48760][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:00.625 UTC [48765][startup] LOG: database system was shut down at 2024-03-10 05:41:00 UTC 2024-03-10 05:41:00.628 UTC [48760][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:00.701 UTC [48760][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:00.701 UTC [48760][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:00.702 UTC [48760][postmaster] LOG: background worker "logical replication launcher" (PID 48770) exited with exit code 1 2024-03-10 05:41:00.703 UTC [48763][checkpointer] LOG: shutting down 2024-03-10 05:41:00.703 UTC [48763][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:00.704 UTC [48763][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/41962E0, redo lsn=0/41962E0 2024-03-10 05:41:00.706 UTC [48760][postmaster] LOG: database system is shut down 2024-03-10 05:41:00.816 UTC [48829][postmaster] FATAL: could not set SSL protocol version range 2024-03-10 05:41:00.816 UTC [48829][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-10 05:41:00.816 UTC [48829][postmaster] LOG: database system is shut down 2024-03-10 05:41:00.924 UTC [48848][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:00.924 UTC [48848][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:00.924 UTC [48848][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:00.927 UTC [48855][startup] LOG: database system was shut down at 2024-03-10 05:41:00 UTC 2024-03-10 05:41:00.930 UTC [48848][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:01.008 UTC [48848][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:01.008 UTC [48848][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:01.009 UTC [48848][postmaster] LOG: background worker "logical replication launcher" (PID 48858) exited with exit code 1 2024-03-10 05:41:01.009 UTC [48853][checkpointer] LOG: shutting down 2024-03-10 05:41:01.009 UTC [48853][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:01.009 UTC [48853][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/4196358, redo lsn=0/4196358 2024-03-10 05:41:01.012 UTC [48848][postmaster] LOG: database system is shut down 2024-03-10 05:41:01.124 UTC [48905][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:01.124 UTC [48905][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:01.125 UTC [48905][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:01.127 UTC [48911][startup] LOG: database system was shut down at 2024-03-10 05:41:01 UTC 2024-03-10 05:41:01.130 UTC [48905][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:01.215 UTC [48937][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50480 2024-03-10 05:41:01.216 UTC [48937][client backend] [[unknown]][0/1:0] FATAL: no pg_hba.conf entry for host "127.0.0.1", user "ssltestuser", database "trustdb", no encryption 2024-03-10 05:41:01.216 UTC [48937][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-03-10 05:41:01.224 UTC [48939][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50490 2024-03-10 05:41:01.227 UTC [48939][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:01.227 UTC [48939][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:01.232 UTC [48939][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid sslmode=require$$ 2024-03-10 05:41:01.233 UTC [48939][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=50490 2024-03-10 05:41:01.241 UTC [48941][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50498 2024-03-10 05:41:01.242 UTC [48941][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-10 05:41:01.250 UTC [48944][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50504 2024-03-10 05:41:01.251 UTC [48944][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-10 05:41:01.259 UTC [48947][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50510 2024-03-10 05:41:01.262 UTC [48947][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-10 05:41:01.270 UTC [48952][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50514 2024-03-10 05:41:01.275 UTC [48952][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-10 05:41:01.282 UTC [48959][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50524 2024-03-10 05:41:01.286 UTC [48959][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-10 05:41:01.293 UTC [48968][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50532 2024-03-10 05:41:01.296 UTC [48968][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-10 05:41:01.303 UTC [48975][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50546 2024-03-10 05:41:01.307 UTC [48975][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:01.307 UTC [48975][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:01.308 UTC [48975][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require$$ 2024-03-10 05:41:01.308 UTC [48975][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=50546 2024-03-10 05:41:01.315 UTC [48979][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50554 2024-03-10 05:41:01.319 UTC [48979][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:01.319 UTC [48979][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:01.320 UTC [48979][client backend] [001_ssltests.pl][3/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$ 2024-03-10 05:41:01.320 UTC [48979][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=50554 2024-03-10 05:41:01.328 UTC [48982][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50564 2024-03-10 05:41:01.332 UTC [48982][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:01.332 UTC [48982][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:01.333 UTC [48982][client backend] [001_ssltests.pl][4/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-full$$ 2024-03-10 05:41:01.333 UTC [48982][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=50564 2024-03-10 05:41:01.341 UTC [48986][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50574 2024-03-10 05:41:01.344 UTC [48986][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:01.344 UTC [48986][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:01.345 UTC [48986][client backend] [001_ssltests.pl][5/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-1.crt sslmode=verify-ca$$ 2024-03-10 05:41:01.345 UTC [48986][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=50574 2024-03-10 05:41:01.353 UTC [48993][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50578 2024-03-10 05:41:01.356 UTC [48993][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:01.356 UTC [48993][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:01.357 UTC [48993][client backend] [001_ssltests.pl][6/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-2.crt sslmode=verify-ca$$ 2024-03-10 05:41:01.358 UTC [48993][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=50578 2024-03-10 05:41:01.365 UTC [48998][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50590 2024-03-10 05:41:01.369 UTC [48998][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:01.369 UTC [48998][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:01.370 UTC [48998][client backend] [001_ssltests.pl][7/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=disable$$ 2024-03-10 05:41:01.370 UTC [48998][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=50590 2024-03-10 05:41:01.380 UTC [49003][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50594 2024-03-10 05:41:01.385 UTC [49003][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:01.385 UTC [49003][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:01.385 UTC [49003][client backend] [001_ssltests.pl][8/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=allow$$ 2024-03-10 05:41:01.387 UTC [49003][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=50594 2024-03-10 05:41:01.395 UTC [49010][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50598 2024-03-10 05:41:01.402 UTC [49010][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:01.402 UTC [49010][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:01.404 UTC [49010][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=50598 2024-03-10 05:41:01.415 UTC [49015][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50604 2024-03-10 05:41:01.418 UTC [49015][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:01.419 UTC [49015][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:01.419 UTC [49015][client backend] [001_ssltests.pl][10/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=invalid$$ 2024-03-10 05:41:01.420 UTC [49015][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=50604 2024-03-10 05:41:01.429 UTC [49019][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50614 2024-03-10 05:41:01.432 UTC [49019][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-10 05:41:01.442 UTC [49023][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50622 2024-03-10 05:41:01.445 UTC [49023][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-10 05:41:01.453 UTC [49026][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50628 2024-03-10 05:41:01.457 UTC [49026][client backend] [[unknown]][11/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:01.457 UTC [49026][client backend] [[unknown]][11/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:01.458 UTC [49026][client backend] [001_ssltests.pl][11/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=ssl/root+server.crl$$ 2024-03-10 05:41:01.459 UTC [49026][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=50628 2024-03-10 05:41:01.467 UTC [49029][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50642 2024-03-10 05:41:01.470 UTC [49029][client backend] [[unknown]][12/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:01.470 UTC [49029][client backend] [[unknown]][12/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:01.471 UTC [49029][client backend] [001_ssltests.pl][12/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrldir=ssl/root+server-crldir$$ 2024-03-10 05:41:01.471 UTC [49029][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=50642 2024-03-10 05:41:01.479 UTC [49033][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50648 2024-03-10 05:41:01.482 UTC [49033][client backend] [[unknown]][13/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:01.483 UTC [49033][client backend] [[unknown]][13/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:01.483 UTC [49033][client backend] [001_ssltests.pl][13/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=require host=wronghost.test$$ 2024-03-10 05:41:01.484 UTC [49033][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=ssltestuser database=trustdb host=localhost port=50648 2024-03-10 05:41:01.491 UTC [49037][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50664 2024-03-10 05:41:01.495 UTC [49037][client backend] [[unknown]][14/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:01.495 UTC [49037][client backend] [[unknown]][14/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:01.496 UTC [49037][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=wronghost.test$$ 2024-03-10 05:41:01.496 UTC [49037][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=50664 2024-03-10 05:41:01.503 UTC [49042][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50680 2024-03-10 05:41:01.510 UTC [48905][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:01.511 UTC [48905][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:01.512 UTC [48905][postmaster] LOG: background worker "logical replication launcher" (PID 48916) exited with exit code 1 2024-03-10 05:41:01.514 UTC [48909][checkpointer] LOG: shutting down 2024-03-10 05:41:01.514 UTC [48909][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:01.514 UTC [48909][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/41963D0, redo lsn=0/41963D0 2024-03-10 05:41:01.517 UTC [48905][postmaster] LOG: database system is shut down 2024-03-10 05:41:01.627 UTC [49060][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:01.627 UTC [49060][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:01.627 UTC [49060][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:01.631 UTC [49066][startup] LOG: database system was shut down at 2024-03-10 05:41:01 UTC 2024-03-10 05:41:01.634 UTC [49060][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:01.719 UTC [49072][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50694 2024-03-10 05:41:01.722 UTC [49072][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:01.722 UTC [49072][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:01.728 UTC [49072][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-10 05:41:01.728 UTC [49072][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=50694 2024-03-10 05:41:01.737 UTC [49075][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50698 2024-03-10 05:41:01.744 UTC [49060][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:01.744 UTC [49060][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:01.746 UTC [49060][postmaster] LOG: background worker "logical replication launcher" (PID 49070) exited with exit code 1 2024-03-10 05:41:01.746 UTC [49064][checkpointer] LOG: shutting down 2024-03-10 05:41:01.746 UTC [49064][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:01.747 UTC [49064][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/4196448, redo lsn=0/4196448 2024-03-10 05:41:01.749 UTC [49060][postmaster] LOG: database system is shut down 2024-03-10 05:41:01.861 UTC [49087][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:01.861 UTC [49087][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:01.861 UTC [49087][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:01.863 UTC [49091][startup] LOG: database system was shut down at 2024-03-10 05:41:01 UTC 2024-03-10 05:41:01.866 UTC [49087][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:01.953 UTC [49099][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50704 2024-03-10 05:41:01.956 UTC [49099][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:01.956 UTC [49099][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:01.961 UTC [49099][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-10 05:41:01.962 UTC [49099][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=50704 2024-03-10 05:41:01.966 UTC [49087][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:01.966 UTC [49087][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:01.967 UTC [49087][postmaster] LOG: background worker "logical replication launcher" (PID 49094) exited with exit code 1 2024-03-10 05:41:01.967 UTC [49089][checkpointer] LOG: shutting down 2024-03-10 05:41:01.967 UTC [49089][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:01.968 UTC [49089][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/41964C0, redo lsn=0/41964C0 2024-03-10 05:41:01.970 UTC [49087][postmaster] LOG: database system is shut down 2024-03-10 05:41:02.083 UTC [49110][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:02.083 UTC [49110][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:02.083 UTC [49110][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:02.085 UTC [49115][startup] LOG: database system was shut down at 2024-03-10 05:41:01 UTC 2024-03-10 05:41:02.088 UTC [49110][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:02.173 UTC [49121][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50720 2024-03-10 05:41:02.178 UTC [49121][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:02.178 UTC [49121][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:02.183 UTC [49121][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-03-10 05:41:02.183 UTC [49121][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=50720 2024-03-10 05:41:02.192 UTC [49129][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50734 2024-03-10 05:41:02.196 UTC [49129][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:02.196 UTC [49129][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:02.197 UTC [49129][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-03-10 05:41:02.198 UTC [49129][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=50734 2024-03-10 05:41:02.207 UTC [49131][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50750 2024-03-10 05:41:02.210 UTC [49131][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:02.210 UTC [49131][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:02.211 UTC [49131][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=foo.wildcard.pg-ssltest.test$$ 2024-03-10 05:41:02.212 UTC [49131][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=50750 2024-03-10 05:41:02.220 UTC [49133][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50756 2024-03-10 05:41:02.232 UTC [49135][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50770 2024-03-10 05:41:02.239 UTC [49110][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:02.239 UTC [49110][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:02.241 UTC [49110][postmaster] LOG: background worker "logical replication launcher" (PID 49118) exited with exit code 1 2024-03-10 05:41:02.242 UTC [49113][checkpointer] LOG: shutting down 2024-03-10 05:41:02.242 UTC [49113][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:02.242 UTC [49113][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/4196538, redo lsn=0/4196538 2024-03-10 05:41:02.245 UTC [49110][postmaster] LOG: database system is shut down 2024-03-10 05:41:02.358 UTC [49142][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:02.358 UTC [49142][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:02.358 UTC [49142][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:02.360 UTC [49145][startup] LOG: database system was shut down at 2024-03-10 05:41:02 UTC 2024-03-10 05:41:02.363 UTC [49142][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:02.448 UTC [49159][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50786 2024-03-10 05:41:02.452 UTC [49159][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:02.452 UTC [49159][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:02.457 UTC [49159][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=single.alt-name.pg-ssltest.test$$ 2024-03-10 05:41:02.457 UTC [49159][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=50786 2024-03-10 05:41:02.466 UTC [49162][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50802 2024-03-10 05:41:02.478 UTC [49165][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50804 2024-03-10 05:41:02.488 UTC [49142][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:02.488 UTC [49142][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:02.489 UTC [49142][postmaster] LOG: background worker "logical replication launcher" (PID 49148) exited with exit code 1 2024-03-10 05:41:02.490 UTC [49143][checkpointer] LOG: shutting down 2024-03-10 05:41:02.490 UTC [49143][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:02.490 UTC [49143][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/41965B0, redo lsn=0/41965B0 2024-03-10 05:41:02.493 UTC [49142][postmaster] LOG: database system is shut down 2024-03-10 05:41:02.606 UTC [49171][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:02.606 UTC [49171][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:02.606 UTC [49171][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:02.608 UTC [49174][startup] LOG: database system was shut down at 2024-03-10 05:41:02 UTC 2024-03-10 05:41:02.612 UTC [49171][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:02.697 UTC [49187][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50814 2024-03-10 05:41:02.700 UTC [49187][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:02.701 UTC [49187][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:02.705 UTC [49187][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-10 05:41:02.706 UTC [49187][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=50814 2024-03-10 05:41:02.715 UTC [49189][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50822 2024-03-10 05:41:02.719 UTC [49189][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:02.719 UTC [49189][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:02.720 UTC [49189][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.000.002.001$$ 2024-03-10 05:41:02.720 UTC [49189][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=50822 2024-03-10 05:41:02.730 UTC [49192][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50836 2024-03-10 05:41:02.743 UTC [49195][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50840 2024-03-10 05:41:02.747 UTC [49195][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:02.747 UTC [49195][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:02.748 UTC [49195][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:DB8::1$$ 2024-03-10 05:41:02.749 UTC [49195][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=50840 2024-03-10 05:41:02.758 UTC [49198][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50844 2024-03-10 05:41:02.762 UTC [49198][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:02.762 UTC [49198][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:02.763 UTC [49198][client backend] [001_ssltests.pl][3/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8:0:0:0:0:0:1$$ 2024-03-10 05:41:02.764 UTC [49198][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=50844 2024-03-10 05:41:02.772 UTC [49200][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50848 2024-03-10 05:41:02.776 UTC [49200][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:02.776 UTC [49200][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:02.777 UTC [49200][client backend] [001_ssltests.pl][4/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::0.0.0.1$$ 2024-03-10 05:41:02.777 UTC [49200][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=50848 2024-03-10 05:41:02.786 UTC [49202][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50858 2024-03-10 05:41:02.798 UTC [49204][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50874 2024-03-10 05:41:02.805 UTC [49171][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:02.805 UTC [49171][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:02.806 UTC [49171][postmaster] LOG: background worker "logical replication launcher" (PID 49177) exited with exit code 1 2024-03-10 05:41:02.806 UTC [49172][checkpointer] LOG: shutting down 2024-03-10 05:41:02.806 UTC [49172][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:02.807 UTC [49172][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/4196628, redo lsn=0/4196628 2024-03-10 05:41:02.809 UTC [49171][postmaster] LOG: database system is shut down 2024-03-10 05:41:02.923 UTC [49213][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:02.923 UTC [49213][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:02.923 UTC [49213][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:02.926 UTC [49216][startup] LOG: database system was shut down at 2024-03-10 05:41:02 UTC 2024-03-10 05:41:02.928 UTC [49213][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:03.013 UTC [49224][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50886 2024-03-10 05:41:03.017 UTC [49224][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:03.017 UTC [49224][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:03.022 UTC [49224][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-03-10 05:41:03.023 UTC [49224][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=50886 2024-03-10 05:41:03.031 UTC [49226][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50900 2024-03-10 05:41:03.035 UTC [49226][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:03.035 UTC [49226][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:03.036 UTC [49226][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-03-10 05:41:03.036 UTC [49226][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=50900 2024-03-10 05:41:03.044 UTC [49228][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50906 2024-03-10 05:41:03.054 UTC [49213][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:03.054 UTC [49213][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:03.055 UTC [49213][postmaster] LOG: background worker "logical replication launcher" (PID 49219) exited with exit code 1 2024-03-10 05:41:03.055 UTC [49214][checkpointer] LOG: shutting down 2024-03-10 05:41:03.056 UTC [49214][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:03.056 UTC [49214][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/41966A0, redo lsn=0/41966A0 2024-03-10 05:41:03.059 UTC [49213][postmaster] LOG: database system is shut down 2024-03-10 05:41:03.172 UTC [49231][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:03.172 UTC [49231][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:03.172 UTC [49231][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:03.174 UTC [49234][startup] LOG: database system was shut down at 2024-03-10 05:41:03 UTC 2024-03-10 05:41:03.177 UTC [49231][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:03.263 UTC [49239][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50910 2024-03-10 05:41:03.266 UTC [49239][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:03.266 UTC [49239][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:03.272 UTC [49239][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$ 2024-03-10 05:41:03.272 UTC [49239][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=50910 2024-03-10 05:41:03.281 UTC [49241][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50912 2024-03-10 05:41:03.285 UTC [49241][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:03.285 UTC [49241][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:03.286 UTC [49241][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-10 05:41:03.287 UTC [49241][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=50912 2024-03-10 05:41:03.295 UTC [49243][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50928 2024-03-10 05:41:03.299 UTC [49243][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:03.299 UTC [49243][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:03.300 UTC [49243][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$ 2024-03-10 05:41:03.300 UTC [49243][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=50928 2024-03-10 05:41:03.304 UTC [49231][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:03.304 UTC [49231][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:03.305 UTC [49231][postmaster] LOG: background worker "logical replication launcher" (PID 49237) exited with exit code 1 2024-03-10 05:41:03.305 UTC [49232][checkpointer] LOG: shutting down 2024-03-10 05:41:03.305 UTC [49232][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:03.306 UTC [49232][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/4196718, redo lsn=0/4196718 2024-03-10 05:41:03.308 UTC [49231][postmaster] LOG: database system is shut down 2024-03-10 05:41:03.422 UTC [49245][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:03.422 UTC [49245][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:03.422 UTC [49245][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:03.424 UTC [49248][startup] LOG: database system was shut down at 2024-03-10 05:41:03 UTC 2024-03-10 05:41:03.427 UTC [49245][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:03.512 UTC [49253][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50944 2024-03-10 05:41:03.516 UTC [49253][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:03.516 UTC [49253][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:03.520 UTC [49253][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.2$$ 2024-03-10 05:41:03.521 UTC [49253][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=50944 2024-03-10 05:41:03.529 UTC [49255][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50946 2024-03-10 05:41:03.533 UTC [49255][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:03.533 UTC [49255][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:03.534 UTC [49255][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$ 2024-03-10 05:41:03.534 UTC [49255][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=50946 2024-03-10 05:41:03.542 UTC [49257][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50962 2024-03-10 05:41:03.548 UTC [49245][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:03.548 UTC [49245][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:03.550 UTC [49245][postmaster] LOG: background worker "logical replication launcher" (PID 49251) exited with exit code 1 2024-03-10 05:41:03.550 UTC [49246][checkpointer] LOG: shutting down 2024-03-10 05:41:03.550 UTC [49246][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:03.550 UTC [49246][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/4196790, redo lsn=0/4196790 2024-03-10 05:41:03.552 UTC [49245][postmaster] LOG: database system is shut down 2024-03-10 05:41:03.666 UTC [49259][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:03.666 UTC [49259][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:03.666 UTC [49259][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:03.668 UTC [49262][startup] LOG: database system was shut down at 2024-03-10 05:41:03 UTC 2024-03-10 05:41:03.671 UTC [49259][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:03.757 UTC [49267][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50972 2024-03-10 05:41:03.760 UTC [49267][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:03.760 UTC [49267][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:03.765 UTC [49267][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-10 05:41:03.766 UTC [49267][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=50972 2024-03-10 05:41:03.774 UTC [49269][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50988 2024-03-10 05:41:03.777 UTC [49269][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:03.777 UTC [49269][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:03.778 UTC [49269][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-03-10 05:41:03.779 UTC [49269][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=50988 2024-03-10 05:41:03.787 UTC [49271][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=50994 2024-03-10 05:41:03.790 UTC [49271][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:03.790 UTC [49271][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:03.791 UTC [49271][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-03-10 05:41:03.792 UTC [49271][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=50994 2024-03-10 05:41:03.795 UTC [49259][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:03.795 UTC [49259][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:03.796 UTC [49259][postmaster] LOG: background worker "logical replication launcher" (PID 49265) exited with exit code 1 2024-03-10 05:41:03.797 UTC [49260][checkpointer] LOG: shutting down 2024-03-10 05:41:03.797 UTC [49260][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:03.797 UTC [49260][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/4196808, redo lsn=0/4196808 2024-03-10 05:41:03.800 UTC [49259][postmaster] LOG: database system is shut down 2024-03-10 05:41:03.913 UTC [49273][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:03.913 UTC [49273][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:03.913 UTC [49273][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:03.915 UTC [49276][startup] LOG: database system was shut down at 2024-03-10 05:41:03 UTC 2024-03-10 05:41:03.918 UTC [49273][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:04.004 UTC [49281][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51000 2024-03-10 05:41:04.008 UTC [49281][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:04.008 UTC [49281][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:04.013 UTC [49281][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=common-name.pg-ssltest.test$$ 2024-03-10 05:41:04.013 UTC [49281][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=51000 2024-03-10 05:41:04.021 UTC [49283][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51016 2024-03-10 05:41:04.028 UTC [49273][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:04.028 UTC [49273][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:04.029 UTC [49273][postmaster] LOG: background worker "logical replication launcher" (PID 49279) exited with exit code 1 2024-03-10 05:41:04.029 UTC [49274][checkpointer] LOG: shutting down 2024-03-10 05:41:04.029 UTC [49274][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:04.029 UTC [49274][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/4196880, redo lsn=0/4196880 2024-03-10 05:41:04.032 UTC [49273][postmaster] LOG: database system is shut down 2024-03-10 05:41:04.145 UTC [49285][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:04.145 UTC [49285][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:04.145 UTC [49285][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:04.147 UTC [49288][startup] LOG: database system was shut down at 2024-03-10 05:41:04 UTC 2024-03-10 05:41:04.150 UTC [49285][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:04.236 UTC [49293][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51030 2024-03-10 05:41:04.239 UTC [49293][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-10 05:41:04.253 UTC [49296][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51036 2024-03-10 05:41:04.256 UTC [49296][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:04.256 UTC [49296][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:04.261 UTC [49296][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=system hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$ 2024-03-10 05:41:04.262 UTC [49296][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=51036 2024-03-10 05:41:04.270 UTC [49298][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51042 2024-03-10 05:41:04.277 UTC [49285][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:04.277 UTC [49285][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:04.278 UTC [49285][postmaster] LOG: background worker "logical replication launcher" (PID 49291) exited with exit code 1 2024-03-10 05:41:04.279 UTC [49286][checkpointer] LOG: shutting down 2024-03-10 05:41:04.279 UTC [49286][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:04.279 UTC [49286][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/41968F8, redo lsn=0/41968F8 2024-03-10 05:41:04.282 UTC [49285][postmaster] LOG: database system is shut down 2024-03-10 05:41:04.395 UTC [49300][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:04.395 UTC [49300][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:04.395 UTC [49300][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:04.397 UTC [49303][startup] LOG: database system was shut down at 2024-03-10 05:41:04 UTC 2024-03-10 05:41:04.400 UTC [49300][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:04.486 UTC [49308][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51048 2024-03-10 05:41:04.490 UTC [49308][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:04.490 UTC [49308][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:04.495 UTC [49308][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$ 2024-03-10 05:41:04.495 UTC [49308][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=51048 2024-03-10 05:41:04.504 UTC [49310][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51054 2024-03-10 05:41:04.508 UTC [49310][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-10 05:41:04.516 UTC [49312][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51066 2024-03-10 05:41:04.519 UTC [49312][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: sslv3 alert certificate revoked 2024-03-10 05:41:04.526 UTC [49314][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51082 2024-03-10 05:41:04.529 UTC [49314][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:04.529 UTC [49314][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:04.530 UTC [49314][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-10 05:41:04.531 UTC [49314][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=trustdb host=localhost port=51082 2024-03-10 05:41:04.539 UTC [49316][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51094 2024-03-10 05:41:04.543 UTC [49316][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:04.543 UTC [49316][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.2, cipher=ECDHE-RSA-AES256-GCM-SHA384, bits=256) 2024-03-10 05:41:04.544 UTC [49316][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require ssl_min_protocol_version=TLSv1.2 ssl_max_protocol_version=TLSv1.2$$ 2024-03-10 05:41:04.544 UTC [49316][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=ssltestuser database=trustdb host=localhost port=51094 2024-03-10 05:41:04.570 UTC [49321][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51104 2024-03-10 05:41:04.574 UTC [49321][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-03-10 05:41:04.582 UTC [49323][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51118 2024-03-10 05:41:04.587 UTC [49323][client backend] [[unknown]][4/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-10 05:41:04.587 UTC [49323][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:04.592 UTC [49323][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_815s/client.key$$ 2024-03-10 05:41:04.592 UTC [49323][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=51118 2024-03-10 05:41:04.600 UTC [49325][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51132 2024-03-10 05:41:04.605 UTC [49325][client backend] [[unknown]][5/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-10 05:41:04.605 UTC [49325][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:04.606 UTC [49325][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_815s/client-der.key$$ 2024-03-10 05:41:04.606 UTC [49325][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=51132 2024-03-10 05:41:04.614 UTC [49327][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51142 2024-03-10 05:41:04.619 UTC [49327][client backend] [[unknown]][6/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-10 05:41:04.619 UTC [49327][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:04.620 UTC [49327][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_815s/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-03-10 05:41:04.620 UTC [49327][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=certdb host=localhost port=51142 2024-03-10 05:41:04.628 UTC [49329][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51148 2024-03-10 05:41:04.633 UTC [49329][client backend] [[unknown]][7/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-10 05:41:04.633 UTC [49329][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:04.634 UTC [49329][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_815s/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-03-10 05:41:04.634 UTC [49329][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=51148 2024-03-10 05:41:04.641 UTC [49331][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51152 2024-03-10 05:41:04.647 UTC [49331][client backend] [[unknown]][8/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-10 05:41:04.647 UTC [49331][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:04.647 UTC [49331][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_815s/client.key$$ 2024-03-10 05:41:04.648 UTC [49331][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=51152 2024-03-10 05:41:04.655 UTC [49333][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51154 2024-03-10 05:41:04.660 UTC [49333][client backend] [[unknown]][9/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-10 05:41:04.660 UTC [49333][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:04.661 UTC [49333][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_815s/client.key$$ 2024-03-10 05:41:04.661 UTC [49333][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=ssltestuser database=certdb host=localhost port=51154 2024-03-10 05:41:04.670 UTC [49335][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51156 2024-03-10 05:41:04.674 UTC [49335][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-03-10 05:41:04.682 UTC [49337][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51166 2024-03-10 05:41:04.683 UTC [49337][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-10 05:41:04.691 UTC [49339][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51180 2024-03-10 05:41:04.696 UTC [49339][client backend] [[unknown]][11/1:0] LOG: connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:8) 2024-03-10 05:41:04.696 UTC [49339][client backend] [[unknown]][11/1:0] LOG: connection authorized: user=ssltestuser database=certdb_dn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:04.701 UTC [49339][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_815s/client-dn.key$$ 2024-03-10 05:41:04.701 UTC [49339][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_dn host=localhost port=51180 2024-03-10 05:41:04.709 UTC [49341][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51186 2024-03-10 05:41:04.714 UTC [49341][client backend] [[unknown]][12/1:0] LOG: connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:9) 2024-03-10 05:41:04.714 UTC [49341][client backend] [[unknown]][12/1:0] LOG: connection authorized: user=ssltestuser database=certdb_dn_re application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:04.719 UTC [49341][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_815s/client-dn.key$$ 2024-03-10 05:41:04.719 UTC [49341][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_dn_re host=localhost port=51186 2024-03-10 05:41:04.727 UTC [49343][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51190 2024-03-10 05:41:04.732 UTC [49343][client backend] [[unknown]][13/1:0] LOG: connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:10) 2024-03-10 05:41:04.732 UTC [49343][client backend] [[unknown]][13/1:0] LOG: connection authorized: user=ssltestuser database=certdb_cn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:04.737 UTC [49343][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_815s/client-dn.key$$ 2024-03-10 05:41:04.737 UTC [49343][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_cn host=localhost port=51190 2024-03-10 05:41:04.757 UTC [49346][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51200 2024-03-10 05:41:04.762 UTC [49346][client backend] [[unknown]][14/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-10 05:41:04.762 UTC [49346][client backend] [[unknown]][14/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:04.763 UTC [49346][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-10 05:41:04.764 UTC [49346][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=certdb host=localhost port=51200 2024-03-10 05:41:04.773 UTC [49348][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51216 2024-03-10 05:41:04.774 UTC [49348][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-10 05:41:04.781 UTC [49350][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51230 2024-03-10 05:41:04.786 UTC [49350][client backend] [[unknown]][15/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-10 05:41:04.786 UTC [49350][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-10 05:41:04.786 UTC [49350][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-03-10 05:41:04.786 UTC [49350][client backend] [[unknown]][15/1:0] DETAIL: Connection matched file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf" line 7: "hostssl certdb all 127.0.0.1/32 cert" 2024-03-10 05:41:04.794 UTC [49352][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51246 2024-03-10 05:41:04.799 UTC [49352][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-10 05:41:04.799 UTC [49352][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-10 05:41:04.806 UTC [49354][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51256 2024-03-10 05:41:04.812 UTC [49354][client backend] [[unknown]][16/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:4) 2024-03-10 05:41:04.812 UTC [49354][client backend] [[unknown]][16/1:0] LOG: connection authorized: user=ssltestuser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:04.816 UTC [49354][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_815s/client.key$$ 2024-03-10 05:41:04.817 UTC [49354][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=verifydb host=localhost port=51256 2024-03-10 05:41:04.825 UTC [49356][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51262 2024-03-10 05:41:04.830 UTC [49356][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-10 05:41:04.830 UTC [49356][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-03-10 05:41:04.830 UTC [49356][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-03-10 05:41:04.830 UTC [49356][client backend] [[unknown]][17/1:0] DETAIL: Connection matched file "/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf" line 5: "hostssl verifydb anotheruser 127.0.0.1/32 trust clientcert=verify-full" 2024-03-10 05:41:04.838 UTC [49358][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51270 2024-03-10 05:41:04.843 UTC [49358][client backend] [[unknown]][18/1:0] LOG: connection authenticated: user="yetanotheruser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:6) 2024-03-10 05:41:04.843 UTC [49358][client backend] [[unknown]][18/1:0] LOG: connection authorized: user=yetanotheruser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:04.844 UTC [49358][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_815s/client.key$$ 2024-03-10 05:41:04.844 UTC [49358][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.006 user=yetanotheruser database=verifydb host=localhost port=51270 2024-03-10 05:41:04.848 UTC [49300][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:04.848 UTC [49300][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:04.849 UTC [49300][postmaster] LOG: background worker "logical replication launcher" (PID 49306) exited with exit code 1 2024-03-10 05:41:04.849 UTC [49301][checkpointer] LOG: shutting down 2024-03-10 05:41:04.849 UTC [49301][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:04.850 UTC [49301][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/4196970, redo lsn=0/4196970 2024-03-10 05:41:04.853 UTC [49300][postmaster] LOG: database system is shut down 2024-03-10 05:41:04.966 UTC [49360][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:04.966 UTC [49360][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:04.966 UTC [49360][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:04.968 UTC [49363][startup] LOG: database system was shut down at 2024-03-10 05:41:04 UTC 2024-03-10 05:41:04.972 UTC [49360][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:05.056 UTC [49368][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51276 2024-03-10 05:41:05.062 UTC [49368][client backend] [[unknown]][0/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-10 05:41:05.062 UTC [49368][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:05.067 UTC [49368][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_815s/client.key sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 host=localhost sslmode=require sslcert=ssl/client+client_ca.crt$$ 2024-03-10 05:41:05.067 UTC [49368][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=51276 2024-03-10 05:41:05.075 UTC [49370][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51282 2024-03-10 05:41:05.080 UTC [49370][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-10 05:41:05.080 UTC [49370][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: unable to get local issuer certificate. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921344, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-10 05:41:05.087 UTC [49372][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51284 2024-03-10 05:41:05.092 UTC [49372][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-10 05:41:05.092 UTC [49372][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: unable to get local issuer certificate. Failed certificate data (unverified): subject ".../CN=ssl-123456789012345678901234567890123456789012345678901234567890", serial number 2315702411956921348, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-10 05:41:05.095 UTC [49360][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:05.095 UTC [49360][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:05.097 UTC [49360][postmaster] LOG: background worker "logical replication launcher" (PID 49366) exited with exit code 1 2024-03-10 05:41:05.097 UTC [49361][checkpointer] LOG: shutting down 2024-03-10 05:41:05.097 UTC [49361][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:05.097 UTC [49361][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/41969E8, redo lsn=0/41969E8 2024-03-10 05:41:05.100 UTC [49360][postmaster] LOG: database system is shut down 2024-03-10 05:41:05.213 UTC [49374][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:05.213 UTC [49374][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:05.213 UTC [49374][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:05.215 UTC [49377][startup] LOG: database system was shut down at 2024-03-10 05:41:05 UTC 2024-03-10 05:41:05.219 UTC [49374][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:05.304 UTC [49382][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51294 2024-03-10 05:41:05.309 UTC [49382][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-10 05:41:05.309 UTC [49382][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 1: unable to get local issuer certificate. Failed certificate data (unverified): subject "/CN=Test CA for PostgreSQL SSL regression test client certs", serial number 2315702411956921089, issuer "/CN=Test root CA for PostgreSQL SSL regression test suite". 2024-03-10 05:41:05.313 UTC [49374][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:05.313 UTC [49374][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:05.314 UTC [49374][postmaster] LOG: background worker "logical replication launcher" (PID 49380) exited with exit code 1 2024-03-10 05:41:05.314 UTC [49375][checkpointer] LOG: shutting down 2024-03-10 05:41:05.314 UTC [49375][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:05.315 UTC [49375][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/4196A60, redo lsn=0/4196A60 2024-03-10 05:41:05.317 UTC [49374][postmaster] LOG: database system is shut down 2024-03-10 05:41:05.430 UTC [49384][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:05.430 UTC [49384][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:05.430 UTC [49384][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:05.433 UTC [49387][startup] LOG: database system was shut down at 2024-03-10 05:41:05 UTC 2024-03-10 05:41:05.436 UTC [49384][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:05.521 UTC [49392][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51308 2024-03-10 05:41:05.526 UTC [49392][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-10 05:41:05.526 UTC [49392][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-10 05:41:05.534 UTC [49394][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51314 2024-03-10 05:41:05.539 UTC [49394][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-10 05:41:05.539 UTC [49394][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=\xce\x9f\xce\xb4\xcf\x85\xcf\x83\xcf\x83\xce\xad\xce\xb1\xcf\x82", serial number 2315702411956921349, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-10 05:41:05.542 UTC [49384][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:05.542 UTC [49384][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:05.544 UTC [49384][postmaster] LOG: background worker "logical replication launcher" (PID 49390) exited with exit code 1 2024-03-10 05:41:05.544 UTC [49385][checkpointer] LOG: shutting down 2024-03-10 05:41:05.544 UTC [49385][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:05.544 UTC [49385][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/4196AD8, redo lsn=0/4196AD8 2024-03-10 05:41:05.547 UTC [49384][postmaster] LOG: database system is shut down 2024-03-10 05:41:05.660 UTC [49396][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:05.660 UTC [49396][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:05.660 UTC [49396][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:05.662 UTC [49399][startup] LOG: database system was shut down at 2024-03-10 05:41:05 UTC 2024-03-10 05:41:05.665 UTC [49396][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:05.751 UTC [49404][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51330 2024-03-10 05:41:05.752 UTC [49404][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:41:05.752 UTC [49404][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:41:05.761 UTC [49406][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51346 2024-03-10 05:41:05.765 UTC [49406][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:05.765 UTC [49406][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:05.770 UTC [49406][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-10 05:41:05.770 UTC [49406][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=51346 2024-03-10 05:41:05.774 UTC [49396][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:05.774 UTC [49396][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:05.775 UTC [49396][postmaster] LOG: background worker "logical replication launcher" (PID 49402) exited with exit code 1 2024-03-10 05:41:05.776 UTC [49397][checkpointer] LOG: shutting down 2024-03-10 05:41:05.776 UTC [49397][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:05.776 UTC [49397][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/4196B50, redo lsn=0/4196B50 2024-03-10 05:41:05.778 UTC [49396][postmaster] LOG: database system is shut down 2024-03-10 05:41:05.892 UTC [49408][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:05.892 UTC [49408][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:05.892 UTC [49408][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:05.894 UTC [49411][startup] LOG: database system was shut down at 2024-03-10 05:41:05 UTC 2024-03-10 05:41:05.897 UTC [49408][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:05.983 UTC [49416][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51360 2024-03-10 05:41:05.984 UTC [49416][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:41:05.984 UTC [49416][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:41:05.993 UTC [49418][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51370 2024-03-10 05:41:05.996 UTC [49418][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:05.996 UTC [49418][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:06.001 UTC [49418][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-10 05:41:06.002 UTC [49418][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=51370 2024-03-10 05:41:06.005 UTC [49408][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:06.005 UTC [49408][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:06.007 UTC [49408][postmaster] LOG: background worker "logical replication launcher" (PID 49414) exited with exit code 1 2024-03-10 05:41:06.007 UTC [49409][checkpointer] LOG: shutting down 2024-03-10 05:41:06.007 UTC [49409][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:06.007 UTC [49409][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/4196BC8, redo lsn=0/4196BC8 2024-03-10 05:41:06.010 UTC [49408][postmaster] LOG: database system is shut down 2024-03-10 05:41:06.123 UTC [49420][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:06.123 UTC [49420][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:06.123 UTC [49420][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:06.125 UTC [49423][startup] LOG: database system was shut down at 2024-03-10 05:41:06 UTC 2024-03-10 05:41:06.128 UTC [49420][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:06.214 UTC [49428][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51380 2024-03-10 05:41:06.215 UTC [49428][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:41:06.215 UTC [49428][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:41:06.224 UTC [49430][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51394 2024-03-10 05:41:06.228 UTC [49430][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:06.228 UTC [49430][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:06.233 UTC [49430][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-10 05:41:06.233 UTC [49430][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=51394 2024-03-10 05:41:06.237 UTC [49420][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:06.237 UTC [49420][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:06.238 UTC [49420][postmaster] LOG: background worker "logical replication launcher" (PID 49426) exited with exit code 1 2024-03-10 05:41:06.238 UTC [49421][checkpointer] LOG: shutting down 2024-03-10 05:41:06.238 UTC [49421][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:06.239 UTC [49421][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/4196C40, redo lsn=0/4196C40 2024-03-10 05:41:06.241 UTC [49420][postmaster] LOG: database system is shut down 2024-03-10 05:41:06.355 UTC [49432][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:06.355 UTC [49432][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:06.355 UTC [49432][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:06.357 UTC [49435][startup] LOG: database system was shut down at 2024-03-10 05:41:06 UTC 2024-03-10 05:41:06.359 UTC [49432][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:06.445 UTC [49440][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51408 2024-03-10 05:41:06.447 UTC [49440][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:41:06.447 UTC [49440][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:41:06.456 UTC [49442][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51414 2024-03-10 05:41:06.459 UTC [49442][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:06.459 UTC [49442][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:06.464 UTC [49442][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-10 05:41:06.465 UTC [49442][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=51414 2024-03-10 05:41:06.468 UTC [49432][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:06.468 UTC [49432][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:06.469 UTC [49432][postmaster] LOG: background worker "logical replication launcher" (PID 49438) exited with exit code 1 2024-03-10 05:41:06.470 UTC [49433][checkpointer] LOG: shutting down 2024-03-10 05:41:06.470 UTC [49433][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:06.470 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/4196CB8, redo lsn=0/4196CB8 2024-03-10 05:41:06.473 UTC [49432][postmaster] LOG: database system is shut down 2024-03-10 05:41:06.587 UTC [49444][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:06.587 UTC [49444][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:06.587 UTC [49444][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:06.589 UTC [49447][startup] LOG: database system was shut down at 2024-03-10 05:41:06 UTC 2024-03-10 05:41:06.592 UTC [49444][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:06.677 UTC [49452][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51430 2024-03-10 05:41:06.678 UTC [49452][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:41:06.678 UTC [49452][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:41:06.688 UTC [49454][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51436 2024-03-10 05:41:06.692 UTC [49454][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:06.692 UTC [49454][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:06.697 UTC [49454][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-10 05:41:06.697 UTC [49454][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=51436 2024-03-10 05:41:06.701 UTC [49444][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:06.701 UTC [49444][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:06.703 UTC [49444][postmaster] LOG: background worker "logical replication launcher" (PID 49450) exited with exit code 1 2024-03-10 05:41:06.703 UTC [49445][checkpointer] LOG: shutting down 2024-03-10 05:41:06.703 UTC [49445][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:06.703 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/4196D30, redo lsn=0/4196D30 2024-03-10 05:41:06.706 UTC [49444][postmaster] LOG: database system is shut down 2024-03-10 05:41:06.818 UTC [49456][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:06.818 UTC [49456][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:06.818 UTC [49456][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:06.820 UTC [49459][startup] LOG: database system was shut down at 2024-03-10 05:41:06 UTC 2024-03-10 05:41:06.824 UTC [49456][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:06.910 UTC [49464][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51438 2024-03-10 05:41:06.911 UTC [49464][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:41:06.911 UTC [49464][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:41:06.920 UTC [49466][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51444 2024-03-10 05:41:06.924 UTC [49466][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:06.924 UTC [49466][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:06.928 UTC [49466][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-10 05:41:06.929 UTC [49466][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=51444 2024-03-10 05:41:06.932 UTC [49456][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:06.932 UTC [49456][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:06.934 UTC [49456][postmaster] LOG: background worker "logical replication launcher" (PID 49462) exited with exit code 1 2024-03-10 05:41:06.934 UTC [49457][checkpointer] LOG: shutting down 2024-03-10 05:41:06.934 UTC [49457][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:06.934 UTC [49457][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/4196DA8, redo lsn=0/4196DA8 2024-03-10 05:41:06.937 UTC [49456][postmaster] LOG: database system is shut down 2024-03-10 05:41:07.051 UTC [49468][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:07.051 UTC [49468][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:07.051 UTC [49468][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:07.053 UTC [49471][startup] LOG: database system was shut down at 2024-03-10 05:41:06 UTC 2024-03-10 05:41:07.056 UTC [49468][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:07.141 UTC [49476][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51458 2024-03-10 05:41:07.142 UTC [49476][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:41:07.142 UTC [49476][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:41:07.150 UTC [49478][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51468 2024-03-10 05:41:07.154 UTC [49478][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:07.154 UTC [49478][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:07.159 UTC [49478][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-10 05:41:07.159 UTC [49478][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=51468 2024-03-10 05:41:07.163 UTC [49468][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:07.163 UTC [49468][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:07.164 UTC [49468][postmaster] LOG: background worker "logical replication launcher" (PID 49474) exited with exit code 1 2024-03-10 05:41:07.164 UTC [49469][checkpointer] LOG: shutting down 2024-03-10 05:41:07.164 UTC [49469][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:07.164 UTC [49469][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/4196E20, redo lsn=0/4196E20 2024-03-10 05:41:07.167 UTC [49468][postmaster] LOG: database system is shut down 2024-03-10 05:41:07.280 UTC [49480][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:07.280 UTC [49480][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:07.280 UTC [49480][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:07.283 UTC [49483][startup] LOG: database system was shut down at 2024-03-10 05:41:07 UTC 2024-03-10 05:41:07.286 UTC [49480][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:07.371 UTC [49488][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51476 2024-03-10 05:41:07.373 UTC [49488][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:41:07.373 UTC [49488][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:41:07.382 UTC [49490][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51480 2024-03-10 05:41:07.386 UTC [49490][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:07.386 UTC [49490][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:07.391 UTC [49490][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-10 05:41:07.392 UTC [49490][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=51480 2024-03-10 05:41:07.396 UTC [49480][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:07.396 UTC [49480][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:07.398 UTC [49480][postmaster] LOG: background worker "logical replication launcher" (PID 49486) exited with exit code 1 2024-03-10 05:41:07.398 UTC [49481][checkpointer] LOG: shutting down 2024-03-10 05:41:07.398 UTC [49481][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:07.398 UTC [49481][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/4196E98, redo lsn=0/4196E98 2024-03-10 05:41:07.401 UTC [49480][postmaster] LOG: database system is shut down 2024-03-10 05:41:07.513 UTC [49492][postmaster] LOG: starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit 2024-03-10 05:41:07.513 UTC [49492][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 57112 2024-03-10 05:41:07.513 UTC [49492][postmaster] LOG: listening on Unix socket "/tmp/XsQjfteO4d/.s.PGSQL.57112" 2024-03-10 05:41:07.515 UTC [49495][startup] LOG: database system was shut down at 2024-03-10 05:41:07 UTC 2024-03-10 05:41:07.518 UTC [49492][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:41:07.605 UTC [49500][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51492 2024-03-10 05:41:07.606 UTC [49500][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:41:07.606 UTC [49500][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:41:07.616 UTC [49502][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=51506 2024-03-10 05:41:07.620 UTC [49502][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-10 05:41:07.620 UTC [49502][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:41:07.625 UTC [49502][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-10 05:41:07.625 UTC [49502][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=51506 2024-03-10 05:41:07.629 UTC [49492][postmaster] LOG: received fast shutdown request 2024-03-10 05:41:07.629 UTC [49492][postmaster] LOG: aborting any active transactions 2024-03-10 05:41:07.631 UTC [49492][postmaster] LOG: background worker "logical replication launcher" (PID 49498) exited with exit code 1 2024-03-10 05:41:07.631 UTC [49493][checkpointer] LOG: shutting down 2024-03-10 05:41:07.631 UTC [49493][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:41:07.631 UTC [49493][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/4196F10, redo lsn=0/4196F10 2024-03-10 05:41:07.634 UTC [49492][postmaster] LOG: database system is shut down 2024-03-10 05:41:07.743 UTC [49504][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-03-10 05:41:07.743 UTC [49504][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [05:41:07.831](0.204s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"