[13:50:16.215](0.021s) # setting up data directory # Checking port 54525 # Found port 54525 Name: primary Data directory: /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata Backup directory: /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/backup Archive directory: /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/archives Connection string: port=54525 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34 Log file: /Users/admin/pgsql/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [13:50:16.220](0.005s) # initializing database system by copying initdb template # Running: cp -RPp /Users/admin/pgsql/build/tmp_install/initdb-template /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata # Running: /Users/admin/pgsql/build/src/test/regress/pg_regress --config-auth /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata ### Starting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 38276 [13:50:16.609](0.389s) ok 1 - ssl_library parameter ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 38742 [13:50:19.565](2.956s) # testing password-protected keys ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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-22 13:50:16.504 UTC [38276][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:16.505 UTC [38276][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:16.511 UTC [38294][startup] LOG: database system was shut down at 2024-03-22 13:47:11 UTC 2024-03-22 13:50:16.519 UTC [38276][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:16.604 UTC [38341][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:16.604 UTC [38341][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:16.604 UTC [38341][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:16.608 UTC [38341][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-22 13:50:16.608 UTC [38341][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=admin database=postgres host=[local] 2024-03-22 13:50:16.619 UTC [38347][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:16.619 UTC [38347][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:16.619 UTC [38347][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:16.620 UTC [38347][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-22 13:50:16.620 UTC [38347][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-22 13:50:16.674 UTC [38356][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:16.674 UTC [38356][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:16.674 UTC [38356][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:16.676 UTC [38356][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-22 13:50:16.676 UTC [38356][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=admin database=postgres host=[local] 2024-03-22 13:50:16.723 UTC [38373][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:16.723 UTC [38373][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:16.724 UTC [38373][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:16.724 UTC [38373][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-22 13:50:16.725 UTC [38373][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-22 13:50:16.777 UTC [38388][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:16.777 UTC [38388][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:16.777 UTC [38388][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:16.778 UTC [38388][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-22 13:50:16.778 UTC [38388][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-22 13:50:16.823 UTC [38398][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:16.823 UTC [38398][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:16.823 UTC [38398][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:16.824 UTC [38398][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-22 13:50:16.876 UTC [38398][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.053 user=admin database=postgres host=[local] 2024-03-22 13:50:16.964 UTC [38442][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:16.964 UTC [38442][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:16.964 UTC [38442][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:16.965 UTC [38442][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-22 13:50:17.088 UTC [38442][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.124 user=admin database=postgres host=[local] 2024-03-22 13:50:17.101 UTC [38461][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:17.101 UTC [38461][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:17.101 UTC [38461][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:17.102 UTC [38461][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-22 13:50:17.282 UTC [38461][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.181 user=admin database=postgres host=[local] 2024-03-22 13:50:17.407 UTC [38494][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:17.408 UTC [38494][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:17.408 UTC [38494][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:17.408 UTC [38494][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-22 13:50:17.804 UTC [38494][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.396 user=admin database=postgres host=[local] 2024-03-22 13:50:18.042 UTC [38571][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:18.042 UTC [38571][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:18.042 UTC [38571][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:18.043 UTC [38571][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-22 13:50:18.086 UTC [38571][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.044 user=admin database=postgres host=[local] 2024-03-22 13:50:18.180 UTC [38586][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:18.180 UTC [38586][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:18.180 UTC [38586][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:18.181 UTC [38586][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-22 13:50:18.406 UTC [38586][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.226 user=admin database=postgres host=[local] 2024-03-22 13:50:18.697 UTC [38276][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:18.697 UTC [38276][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:18.697 UTC [38276][postmaster] LOG: background worker "logical replication launcher" (PID 38304) exited with exit code 1 2024-03-22 13:50:18.698 UTC [38288][checkpointer] LOG: shutting down 2024-03-22 13:50:18.698 UTC [38288][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:19.377 UTC [38288][checkpointer] LOG: checkpoint complete: wrote 5572 buffers (34.0%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.679 s, sync=0.001 s, total=0.680 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44852 kB, estimate=44852 kB; lsn=0/40D6980, redo lsn=0/40D6980 2024-03-22 13:50:19.387 UTC [38276][postmaster] LOG: database system is shut down 2024-03-22 13:50:19.523 UTC [38742][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:19.523 UTC [38742][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:19.524 UTC [38742][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:19.527 UTC [38753][startup] LOG: database system was shut down at 2024-03-22 13:50:19 UTC 2024-03-22 13:50:19.529 UTC [38742][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:19.569 UTC [38742][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:19.569 UTC [38742][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:19.570 UTC [38742][postmaster] LOG: background worker "logical replication launcher" (PID 38756) exited with exit code 1 2024-03-22 13:50:19.570 UTC [38751][checkpointer] LOG: shutting down 2024-03-22 13:50:19.570 UTC [38751][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:19.575 UTC [38751][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.005 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D69F8, redo lsn=0/40D69F8 2024-03-22 13:50:19.576 UTC [38742][postmaster] LOG: database system is shut down 2024-03-22 13:50:19.693 UTC [38768][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-22 13:50:19.693 UTC [38768][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [13:50:19.782](0.217s) ok 2 - restart fails with password-protected key file with wrong password ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart pg_ctl: PID file "/Users/admin/pgsql/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 38772 [13:50:19.891](0.109s) ok 3 - restart succeeds with password-protected key file ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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-22 13:50:16.504 UTC [38276][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:16.505 UTC [38276][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:16.511 UTC [38294][startup] LOG: database system was shut down at 2024-03-22 13:47:11 UTC 2024-03-22 13:50:16.519 UTC [38276][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:16.604 UTC [38341][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:16.604 UTC [38341][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:16.604 UTC [38341][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:16.608 UTC [38341][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-22 13:50:16.608 UTC [38341][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=admin database=postgres host=[local] 2024-03-22 13:50:16.619 UTC [38347][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:16.619 UTC [38347][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:16.619 UTC [38347][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:16.620 UTC [38347][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-22 13:50:16.620 UTC [38347][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-22 13:50:16.674 UTC [38356][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:16.674 UTC [38356][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:16.674 UTC [38356][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:16.676 UTC [38356][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-22 13:50:16.676 UTC [38356][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=admin database=postgres host=[local] 2024-03-22 13:50:16.723 UTC [38373][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:16.723 UTC [38373][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:16.724 UTC [38373][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:16.724 UTC [38373][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-22 13:50:16.725 UTC [38373][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-22 13:50:16.777 UTC [38388][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:16.777 UTC [38388][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:16.777 UTC [38388][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:16.778 UTC [38388][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-22 13:50:16.778 UTC [38388][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-22 13:50:16.823 UTC [38398][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:16.823 UTC [38398][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:16.823 UTC [38398][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:16.824 UTC [38398][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-22 13:50:16.876 UTC [38398][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.053 user=admin database=postgres host=[local] 2024-03-22 13:50:16.964 UTC [38442][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:16.964 UTC [38442][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:16.964 UTC [38442][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:16.965 UTC [38442][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-22 13:50:17.088 UTC [38442][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.124 user=admin database=postgres host=[local] 2024-03-22 13:50:17.101 UTC [38461][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:17.101 UTC [38461][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:17.101 UTC [38461][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:17.102 UTC [38461][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-22 13:50:17.282 UTC [38461][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.181 user=admin database=postgres host=[local] 2024-03-22 13:50:17.407 UTC [38494][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:17.408 UTC [38494][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:17.408 UTC [38494][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:17.408 UTC [38494][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-22 13:50:17.804 UTC [38494][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.396 user=admin database=postgres host=[local] 2024-03-22 13:50:18.042 UTC [38571][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:18.042 UTC [38571][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:18.042 UTC [38571][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:18.043 UTC [38571][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-22 13:50:18.086 UTC [38571][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.044 user=admin database=postgres host=[local] 2024-03-22 13:50:18.180 UTC [38586][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:18.180 UTC [38586][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:18.180 UTC [38586][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:18.181 UTC [38586][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-22 13:50:18.406 UTC [38586][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.226 user=admin database=postgres host=[local] 2024-03-22 13:50:18.697 UTC [38276][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:18.697 UTC [38276][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:18.697 UTC [38276][postmaster] LOG: background worker "logical replication launcher" (PID 38304) exited with exit code 1 2024-03-22 13:50:18.698 UTC [38288][checkpointer] LOG: shutting down 2024-03-22 13:50:18.698 UTC [38288][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:19.377 UTC [38288][checkpointer] LOG: checkpoint complete: wrote 5572 buffers (34.0%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.679 s, sync=0.001 s, total=0.680 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44852 kB, estimate=44852 kB; lsn=0/40D6980, redo lsn=0/40D6980 2024-03-22 13:50:19.387 UTC [38276][postmaster] LOG: database system is shut down 2024-03-22 13:50:19.523 UTC [38742][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:19.523 UTC [38742][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:19.524 UTC [38742][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:19.527 UTC [38753][startup] LOG: database system was shut down at 2024-03-22 13:50:19 UTC 2024-03-22 13:50:19.529 UTC [38742][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:19.569 UTC [38742][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:19.569 UTC [38742][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:19.570 UTC [38742][postmaster] LOG: background worker "logical replication launcher" (PID 38756) exited with exit code 1 2024-03-22 13:50:19.570 UTC [38751][checkpointer] LOG: shutting down 2024-03-22 13:50:19.570 UTC [38751][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:19.575 UTC [38751][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.005 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D69F8, redo lsn=0/40D69F8 2024-03-22 13:50:19.576 UTC [38742][postmaster] LOG: database system is shut down 2024-03-22 13:50:19.693 UTC [38768][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-22 13:50:19.693 UTC [38768][postmaster] LOG: database system is shut down 2024-03-22 13:50:19.813 UTC [38772][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:19.813 UTC [38772][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:19.813 UTC [38772][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:19.816 UTC [38782][startup] LOG: database system was shut down at 2024-03-22 13:50:19 UTC 2024-03-22 13:50:19.817 UTC [38772][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:19.895 UTC [38772][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:19.895 UTC [38772][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:19.896 UTC [38772][postmaster] LOG: background worker "logical replication launcher" (PID 38785) exited with exit code 1 2024-03-22 13:50:19.896 UTC [38780][checkpointer] LOG: shutting down 2024-03-22 13:50:19.896 UTC [38780][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:19.901 UTC [38780][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.006 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D6A70, redo lsn=0/40D6A70 2024-03-22 13:50:19.903 UTC [38772][postmaster] LOG: database system is shut down 2024-03-22 13:50:20.017 UTC [38794][postmaster] FATAL: could not set SSL protocol version range 2024-03-22 13:50:20.017 UTC [38794][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-22 13:50:20.030 UTC [38794][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [13:50:20.107](0.216s) ok 4 - restart fails with incorrect SSL protocol bounds ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart pg_ctl: PID file "/Users/admin/pgsql/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 38805 [13:50:20.440](0.333s) ok 5 - restart succeeds with correct SSL protocol bounds [13:50:20.440](0.000s) # running client tests ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 38862 [13:50:20.675](0.236s) ok 6 - server doesn't accept non-SSL connections [13:50:20.676](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [13:50:20.701](0.026s) ok 8 - connect without server root cert sslmode=require [13:50:20.702](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [13:50:20.719](0.017s) ok 10 - connect without server root cert sslmode=verify-ca [13:50:20.719](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [13:50:20.736](0.017s) ok 12 - connect without server root cert sslmode=verify-full [13:50:20.736](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [13:50:20.756](0.020s) ok 14 - connect with wrong server root cert sslmode=require [13:50:20.756](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [13:50:20.775](0.019s) ok 16 - connect with wrong server root cert sslmode=verify-ca [13:50:20.775](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [13:50:20.794](0.019s) ok 18 - connect with wrong server root cert sslmode=verify-full [13:50:20.794](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [13:50:20.813](0.019s) ok 20 - connect with server CA cert, without root CA [13:50:20.813](0.000s) ok 21 - connect with server CA cert, without root CA: matches [13:50:20.835](0.022s) ok 22 - connect with correct server CA cert file sslmode=require [13:50:20.835](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [13:50:20.855](0.020s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [13:50:20.855](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [13:50:20.875](0.020s) ok 26 - connect with correct server CA cert file sslmode=verify-full [13:50:20.875](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [13:50:20.895](0.020s) ok 28 - cert root file that contains two certificates, order 1 [13:50:20.895](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [13:50:20.916](0.021s) ok 30 - cert root file that contains two certificates, order 2 [13:50:20.916](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [13:50:20.939](0.023s) ok 32 - connect with sslcertmode=disable [13:50:20.939](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [13:50:20.962](0.023s) ok 34 - connect with sslcertmode=allow [13:50:20.962](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [13:50:20.982](0.019s) ok 36 - connect with sslcertmode=require fails without a client certificate [13:50:20.982](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [13:50:21.001](0.019s) ok 38 - sslcrl option with invalid file name [13:50:21.001](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [13:50:21.020](0.019s) ok 40 - CRL belonging to a different CA [13:50:21.020](0.000s) ok 41 - CRL belonging to a different CA: matches [13:50:21.038](0.018s) ok 42 - directory CRL belonging to a different CA [13:50:21.039](0.000s) ok 43 - directory CRL belonging to a different CA: matches [13:50:21.058](0.020s) ok 44 - CRL with a non-revoked cert [13:50:21.059](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [13:50:21.078](0.020s) ok 46 - directory CRL with a non-revoked cert [13:50:21.078](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [13:50:21.099](0.021s) ok 48 - mismatch between host name and server certificate sslmode=require [13:50:21.099](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [13:50:21.119](0.020s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [13:50:21.119](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [13:50:21.138](0.019s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [13:50:21.139](0.000s) ok 53 - mismatch between host name and server certificate sslmode=verify-full: matches ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39014 [13:50:21.804](0.665s) ok 54 - IP address in the Common Name [13:50:21.804](0.000s) ok 55 - IP address in the Common Name: no stderr [13:50:21.826](0.022s) ok 56 - mismatch between host name and server certificate IP address [13:50:21.826](0.000s) ok 57 - mismatch between host name and server certificate IP address: matches ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39047 [13:50:22.076](0.250s) ok 58 - IP address in a dNSName [13:50:22.076](0.000s) ok 59 - IP address in a dNSName: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39067 [13:50:22.322](0.246s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [13:50:22.322](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [13:50:22.342](0.020s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [13:50:22.342](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [13:50:22.362](0.020s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [13:50:22.363](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [13:50:22.382](0.019s) ok 66 - host name not matching with X.509 Subject Alternative Names [13:50:22.382](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [13:50:22.406](0.024s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [13:50:22.406](0.000s) ok 69 - host name not matching with X.509 Subject Alternative Names wildcard: matches ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39085 [13:50:22.644](0.237s) ok 70 - host name matching with a single X.509 Subject Alternative Name [13:50:22.644](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [13:50:22.662](0.018s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [13:50:22.662](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [13:50:22.680](0.018s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [13:50:22.681](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 /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39100 [13:50:22.935](0.254s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [13:50:22.935](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [13:50:22.956](0.021s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [13:50:22.956](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [13:50:22.974](0.018s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [13:50:22.974](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [13:50:22.994](0.020s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [13:50:22.995](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [13:50:23.013](0.019s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [13:50:23.013](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [13:50:23.032](0.019s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [13:50:23.032](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [13:50:23.050](0.018s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [13:50:23.050](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [13:50:23.070](0.020s) ok 90 - IPv6 host with CIDR mask does not match [13:50:23.070](0.000s) ok 91 - IPv6 host with CIDR mask does not match: matches ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39124 [13:50:23.315](0.245s) ok 92 - certificate with both a CN and SANs 1 [13:50:23.315](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [13:50:23.334](0.019s) ok 94 - certificate with both a CN and SANs 2 [13:50:23.335](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [13:50:23.355](0.021s) ok 96 - certificate with both a CN and SANs ignores CN [13:50:23.355](0.000s) ok 97 - certificate with both a CN and SANs ignores CN: matches ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39139 [13:50:23.604](0.248s) ok 98 - certificate with both a CN and IP SANs matches CN [13:50:23.604](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [13:50:23.624](0.020s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [13:50:23.624](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [13:50:23.644](0.020s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [13:50:23.644](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 /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39153 [13:50:23.910](0.265s) ok 104 - certificate with both an IP CN and IP SANs 1 [13:50:23.910](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [13:50:23.932](0.022s) ok 106 - certificate with both an IP CN and IP SANs 2 [13:50:23.932](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [13:50:23.951](0.019s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [13:50:23.951](0.000s) ok 109 - certificate with both an IP CN and IP SANs ignores CN: matches ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39167 [13:50:24.203](0.252s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [13:50:24.203](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [13:50:24.222](0.019s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [13:50:24.222](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [13:50:24.241](0.018s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [13:50:24.241](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 /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39181 [13:50:24.491](0.250s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [13:50:24.491](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [13:50:24.509](0.018s) ok 118 - server certificate without CN or SANs sslmode=verify-full [13:50:24.509](0.000s) ok 119 - server certificate without CN or SANs sslmode=verify-full: matches ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39193 [13:50:24.751](0.242s) ok 120 - sslrootcert=system does not connect with private CA [13:50:24.751](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [13:50:24.760](0.009s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [13:50:24.760](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [13:50:24.782](0.022s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [13:50:24.782](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [13:50:24.800](0.018s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [13:50:24.800](0.000s) ok 127 - sslrootcert=system defaults to sslmode=verify-full: matches ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39208 [13:50:25.051](0.251s) ok 128 - connects without client-side CRL [13:50:25.051](0.000s) ok 129 - connects without client-side CRL: no stderr [13:50:25.069](0.017s) ok 130 - does not connect with client-side CRL file [13:50:25.069](0.000s) ok 131 - does not connect with client-side CRL file: matches [13:50:25.085](0.016s) ok 132 - does not connect with client-side CRL directory [13:50:25.085](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() [13:50:25.102](0.017s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [13:50:25.102](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [13:50:25.102](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [13:50:25.120](0.018s) ok 137 - connection success with correct range of TLS protocol versions [13:50:25.120](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [13:50:25.128](0.008s) ok 139 - connection failure with incorrect range of TLS protocol versions [13:50:25.128](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [13:50:25.136](0.007s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [13:50:25.136](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [13:50:25.145](0.009s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [13:50:25.145](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [13:50:25.145](0.000s) # running server tests [13:50:25.161](0.017s) ok 145 - certificate authorization fails without client cert [13:50:25.161](0.000s) ok 146 - certificate authorization fails without client cert: matches [13:50:25.182](0.021s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [13:50:25.182](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [13:50:25.201](0.019s) ok 149 - certificate authorization succeeds with correct client cert in DER format [13:50:25.201](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [13:50:25.221](0.021s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [13:50:25.221](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [13:50:25.240](0.019s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [13:50:25.240](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [13:50:25.260](0.020s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [13:50:25.260](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [13:50:25.279](0.019s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [13:50:25.279](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [13:50:25.296](0.018s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [13:50:25.297](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [13:50:25.314](0.017s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [13:50:25.314](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [13:50:25.336](0.022s) ok 163 - certificate authorization succeeds with DN mapping [13:50:25.336](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [13:50:25.336](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [13:50:25.356](0.020s) ok 166 - certificate authorization succeeds with DN regex mapping [13:50:25.356](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [13:50:25.376](0.020s) ok 168 - certificate authorization succeeds with CN mapping [13:50:25.377](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [13:50:25.377](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [13:50:25.377](0.000s) not ok 171 # TODO & SKIP Need Pty support [13:50:25.377](0.000s) not ok 172 # TODO & SKIP Need Pty support [13:50:25.377](0.000s) not ok 173 # TODO & SKIP Need Pty support [13:50:25.377](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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_CgHw/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [13:50:25.404](0.028s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [13:50:25.405](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [13:50:25.405](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [13:50:25.419](0.015s) ok 178 - certificate authorization fails because of file permissions [13:50:25.419](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [13:50:25.438](0.018s) ok 180 - certificate authorization fails with client cert belonging to another user [13:50:25.438](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [13:50:25.456](0.018s) ok 182 - certificate authorization fails with revoked client cert [13:50:25.456](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [13:50:25.456](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [13:50:25.476](0.020s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [13:50:25.477](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [13:50:25.477](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [13:50:25.494](0.018s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [13:50:25.494](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [13:50:25.495](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [13:50:25.513](0.018s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [13:50:25.513](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [13:50:25.513](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 /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39284 [13:50:25.765](0.252s) ok 194 - intermediate client certificate is provided by client [13:50:25.765](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [13:50:25.782](0.018s) ok 196 - intermediate client certificate is missing [13:50:25.782](0.000s) ok 197 - intermediate client certificate is missing: matches [13:50:25.800](0.017s) ok 198 - logged client certificate Subjects are truncated if they're too long [13:50:25.800](0.000s) ok 199 - logged client certificate Subjects are truncated if they're too long: matches ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39310 [13:50:26.044](0.244s) ok 200 - intermediate client certificate is untrusted [13:50:26.044](0.000s) ok 201 - intermediate client certificate is untrusted: matches ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39335 [13:50:26.285](0.241s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [13:50:26.285](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [13:50:26.305](0.020s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [13:50:26.305](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 /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39347 [13:50:26.544](0.239s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [13:50:26.544](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1' # at /Users/admin/pgsql/src/test/ssl/t/001_ssltests.pl line 923. [13:50:26.544](0.000s) # got: '2' # expected: '0' [13:50:26.544](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [13:50:26.544](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr' # at /Users/admin/pgsql/src/test/ssl/t/001_ssltests.pl line 923. [13:50:26.544](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 54525 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: '' [13:50:26.563](0.019s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [13:50:26.563](0.000s) ok 209 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39359 [13:50:26.805](0.241s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [13:50:26.825](0.020s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [13:50:26.825](0.000s) ok 212 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39372 [13:50:27.065](0.240s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [13:50:27.087](0.022s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [13:50:27.087](0.000s) ok 215 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39384 [13:50:27.336](0.249s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [13:50:27.358](0.021s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [13:50:27.358](0.000s) ok 218 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39396 [13:50:27.612](0.255s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [13:50:27.612](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1' # at /Users/admin/pgsql/src/test/ssl/t/001_ssltests.pl line 1001. [13:50:27.613](0.000s) # got: '2' # expected: '0' [13:50:27.613](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [13:50:27.613](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr' # at /Users/admin/pgsql/src/test/ssl/t/001_ssltests.pl line 1001. [13:50:27.613](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 54525 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: '' [13:50:27.634](0.021s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [13:50:27.634](0.000s) ok 222 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39408 [13:50:27.895](0.260s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [13:50:27.920](0.025s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [13:50:27.920](0.000s) ok 225 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39420 [13:50:28.173](0.253s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [13:50:28.196](0.024s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [13:50:28.196](0.000s) ok 228 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39432 [13:50:28.453](0.257s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [13:50:28.478](0.025s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [13:50:28.478](0.000s) ok 231 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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 39444 [13:50:28.732](0.253s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [13:50:28.757](0.025s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [13:50:28.757](0.000s) ok 234 - connect without requesting ocsp response when sslocspstapling=0: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/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-22 13:50:16.504 UTC [38276][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:16.505 UTC [38276][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:16.511 UTC [38294][startup] LOG: database system was shut down at 2024-03-22 13:47:11 UTC 2024-03-22 13:50:16.519 UTC [38276][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:16.604 UTC [38341][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:16.604 UTC [38341][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:16.604 UTC [38341][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:16.608 UTC [38341][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-22 13:50:16.608 UTC [38341][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=admin database=postgres host=[local] 2024-03-22 13:50:16.619 UTC [38347][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:16.619 UTC [38347][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:16.619 UTC [38347][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:16.620 UTC [38347][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-22 13:50:16.620 UTC [38347][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-22 13:50:16.674 UTC [38356][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:16.674 UTC [38356][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:16.674 UTC [38356][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:16.676 UTC [38356][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-22 13:50:16.676 UTC [38356][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=admin database=postgres host=[local] 2024-03-22 13:50:16.723 UTC [38373][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:16.723 UTC [38373][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:16.724 UTC [38373][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:16.724 UTC [38373][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-22 13:50:16.725 UTC [38373][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-22 13:50:16.777 UTC [38388][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:16.777 UTC [38388][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:16.777 UTC [38388][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:16.778 UTC [38388][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-22 13:50:16.778 UTC [38388][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-22 13:50:16.823 UTC [38398][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:16.823 UTC [38398][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:16.823 UTC [38398][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:16.824 UTC [38398][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-22 13:50:16.876 UTC [38398][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.053 user=admin database=postgres host=[local] 2024-03-22 13:50:16.964 UTC [38442][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:16.964 UTC [38442][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:16.964 UTC [38442][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:16.965 UTC [38442][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-22 13:50:17.088 UTC [38442][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.124 user=admin database=postgres host=[local] 2024-03-22 13:50:17.101 UTC [38461][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:17.101 UTC [38461][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:17.101 UTC [38461][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:17.102 UTC [38461][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-22 13:50:17.282 UTC [38461][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.181 user=admin database=postgres host=[local] 2024-03-22 13:50:17.407 UTC [38494][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:17.408 UTC [38494][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:17.408 UTC [38494][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:17.408 UTC [38494][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-22 13:50:17.804 UTC [38494][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.396 user=admin database=postgres host=[local] 2024-03-22 13:50:18.042 UTC [38571][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:18.042 UTC [38571][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:18.042 UTC [38571][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:18.043 UTC [38571][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-22 13:50:18.086 UTC [38571][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.044 user=admin database=postgres host=[local] 2024-03-22 13:50:18.180 UTC [38586][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-22 13:50:18.180 UTC [38586][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-22 13:50:18.180 UTC [38586][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-22 13:50:18.181 UTC [38586][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-22 13:50:18.406 UTC [38586][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.226 user=admin database=postgres host=[local] 2024-03-22 13:50:18.697 UTC [38276][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:18.697 UTC [38276][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:18.697 UTC [38276][postmaster] LOG: background worker "logical replication launcher" (PID 38304) exited with exit code 1 2024-03-22 13:50:18.698 UTC [38288][checkpointer] LOG: shutting down 2024-03-22 13:50:18.698 UTC [38288][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:19.377 UTC [38288][checkpointer] LOG: checkpoint complete: wrote 5572 buffers (34.0%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.679 s, sync=0.001 s, total=0.680 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44852 kB, estimate=44852 kB; lsn=0/40D6980, redo lsn=0/40D6980 2024-03-22 13:50:19.387 UTC [38276][postmaster] LOG: database system is shut down 2024-03-22 13:50:19.523 UTC [38742][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:19.523 UTC [38742][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:19.524 UTC [38742][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:19.527 UTC [38753][startup] LOG: database system was shut down at 2024-03-22 13:50:19 UTC 2024-03-22 13:50:19.529 UTC [38742][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:19.569 UTC [38742][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:19.569 UTC [38742][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:19.570 UTC [38742][postmaster] LOG: background worker "logical replication launcher" (PID 38756) exited with exit code 1 2024-03-22 13:50:19.570 UTC [38751][checkpointer] LOG: shutting down 2024-03-22 13:50:19.570 UTC [38751][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:19.575 UTC [38751][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.005 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D69F8, redo lsn=0/40D69F8 2024-03-22 13:50:19.576 UTC [38742][postmaster] LOG: database system is shut down 2024-03-22 13:50:19.693 UTC [38768][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-22 13:50:19.693 UTC [38768][postmaster] LOG: database system is shut down 2024-03-22 13:50:19.813 UTC [38772][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:19.813 UTC [38772][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:19.813 UTC [38772][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:19.816 UTC [38782][startup] LOG: database system was shut down at 2024-03-22 13:50:19 UTC 2024-03-22 13:50:19.817 UTC [38772][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:19.895 UTC [38772][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:19.895 UTC [38772][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:19.896 UTC [38772][postmaster] LOG: background worker "logical replication launcher" (PID 38785) exited with exit code 1 2024-03-22 13:50:19.896 UTC [38780][checkpointer] LOG: shutting down 2024-03-22 13:50:19.896 UTC [38780][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:19.901 UTC [38780][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.006 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D6A70, redo lsn=0/40D6A70 2024-03-22 13:50:19.903 UTC [38772][postmaster] LOG: database system is shut down 2024-03-22 13:50:20.017 UTC [38794][postmaster] FATAL: could not set SSL protocol version range 2024-03-22 13:50:20.017 UTC [38794][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-22 13:50:20.030 UTC [38794][postmaster] LOG: database system is shut down 2024-03-22 13:50:20.206 UTC [38805][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:20.214 UTC [38805][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:20.214 UTC [38805][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:20.334 UTC [38823][startup] LOG: database system was shut down at 2024-03-22 13:50:19 UTC 2024-03-22 13:50:20.337 UTC [38805][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:20.445 UTC [38805][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:20.445 UTC [38805][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:20.447 UTC [38805][postmaster] LOG: background worker "logical replication launcher" (PID 38829) exited with exit code 1 2024-03-22 13:50:20.447 UTC [38820][checkpointer] LOG: shutting down 2024-03-22 13:50:20.447 UTC [38820][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:20.448 UTC [38820][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/40D6AE8, redo lsn=0/40D6AE8 2024-03-22 13:50:20.449 UTC [38805][postmaster] LOG: database system is shut down 2024-03-22 13:50:20.567 UTC [38862][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:20.567 UTC [38862][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:20.567 UTC [38862][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:20.571 UTC [38868][startup] LOG: database system was shut down at 2024-03-22 13:50:20 UTC 2024-03-22 13:50:20.575 UTC [38862][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:20.669 UTC [38891][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49253 2024-03-22 13:50:20.674 UTC [38891][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-22 13:50:20.674 UTC [38891][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-03-22 13:50:20.688 UTC [38898][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49256 2024-03-22 13:50:20.696 UTC [38898][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:20.696 UTC [38898][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-22 13:50:20.700 UTC [38898][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-22 13:50:20.700 UTC [38898][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49256 2024-03-22 13:50:20.712 UTC [38905][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49260 2024-03-22 13:50:20.718 UTC [38905][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-22 13:50:20.729 UTC [38910][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49262 2024-03-22 13:50:20.735 UTC [38910][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-22 13:50:20.747 UTC [38915][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49264 2024-03-22 13:50:20.755 UTC [38915][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-22 13:50:20.766 UTC [38920][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49267 2024-03-22 13:50:20.774 UTC [38920][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-22 13:50:20.785 UTC [38925][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49270 2024-03-22 13:50:20.793 UTC [38925][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-22 13:50:20.804 UTC [38929][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49272 2024-03-22 13:50:20.812 UTC [38929][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-22 13:50:20.825 UTC [38935][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49274 2024-03-22 13:50:20.833 UTC [38935][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:20.833 UTC [38935][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-22 13:50:20.834 UTC [38935][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-22 13:50:20.834 UTC [38935][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49274 2024-03-22 13:50:20.845 UTC [38945][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49276 2024-03-22 13:50:20.853 UTC [38945][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:20.853 UTC [38945][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-22 13:50:20.854 UTC [38945][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-22 13:50:20.854 UTC [38945][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49276 2024-03-22 13:50:20.864 UTC [38949][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49278 2024-03-22 13:50:20.873 UTC [38949][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:20.873 UTC [38949][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-22 13:50:20.873 UTC [38949][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-22 13:50:20.874 UTC [38949][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49278 2024-03-22 13:50:20.884 UTC [38953][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49280 2024-03-22 13:50:20.893 UTC [38953][client backend] [[unknown]][5/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:20.893 UTC [38953][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-22 13:50:20.893 UTC [38953][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-22 13:50:20.894 UTC [38953][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49280 2024-03-22 13:50:20.904 UTC [38957][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49282 2024-03-22 13:50:20.914 UTC [38957][client backend] [[unknown]][6/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:20.914 UTC [38957][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-22 13:50:20.914 UTC [38957][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-22 13:50:20.915 UTC [38957][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49282 2024-03-22 13:50:20.929 UTC [38963][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49284 2024-03-22 13:50:20.937 UTC [38963][client backend] [[unknown]][7/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:20.937 UTC [38963][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-22 13:50:20.938 UTC [38963][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-22 13:50:20.938 UTC [38963][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49284 2024-03-22 13:50:20.952 UTC [38974][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49286 2024-03-22 13:50:20.960 UTC [38974][client backend] [[unknown]][8/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:20.960 UTC [38974][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-22 13:50:20.961 UTC [38974][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-22 13:50:20.961 UTC [38974][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49286 2024-03-22 13:50:20.972 UTC [38978][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49288 2024-03-22 13:50:20.980 UTC [38978][client backend] [[unknown]][9/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:20.980 UTC [38978][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-22 13:50:20.981 UTC [38978][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49288 2024-03-22 13:50:20.991 UTC [38982][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49290 2024-03-22 13:50:20.999 UTC [38982][client backend] [[unknown]][10/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:20.999 UTC [38982][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-22 13:50:21.000 UTC [38982][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-22 13:50:21.000 UTC [38982][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49290 2024-03-22 13:50:21.011 UTC [38986][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49292 2024-03-22 13:50:21.019 UTC [38986][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-22 13:50:21.029 UTC [38990][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49294 2024-03-22 13:50:21.037 UTC [38990][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-22 13:50:21.048 UTC [38993][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49295 2024-03-22 13:50:21.057 UTC [38993][client backend] [[unknown]][11/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:21.057 UTC [38993][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-22 13:50:21.057 UTC [38993][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-22 13:50:21.057 UTC [38993][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49295 2024-03-22 13:50:21.068 UTC [38996][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49296 2024-03-22 13:50:21.077 UTC [38996][client backend] [[unknown]][12/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:21.077 UTC [38996][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-22 13:50:21.077 UTC [38996][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-22 13:50:21.077 UTC [38996][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49296 2024-03-22 13:50:21.088 UTC [39005][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49297 2024-03-22 13:50:21.097 UTC [39005][client backend] [[unknown]][13/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:21.097 UTC [39005][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-22 13:50:21.098 UTC [39005][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-22 13:50:21.098 UTC [39005][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49297 2024-03-22 13:50:21.109 UTC [39007][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49298 2024-03-22 13:50:21.117 UTC [39007][client backend] [[unknown]][14/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:21.117 UTC [39007][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-22 13:50:21.118 UTC [39007][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-22 13:50:21.118 UTC [39007][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49298 2024-03-22 13:50:21.129 UTC [39009][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49299 2024-03-22 13:50:21.142 UTC [38862][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:21.142 UTC [38862][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:21.143 UTC [38862][postmaster] LOG: background worker "logical replication launcher" (PID 38871) exited with exit code 1 2024-03-22 13:50:21.144 UTC [38866][checkpointer] LOG: shutting down 2024-03-22 13:50:21.144 UTC [38866][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:21.144 UTC [38866][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/40D6B60, redo lsn=0/40D6B60 2024-03-22 13:50:21.146 UTC [38862][postmaster] LOG: database system is shut down 2024-03-22 13:50:21.266 UTC [39014][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:21.267 UTC [39014][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:21.267 UTC [39014][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:21.270 UTC [39017][startup] LOG: database system was shut down at 2024-03-22 13:50:21 UTC 2024-03-22 13:50:21.272 UTC [39014][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:21.790 UTC [39034][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49301 2024-03-22 13:50:21.800 UTC [39034][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:21.800 UTC [39034][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-22 13:50:21.802 UTC [39034][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-22 13:50:21.803 UTC [39034][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49301 2024-03-22 13:50:21.816 UTC [39042][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49302 2024-03-22 13:50:21.830 UTC [39014][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:21.830 UTC [39014][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:21.831 UTC [39014][postmaster] LOG: background worker "logical replication launcher" (PID 39020) exited with exit code 1 2024-03-22 13:50:21.831 UTC [39015][checkpointer] LOG: shutting down 2024-03-22 13:50:21.831 UTC [39015][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:21.831 UTC [39015][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/40D6BD8, redo lsn=0/40D6BD8 2024-03-22 13:50:21.833 UTC [39014][postmaster] LOG: database system is shut down 2024-03-22 13:50:21.956 UTC [39047][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:21.957 UTC [39047][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:21.957 UTC [39047][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:21.963 UTC [39050][startup] LOG: database system was shut down at 2024-03-22 13:50:21 UTC 2024-03-22 13:50:21.964 UTC [39047][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:22.063 UTC [39062][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49304 2024-03-22 13:50:22.072 UTC [39062][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:22.072 UTC [39062][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-22 13:50:22.075 UTC [39062][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-22 13:50:22.075 UTC [39062][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49304 2024-03-22 13:50:22.080 UTC [39047][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:22.080 UTC [39047][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:22.080 UTC [39047][postmaster] LOG: background worker "logical replication launcher" (PID 39053) exited with exit code 1 2024-03-22 13:50:22.081 UTC [39048][checkpointer] LOG: shutting down 2024-03-22 13:50:22.081 UTC [39048][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:22.082 UTC [39048][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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D6C50, redo lsn=0/40D6C50 2024-03-22 13:50:22.083 UTC [39047][postmaster] LOG: database system is shut down 2024-03-22 13:50:22.208 UTC [39067][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:22.208 UTC [39067][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:22.208 UTC [39067][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:22.210 UTC [39070][startup] LOG: database system was shut down at 2024-03-22 13:50:22 UTC 2024-03-22 13:50:22.212 UTC [39067][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:22.309 UTC [39075][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49306 2024-03-22 13:50:22.318 UTC [39075][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:22.318 UTC [39075][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-22 13:50:22.321 UTC [39075][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-22 13:50:22.321 UTC [39075][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49306 2024-03-22 13:50:22.331 UTC [39077][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49307 2024-03-22 13:50:22.340 UTC [39077][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:22.340 UTC [39077][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-22 13:50:22.341 UTC [39077][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-22 13:50:22.341 UTC [39077][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49307 2024-03-22 13:50:22.352 UTC [39079][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49308 2024-03-22 13:50:22.360 UTC [39079][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:22.361 UTC [39079][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-22 13:50:22.361 UTC [39079][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-22 13:50:22.361 UTC [39079][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49308 2024-03-22 13:50:22.372 UTC [39081][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49309 2024-03-22 13:50:22.394 UTC [39083][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49310 2024-03-22 13:50:22.410 UTC [39067][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:22.410 UTC [39067][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:22.411 UTC [39067][postmaster] LOG: background worker "logical replication launcher" (PID 39073) exited with exit code 1 2024-03-22 13:50:22.411 UTC [39068][checkpointer] LOG: shutting down 2024-03-22 13:50:22.411 UTC [39068][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:22.412 UTC [39068][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D6CC8, redo lsn=0/40D6CC8 2024-03-22 13:50:22.413 UTC [39067][postmaster] LOG: database system is shut down 2024-03-22 13:50:22.533 UTC [39085][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:22.533 UTC [39085][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:22.533 UTC [39085][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:22.536 UTC [39088][startup] LOG: database system was shut down at 2024-03-22 13:50:22 UTC 2024-03-22 13:50:22.537 UTC [39085][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:22.632 UTC [39093][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49311 2024-03-22 13:50:22.640 UTC [39093][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:22.640 UTC [39093][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-22 13:50:22.642 UTC [39093][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-22 13:50:22.643 UTC [39093][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49311 2024-03-22 13:50:22.653 UTC [39095][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49312 2024-03-22 13:50:22.671 UTC [39097][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49313 2024-03-22 13:50:22.689 UTC [39085][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:22.689 UTC [39085][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:22.690 UTC [39085][postmaster] LOG: background worker "logical replication launcher" (PID 39091) exited with exit code 1 2024-03-22 13:50:22.690 UTC [39086][checkpointer] LOG: shutting down 2024-03-22 13:50:22.690 UTC [39086][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:22.691 UTC [39086][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/40D6D40, redo lsn=0/40D6D40 2024-03-22 13:50:22.692 UTC [39085][postmaster] LOG: database system is shut down 2024-03-22 13:50:22.815 UTC [39100][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:22.816 UTC [39100][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:22.816 UTC [39100][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:22.819 UTC [39103][startup] LOG: database system was shut down at 2024-03-22 13:50:22 UTC 2024-03-22 13:50:22.820 UTC [39100][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:22.921 UTC [39108][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49314 2024-03-22 13:50:22.931 UTC [39108][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:22.931 UTC [39108][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-22 13:50:22.933 UTC [39108][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-22 13:50:22.934 UTC [39108][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49314 2024-03-22 13:50:22.945 UTC [39110][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49315 2024-03-22 13:50:22.954 UTC [39110][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:22.954 UTC [39110][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-22 13:50:22.955 UTC [39110][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-22 13:50:22.955 UTC [39110][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49315 2024-03-22 13:50:22.966 UTC [39112][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49316 2024-03-22 13:50:22.984 UTC [39114][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49317 2024-03-22 13:50:22.993 UTC [39114][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:22.993 UTC [39114][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-22 13:50:22.993 UTC [39114][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-22 13:50:22.993 UTC [39114][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49317 2024-03-22 13:50:23.004 UTC [39116][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49318 2024-03-22 13:50:23.012 UTC [39116][client backend] [[unknown]][3/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:23.012 UTC [39116][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-22 13:50:23.012 UTC [39116][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-22 13:50:23.012 UTC [39116][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49318 2024-03-22 13:50:23.023 UTC [39118][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49319 2024-03-22 13:50:23.031 UTC [39118][client backend] [[unknown]][4/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:23.031 UTC [39118][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-22 13:50:23.031 UTC [39118][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-22 13:50:23.031 UTC [39118][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49319 2024-03-22 13:50:23.042 UTC [39120][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49320 2024-03-22 13:50:23.061 UTC [39122][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49321 2024-03-22 13:50:23.076 UTC [39100][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:23.076 UTC [39100][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:23.077 UTC [39100][postmaster] LOG: background worker "logical replication launcher" (PID 39106) exited with exit code 1 2024-03-22 13:50:23.077 UTC [39101][checkpointer] LOG: shutting down 2024-03-22 13:50:23.077 UTC [39101][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:23.078 UTC [39101][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/40D6DB8, redo lsn=0/40D6DB8 2024-03-22 13:50:23.079 UTC [39100][postmaster] LOG: database system is shut down 2024-03-22 13:50:23.198 UTC [39124][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:23.198 UTC [39124][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:23.198 UTC [39124][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:23.201 UTC [39127][startup] LOG: database system was shut down at 2024-03-22 13:50:23 UTC 2024-03-22 13:50:23.202 UTC [39124][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:23.301 UTC [39132][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49322 2024-03-22 13:50:23.311 UTC [39132][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:23.311 UTC [39132][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-22 13:50:23.313 UTC [39132][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-22 13:50:23.314 UTC [39132][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49322 2024-03-22 13:50:23.324 UTC [39134][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49323 2024-03-22 13:50:23.333 UTC [39134][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:23.333 UTC [39134][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-22 13:50:23.333 UTC [39134][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-22 13:50:23.334 UTC [39134][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49323 2024-03-22 13:50:23.346 UTC [39136][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49324 2024-03-22 13:50:23.363 UTC [39124][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:23.363 UTC [39124][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:23.364 UTC [39124][postmaster] LOG: background worker "logical replication launcher" (PID 39130) exited with exit code 1 2024-03-22 13:50:23.364 UTC [39125][checkpointer] LOG: shutting down 2024-03-22 13:50:23.364 UTC [39125][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:23.364 UTC [39125][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/40D6E30, redo lsn=0/40D6E30 2024-03-22 13:50:23.365 UTC [39124][postmaster] LOG: database system is shut down 2024-03-22 13:50:23.490 UTC [39139][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:23.490 UTC [39139][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:23.490 UTC [39139][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:23.493 UTC [39142][startup] LOG: database system was shut down at 2024-03-22 13:50:23 UTC 2024-03-22 13:50:23.495 UTC [39139][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:23.591 UTC [39147][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49325 2024-03-22 13:50:23.600 UTC [39147][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:23.600 UTC [39147][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-22 13:50:23.602 UTC [39147][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-22 13:50:23.603 UTC [39147][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49325 2024-03-22 13:50:23.613 UTC [39149][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49326 2024-03-22 13:50:23.622 UTC [39149][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:23.622 UTC [39149][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-22 13:50:23.622 UTC [39149][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-22 13:50:23.623 UTC [39149][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49326 2024-03-22 13:50:23.634 UTC [39151][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49327 2024-03-22 13:50:23.643 UTC [39151][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:23.643 UTC [39151][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-22 13:50:23.643 UTC [39151][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-22 13:50:23.643 UTC [39151][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49327 2024-03-22 13:50:23.648 UTC [39139][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:23.648 UTC [39139][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:23.649 UTC [39139][postmaster] LOG: background worker "logical replication launcher" (PID 39145) exited with exit code 1 2024-03-22 13:50:23.649 UTC [39140][checkpointer] LOG: shutting down 2024-03-22 13:50:23.649 UTC [39140][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:23.649 UTC [39140][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/40D6EA8, redo lsn=0/40D6EA8 2024-03-22 13:50:23.651 UTC [39139][postmaster] LOG: database system is shut down 2024-03-22 13:50:23.770 UTC [39153][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:23.771 UTC [39153][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:23.771 UTC [39153][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:23.776 UTC [39156][startup] LOG: database system was shut down at 2024-03-22 13:50:23 UTC 2024-03-22 13:50:23.777 UTC [39153][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:23.876 UTC [39161][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49328 2024-03-22 13:50:23.886 UTC [39161][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:23.887 UTC [39161][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-22 13:50:23.908 UTC [39161][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-22 13:50:23.909 UTC [39161][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.032 user=ssltestuser database=trustdb host=localhost port=49328 2024-03-22 13:50:23.920 UTC [39163][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49329 2024-03-22 13:50:23.930 UTC [39163][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:23.930 UTC [39163][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-22 13:50:23.930 UTC [39163][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-22 13:50:23.930 UTC [39163][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49329 2024-03-22 13:50:23.942 UTC [39165][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49330 2024-03-22 13:50:23.955 UTC [39153][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:23.955 UTC [39153][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:23.955 UTC [39153][postmaster] LOG: background worker "logical replication launcher" (PID 39159) exited with exit code 1 2024-03-22 13:50:23.955 UTC [39154][checkpointer] LOG: shutting down 2024-03-22 13:50:23.956 UTC [39154][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:23.956 UTC [39154][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/40D6F20, redo lsn=0/40D6F20 2024-03-22 13:50:23.957 UTC [39153][postmaster] LOG: database system is shut down 2024-03-22 13:50:24.109 UTC [39167][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:24.110 UTC [39167][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:24.110 UTC [39167][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:24.120 UTC [39170][startup] LOG: database system was shut down at 2024-03-22 13:50:23 UTC 2024-03-22 13:50:24.122 UTC [39167][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:24.191 UTC [39175][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49331 2024-03-22 13:50:24.199 UTC [39175][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:24.199 UTC [39175][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-22 13:50:24.202 UTC [39175][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-22 13:50:24.202 UTC [39175][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49331 2024-03-22 13:50:24.213 UTC [39177][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49332 2024-03-22 13:50:24.221 UTC [39177][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:24.221 UTC [39177][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-22 13:50:24.221 UTC [39177][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-22 13:50:24.221 UTC [39177][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49332 2024-03-22 13:50:24.231 UTC [39179][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49333 2024-03-22 13:50:24.239 UTC [39179][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:24.239 UTC [39179][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-22 13:50:24.240 UTC [39179][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-22 13:50:24.240 UTC [39179][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49333 2024-03-22 13:50:24.244 UTC [39167][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:24.244 UTC [39167][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:24.245 UTC [39167][postmaster] LOG: background worker "logical replication launcher" (PID 39173) exited with exit code 1 2024-03-22 13:50:24.245 UTC [39168][checkpointer] LOG: shutting down 2024-03-22 13:50:24.245 UTC [39168][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:24.246 UTC [39168][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/40D6F98, redo lsn=0/40D6F98 2024-03-22 13:50:24.247 UTC [39167][postmaster] LOG: database system is shut down 2024-03-22 13:50:24.371 UTC [39181][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:24.371 UTC [39181][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:24.372 UTC [39181][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:24.374 UTC [39184][startup] LOG: database system was shut down at 2024-03-22 13:50:24 UTC 2024-03-22 13:50:24.377 UTC [39181][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:24.479 UTC [39189][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49334 2024-03-22 13:50:24.488 UTC [39189][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:24.488 UTC [39189][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-22 13:50:24.490 UTC [39189][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-22 13:50:24.490 UTC [39189][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49334 2024-03-22 13:50:24.501 UTC [39191][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49335 2024-03-22 13:50:24.514 UTC [39181][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:24.514 UTC [39181][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:24.515 UTC [39181][postmaster] LOG: background worker "logical replication launcher" (PID 39187) exited with exit code 1 2024-03-22 13:50:24.515 UTC [39182][checkpointer] LOG: shutting down 2024-03-22 13:50:24.515 UTC [39182][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:24.516 UTC [39182][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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D7010, redo lsn=0/40D7010 2024-03-22 13:50:24.517 UTC [39181][postmaster] LOG: database system is shut down 2024-03-22 13:50:24.636 UTC [39193][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:24.636 UTC [39193][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:24.636 UTC [39193][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:24.639 UTC [39196][startup] LOG: database system was shut down at 2024-03-22 13:50:24 UTC 2024-03-22 13:50:24.641 UTC [39193][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:24.743 UTC [39201][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49336 2024-03-22 13:50:24.750 UTC [39201][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-22 13:50:24.771 UTC [39204][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49337 2024-03-22 13:50:24.779 UTC [39204][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:24.779 UTC [39204][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-22 13:50:24.781 UTC [39204][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-22 13:50:24.781 UTC [39204][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49337 2024-03-22 13:50:24.792 UTC [39206][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49338 2024-03-22 13:50:24.804 UTC [39193][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:24.804 UTC [39193][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:24.804 UTC [39193][postmaster] LOG: background worker "logical replication launcher" (PID 39199) exited with exit code 1 2024-03-22 13:50:24.804 UTC [39194][checkpointer] LOG: shutting down 2024-03-22 13:50:24.804 UTC [39194][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:24.805 UTC [39194][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/40D7088, redo lsn=0/40D7088 2024-03-22 13:50:24.806 UTC [39193][postmaster] LOG: database system is shut down 2024-03-22 13:50:24.931 UTC [39208][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:24.932 UTC [39208][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:24.932 UTC [39208][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:24.934 UTC [39211][startup] LOG: database system was shut down at 2024-03-22 13:50:24 UTC 2024-03-22 13:50:24.936 UTC [39208][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:25.040 UTC [39216][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49339 2024-03-22 13:50:25.048 UTC [39216][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:25.048 UTC [39216][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-22 13:50:25.050 UTC [39216][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-22 13:50:25.050 UTC [39216][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49339 2024-03-22 13:50:25.060 UTC [39218][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49340 2024-03-22 13:50:25.068 UTC [39218][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: ssl/tls alert certificate revoked 2024-03-22 13:50:25.077 UTC [39220][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49341 2024-03-22 13:50:25.084 UTC [39220][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: ssl/tls alert certificate revoked 2024-03-22 13:50:25.093 UTC [39222][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49342 2024-03-22 13:50:25.100 UTC [39222][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:25.100 UTC [39222][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-22 13:50:25.101 UTC [39222][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-22 13:50:25.101 UTC [39222][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49342 2024-03-22 13:50:25.111 UTC [39224][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49343 2024-03-22 13:50:25.119 UTC [39224][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:25.119 UTC [39224][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-22 13:50:25.119 UTC [39224][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-22 13:50:25.119 UTC [39224][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49343 2024-03-22 13:50:25.153 UTC [39229][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49344 2024-03-22 13:50:25.160 UTC [39229][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-03-22 13:50:25.170 UTC [39231][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49345 2024-03-22 13:50:25.179 UTC [39231][client backend] [[unknown]][4/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-22 13:50:25.179 UTC [39231][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-22 13:50:25.181 UTC [39231][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_CgHw/client.key$$ 2024-03-22 13:50:25.181 UTC [39231][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=49345 2024-03-22 13:50:25.190 UTC [39233][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49346 2024-03-22 13:50:25.199 UTC [39233][client backend] [[unknown]][5/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-22 13:50:25.199 UTC [39233][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-22 13:50:25.200 UTC [39233][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_CgHw/client-der.key$$ 2024-03-22 13:50:25.200 UTC [39233][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49346 2024-03-22 13:50:25.210 UTC [39235][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49347 2024-03-22 13:50:25.220 UTC [39235][client backend] [[unknown]][6/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-22 13:50:25.220 UTC [39235][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-22 13:50:25.220 UTC [39235][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_CgHw/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-03-22 13:50:25.221 UTC [39235][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=49347 2024-03-22 13:50:25.230 UTC [39237][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49348 2024-03-22 13:50:25.238 UTC [39237][client backend] [[unknown]][7/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-22 13:50:25.238 UTC [39237][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-22 13:50:25.239 UTC [39237][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_CgHw/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-03-22 13:50:25.239 UTC [39237][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49348 2024-03-22 13:50:25.249 UTC [39239][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49349 2024-03-22 13:50:25.258 UTC [39239][client backend] [[unknown]][8/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-22 13:50:25.258 UTC [39239][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-22 13:50:25.259 UTC [39239][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_CgHw/client.key$$ 2024-03-22 13:50:25.259 UTC [39239][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49349 2024-03-22 13:50:25.268 UTC [39241][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49350 2024-03-22 13:50:25.277 UTC [39241][client backend] [[unknown]][9/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-22 13:50:25.277 UTC [39241][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-22 13:50:25.278 UTC [39241][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_CgHw/client.key$$ 2024-03-22 13:50:25.278 UTC [39241][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49350 2024-03-22 13:50:25.287 UTC [39243][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49351 2024-03-22 13:50:25.295 UTC [39243][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-03-22 13:50:25.306 UTC [39250][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49352 2024-03-22 13:50:25.313 UTC [39250][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-22 13:50:25.323 UTC [39262][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49353 2024-03-22 13:50:25.332 UTC [39262][client backend] [[unknown]][11/1:0] LOG: connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:8) 2024-03-22 13:50:25.332 UTC [39262][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-22 13:50:25.335 UTC [39262][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_CgHw/client-dn.key$$ 2024-03-22 13:50:25.335 UTC [39262][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb_dn host=localhost port=49353 2024-03-22 13:50:25.345 UTC [39264][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49354 2024-03-22 13:50:25.353 UTC [39264][client backend] [[unknown]][12/1:0] LOG: connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:9) 2024-03-22 13:50:25.353 UTC [39264][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-22 13:50:25.355 UTC [39264][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_CgHw/client-dn.key$$ 2024-03-22 13:50:25.355 UTC [39264][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_dn_re host=localhost port=49354 2024-03-22 13:50:25.364 UTC [39266][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49355 2024-03-22 13:50:25.373 UTC [39266][client backend] [[unknown]][13/1:0] LOG: connection authenticated: identity="CN=ssltestuser-dn,OU=Testing,OU=Engineering,O=PGDG" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:10) 2024-03-22 13:50:25.373 UTC [39266][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-22 13:50:25.375 UTC [39266][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_CgHw/client-dn.key$$ 2024-03-22 13:50:25.376 UTC [39266][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_cn host=localhost port=49355 2024-03-22 13:50:25.394 UTC [39269][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49356 2024-03-22 13:50:25.402 UTC [39269][client backend] [[unknown]][14/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-22 13:50:25.402 UTC [39269][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-22 13:50:25.403 UTC [39269][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-22 13:50:25.404 UTC [39269][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=49356 2024-03-22 13:50:25.413 UTC [39271][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49357 2024-03-22 13:50:25.419 UTC [39271][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-22 13:50:25.428 UTC [39273][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49358 2024-03-22 13:50:25.437 UTC [39273][client backend] [[unknown]][15/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-22 13:50:25.437 UTC [39273][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-22 13:50:25.437 UTC [39273][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-03-22 13:50:25.437 UTC [39273][client backend] [[unknown]][15/1:0] DETAIL: Connection matched file "/Users/admin/pgsql/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-22 13:50:25.446 UTC [39275][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49359 2024-03-22 13:50:25.455 UTC [39275][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-22 13:50:25.455 UTC [39275][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-22 13:50:25.464 UTC [39277][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49360 2024-03-22 13:50:25.473 UTC [39277][client backend] [[unknown]][16/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:4) 2024-03-22 13:50:25.473 UTC [39277][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-22 13:50:25.475 UTC [39277][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_CgHw/client.key$$ 2024-03-22 13:50:25.476 UTC [39277][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=verifydb host=localhost port=49360 2024-03-22 13:50:25.485 UTC [39279][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49361 2024-03-22 13:50:25.494 UTC [39279][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-22 13:50:25.494 UTC [39279][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-03-22 13:50:25.494 UTC [39279][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-03-22 13:50:25.494 UTC [39279][client backend] [[unknown]][17/1:0] DETAIL: Connection matched file "/Users/admin/pgsql/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-22 13:50:25.503 UTC [39281][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49362 2024-03-22 13:50:25.511 UTC [39281][client backend] [[unknown]][18/1:0] LOG: connection authenticated: user="yetanotheruser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:6) 2024-03-22 13:50:25.511 UTC [39281][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-22 13:50:25.512 UTC [39281][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_CgHw/client.key$$ 2024-03-22 13:50:25.512 UTC [39281][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=yetanotheruser database=verifydb host=localhost port=49362 2024-03-22 13:50:25.516 UTC [39208][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:25.516 UTC [39208][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:25.517 UTC [39208][postmaster] LOG: background worker "logical replication launcher" (PID 39214) exited with exit code 1 2024-03-22 13:50:25.517 UTC [39209][checkpointer] LOG: shutting down 2024-03-22 13:50:25.517 UTC [39209][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:25.518 UTC [39209][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/40D7100, redo lsn=0/40D7100 2024-03-22 13:50:25.519 UTC [39208][postmaster] LOG: database system is shut down 2024-03-22 13:50:25.637 UTC [39284][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:25.638 UTC [39284][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:25.638 UTC [39284][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:25.640 UTC [39287][startup] LOG: database system was shut down at 2024-03-22 13:50:25 UTC 2024-03-22 13:50:25.642 UTC [39284][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:25.748 UTC [39296][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49363 2024-03-22 13:50:25.761 UTC [39296][client backend] [[unknown]][0/1:0] LOG: connection authenticated: identity="CN=ssltestuser" method=cert (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:7) 2024-03-22 13:50:25.761 UTC [39296][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-22 13:50:25.763 UTC [39296][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=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_CgHw/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-22 13:50:25.764 UTC [39296][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.015 user=ssltestuser database=certdb host=localhost port=49363 2024-03-22 13:50:25.774 UTC [39301][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49364 2024-03-22 13:50:25.781 UTC [39301][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-22 13:50:25.781 UTC [39301][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-22 13:50:25.791 UTC [39303][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49365 2024-03-22 13:50:25.799 UTC [39303][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-22 13:50:25.799 UTC [39303][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-22 13:50:25.803 UTC [39284][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:25.803 UTC [39284][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:25.803 UTC [39284][postmaster] LOG: background worker "logical replication launcher" (PID 39290) exited with exit code 1 2024-03-22 13:50:25.803 UTC [39285][checkpointer] LOG: shutting down 2024-03-22 13:50:25.804 UTC [39285][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:25.804 UTC [39285][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/40D7178, redo lsn=0/40D7178 2024-03-22 13:50:25.805 UTC [39284][postmaster] LOG: database system is shut down 2024-03-22 13:50:25.934 UTC [39310][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:25.934 UTC [39310][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:25.936 UTC [39310][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:25.950 UTC [39313][startup] LOG: database system was shut down at 2024-03-22 13:50:25 UTC 2024-03-22 13:50:25.952 UTC [39310][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:26.034 UTC [39332][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49366 2024-03-22 13:50:26.043 UTC [39332][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-22 13:50:26.043 UTC [39332][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-22 13:50:26.047 UTC [39310][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:26.047 UTC [39310][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:26.048 UTC [39310][postmaster] LOG: background worker "logical replication launcher" (PID 39316) exited with exit code 1 2024-03-22 13:50:26.048 UTC [39311][checkpointer] LOG: shutting down 2024-03-22 13:50:26.048 UTC [39311][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:26.049 UTC [39311][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D71F0, redo lsn=0/40D71F0 2024-03-22 13:50:26.050 UTC [39310][postmaster] LOG: database system is shut down 2024-03-22 13:50:26.175 UTC [39335][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:26.175 UTC [39335][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:26.176 UTC [39335][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:26.178 UTC [39338][startup] LOG: database system was shut down at 2024-03-22 13:50:26 UTC 2024-03-22 13:50:26.180 UTC [39335][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:26.272 UTC [39343][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49367 2024-03-22 13:50:26.284 UTC [39343][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-22 13:50:26.284 UTC [39343][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-22 13:50:26.295 UTC [39345][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49368 2024-03-22 13:50:26.304 UTC [39345][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-22 13:50:26.304 UTC [39345][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-22 13:50:26.308 UTC [39335][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:26.308 UTC [39335][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:26.309 UTC [39335][postmaster] LOG: background worker "logical replication launcher" (PID 39341) exited with exit code 1 2024-03-22 13:50:26.309 UTC [39336][checkpointer] LOG: shutting down 2024-03-22 13:50:26.309 UTC [39336][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:26.309 UTC [39336][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/40D7268, redo lsn=0/40D7268 2024-03-22 13:50:26.310 UTC [39335][postmaster] LOG: database system is shut down 2024-03-22 13:50:26.432 UTC [39347][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:26.432 UTC [39347][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:26.432 UTC [39347][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:26.434 UTC [39350][startup] LOG: database system was shut down at 2024-03-22 13:50:26 UTC 2024-03-22 13:50:26.436 UTC [39347][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:26.536 UTC [39355][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49369 2024-03-22 13:50:26.542 UTC [39355][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-22 13:50:26.542 UTC [39355][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-22 13:50:26.552 UTC [39357][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49370 2024-03-22 13:50:26.560 UTC [39357][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:26.560 UTC [39357][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-22 13:50:26.562 UTC [39357][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-22 13:50:26.563 UTC [39357][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49370 2024-03-22 13:50:26.566 UTC [39347][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:26.566 UTC [39347][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:26.567 UTC [39347][postmaster] LOG: background worker "logical replication launcher" (PID 39353) exited with exit code 1 2024-03-22 13:50:26.567 UTC [39348][checkpointer] LOG: shutting down 2024-03-22 13:50:26.567 UTC [39348][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:26.568 UTC [39348][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/40D72E0, redo lsn=0/40D72E0 2024-03-22 13:50:26.569 UTC [39347][postmaster] LOG: database system is shut down 2024-03-22 13:50:26.691 UTC [39359][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:26.692 UTC [39359][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:26.692 UTC [39359][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:26.694 UTC [39362][startup] LOG: database system was shut down at 2024-03-22 13:50:26 UTC 2024-03-22 13:50:26.695 UTC [39359][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:26.797 UTC [39368][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49371 2024-03-22 13:50:26.803 UTC [39368][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-22 13:50:26.803 UTC [39368][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-22 13:50:26.813 UTC [39370][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49372 2024-03-22 13:50:26.821 UTC [39370][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:26.821 UTC [39370][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-22 13:50:26.824 UTC [39370][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-22 13:50:26.824 UTC [39370][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49372 2024-03-22 13:50:26.828 UTC [39359][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:26.828 UTC [39359][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:26.829 UTC [39359][postmaster] LOG: background worker "logical replication launcher" (PID 39365) exited with exit code 1 2024-03-22 13:50:26.829 UTC [39360][checkpointer] LOG: shutting down 2024-03-22 13:50:26.829 UTC [39360][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:26.830 UTC [39360][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D7358, redo lsn=0/40D7358 2024-03-22 13:50:26.831 UTC [39359][postmaster] LOG: database system is shut down 2024-03-22 13:50:26.953 UTC [39372][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:26.953 UTC [39372][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:26.953 UTC [39372][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:26.956 UTC [39375][startup] LOG: database system was shut down at 2024-03-22 13:50:26 UTC 2024-03-22 13:50:26.958 UTC [39372][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:27.057 UTC [39380][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49373 2024-03-22 13:50:27.064 UTC [39380][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-22 13:50:27.064 UTC [39380][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-22 13:50:27.075 UTC [39382][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49374 2024-03-22 13:50:27.083 UTC [39382][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:27.083 UTC [39382][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-22 13:50:27.086 UTC [39382][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-22 13:50:27.086 UTC [39382][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49374 2024-03-22 13:50:27.091 UTC [39372][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:27.091 UTC [39372][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:27.092 UTC [39372][postmaster] LOG: background worker "logical replication launcher" (PID 39378) exited with exit code 1 2024-03-22 13:50:27.092 UTC [39373][checkpointer] LOG: shutting down 2024-03-22 13:50:27.092 UTC [39373][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:27.093 UTC [39373][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/40D73D0, redo lsn=0/40D73D0 2024-03-22 13:50:27.094 UTC [39372][postmaster] LOG: database system is shut down 2024-03-22 13:50:27.218 UTC [39384][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:27.219 UTC [39384][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:27.219 UTC [39384][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:27.221 UTC [39387][startup] LOG: database system was shut down at 2024-03-22 13:50:27 UTC 2024-03-22 13:50:27.223 UTC [39384][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:27.327 UTC [39392][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49375 2024-03-22 13:50:27.335 UTC [39392][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-22 13:50:27.335 UTC [39392][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-22 13:50:27.346 UTC [39394][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49376 2024-03-22 13:50:27.354 UTC [39394][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:27.354 UTC [39394][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-22 13:50:27.356 UTC [39394][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-22 13:50:27.357 UTC [39394][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49376 2024-03-22 13:50:27.361 UTC [39384][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:27.361 UTC [39384][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:27.361 UTC [39384][postmaster] LOG: background worker "logical replication launcher" (PID 39390) exited with exit code 1 2024-03-22 13:50:27.361 UTC [39385][checkpointer] LOG: shutting down 2024-03-22 13:50:27.361 UTC [39385][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:27.362 UTC [39385][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/40D7448, redo lsn=0/40D7448 2024-03-22 13:50:27.363 UTC [39384][postmaster] LOG: database system is shut down 2024-03-22 13:50:27.493 UTC [39396][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:27.493 UTC [39396][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:27.493 UTC [39396][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:27.496 UTC [39399][startup] LOG: database system was shut down at 2024-03-22 13:50:27 UTC 2024-03-22 13:50:27.497 UTC [39396][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:27.603 UTC [39404][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49377 2024-03-22 13:50:27.611 UTC [39404][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-22 13:50:27.611 UTC [39404][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-22 13:50:27.622 UTC [39406][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49378 2024-03-22 13:50:27.630 UTC [39406][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:27.630 UTC [39406][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-22 13:50:27.633 UTC [39406][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-22 13:50:27.633 UTC [39406][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49378 2024-03-22 13:50:27.637 UTC [39396][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:27.637 UTC [39396][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:27.638 UTC [39396][postmaster] LOG: background worker "logical replication launcher" (PID 39402) exited with exit code 1 2024-03-22 13:50:27.638 UTC [39397][checkpointer] LOG: shutting down 2024-03-22 13:50:27.638 UTC [39397][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:27.638 UTC [39397][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/40D74C0, redo lsn=0/40D74C0 2024-03-22 13:50:27.639 UTC [39396][postmaster] LOG: database system is shut down 2024-03-22 13:50:27.767 UTC [39408][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:27.767 UTC [39408][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:27.768 UTC [39408][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:27.770 UTC [39411][startup] LOG: database system was shut down at 2024-03-22 13:50:27 UTC 2024-03-22 13:50:27.772 UTC [39408][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:27.883 UTC [39416][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49379 2024-03-22 13:50:27.893 UTC [39416][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-22 13:50:27.893 UTC [39416][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-22 13:50:27.906 UTC [39418][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49380 2024-03-22 13:50:27.916 UTC [39418][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:27.916 UTC [39418][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-22 13:50:27.918 UTC [39418][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-22 13:50:27.919 UTC [39418][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49380 2024-03-22 13:50:27.923 UTC [39408][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:27.923 UTC [39408][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:27.924 UTC [39408][postmaster] LOG: background worker "logical replication launcher" (PID 39414) exited with exit code 1 2024-03-22 13:50:27.924 UTC [39409][checkpointer] LOG: shutting down 2024-03-22 13:50:27.924 UTC [39409][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:27.924 UTC [39409][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/40D7538, redo lsn=0/40D7538 2024-03-22 13:50:27.925 UTC [39408][postmaster] LOG: database system is shut down 2024-03-22 13:50:28.061 UTC [39420][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:28.062 UTC [39420][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:28.062 UTC [39420][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:28.065 UTC [39423][startup] LOG: database system was shut down at 2024-03-22 13:50:27 UTC 2024-03-22 13:50:28.067 UTC [39420][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:28.160 UTC [39428][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49381 2024-03-22 13:50:28.170 UTC [39428][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-22 13:50:28.170 UTC [39428][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-22 13:50:28.184 UTC [39430][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49382 2024-03-22 13:50:28.192 UTC [39430][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:28.193 UTC [39430][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-22 13:50:28.195 UTC [39430][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-22 13:50:28.195 UTC [39430][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49382 2024-03-22 13:50:28.200 UTC [39420][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:28.200 UTC [39420][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:28.200 UTC [39420][postmaster] LOG: background worker "logical replication launcher" (PID 39426) exited with exit code 1 2024-03-22 13:50:28.201 UTC [39421][checkpointer] LOG: shutting down 2024-03-22 13:50:28.201 UTC [39421][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:28.201 UTC [39421][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/40D75B0, redo lsn=0/40D75B0 2024-03-22 13:50:28.202 UTC [39420][postmaster] LOG: database system is shut down 2024-03-22 13:50:28.329 UTC [39432][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:28.330 UTC [39432][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:28.330 UTC [39432][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:28.333 UTC [39435][startup] LOG: database system was shut down at 2024-03-22 13:50:28 UTC 2024-03-22 13:50:28.335 UTC [39432][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:28.442 UTC [39440][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49383 2024-03-22 13:50:28.451 UTC [39440][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-22 13:50:28.451 UTC [39440][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-22 13:50:28.465 UTC [39442][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49384 2024-03-22 13:50:28.474 UTC [39442][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:28.474 UTC [39442][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-22 13:50:28.477 UTC [39442][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-22 13:50:28.477 UTC [39442][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49384 2024-03-22 13:50:28.482 UTC [39432][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:28.482 UTC [39432][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:28.482 UTC [39432][postmaster] LOG: background worker "logical replication launcher" (PID 39438) exited with exit code 1 2024-03-22 13:50:28.482 UTC [39433][checkpointer] LOG: shutting down 2024-03-22 13:50:28.482 UTC [39433][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:28.483 UTC [39433][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/40D7628, redo lsn=0/40D7628 2024-03-22 13:50:28.484 UTC [39432][postmaster] LOG: database system is shut down 2024-03-22 13:50:28.611 UTC [39444][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-22 13:50:28.612 UTC [39444][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 54525 2024-03-22 13:50:28.612 UTC [39444][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/1ifa5frg34/.s.PGSQL.54525" 2024-03-22 13:50:28.615 UTC [39447][startup] LOG: database system was shut down at 2024-03-22 13:50:28 UTC 2024-03-22 13:50:28.617 UTC [39444][postmaster] LOG: database system is ready to accept connections 2024-03-22 13:50:28.719 UTC [39452][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49385 2024-03-22 13:50:28.730 UTC [39452][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-22 13:50:28.730 UTC [39452][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-22 13:50:28.743 UTC [39454][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49386 2024-03-22 13:50:28.753 UTC [39454][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-22 13:50:28.753 UTC [39454][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-22 13:50:28.756 UTC [39454][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-22 13:50:28.756 UTC [39454][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=49386 2024-03-22 13:50:28.761 UTC [39444][postmaster] LOG: received fast shutdown request 2024-03-22 13:50:28.761 UTC [39444][postmaster] LOG: aborting any active transactions 2024-03-22 13:50:28.761 UTC [39444][postmaster] LOG: background worker "logical replication launcher" (PID 39450) exited with exit code 1 2024-03-22 13:50:28.762 UTC [39445][checkpointer] LOG: shutting down 2024-03-22 13:50:28.762 UTC [39445][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-22 13:50:28.762 UTC [39445][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/40D76A0, redo lsn=0/40D76A0 2024-03-22 13:50:28.763 UTC [39444][postmaster] LOG: database system is shut down 2024-03-22 13:50:28.893 UTC [39456][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-03-22 13:50:28.893 UTC [39456][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [13:50:28.984](0.226s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"