[08:42:48.579](0.031s) # setting up data directory # Checking port 63500 # Found port 63500 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=63500 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK Log file: /Users/admin/pgsql/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [08:42:48.588](0.009s) # 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 43232 [08:42:49.452](0.864s) 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 43593 [08:42:51.813](2.361s) # 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-26 08:42:49.335 UTC [43232][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:49.335 UTC [43232][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:49.424 UTC [43271][startup] LOG: database system was shut down at 2024-03-26 08:39:43 UTC 2024-03-26 08:42:49.433 UTC [43232][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:49.448 UTC [43294][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:49.449 UTC [43294][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-26 08:42:49.449 UTC [43294][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:49.451 UTC [43294][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-26 08:42:49.451 UTC [43294][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-03-26 08:42:49.461 UTC [43303][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:49.462 UTC [43303][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-26 08:42:49.462 UTC [43303][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:49.462 UTC [43303][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-26 08:42:49.462 UTC [43303][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-26 08:42:49.514 UTC [43315][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:49.514 UTC [43315][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-26 08:42:49.514 UTC [43315][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:49.514 UTC [43315][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-26 08:42:49.515 UTC [43315][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.000 user=admin database=postgres host=[local] 2024-03-26 08:42:49.562 UTC [43325][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:49.562 UTC [43325][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-26 08:42:49.562 UTC [43325][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:49.563 UTC [43325][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-26 08:42:49.563 UTC [43325][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-26 08:42:49.616 UTC [43345][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:49.616 UTC [43345][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-26 08:42:49.616 UTC [43345][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:49.617 UTC [43345][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-26 08:42:49.617 UTC [43345][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-26 08:42:49.667 UTC [43353][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:50.136 UTC [43353][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-26 08:42:50.136 UTC [43353][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:50.137 UTC [43353][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-26 08:42:50.188 UTC [43353][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.520 user=admin database=postgres host=[local] 2024-03-26 08:42:50.307 UTC [43397][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:50.307 UTC [43397][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-26 08:42:50.307 UTC [43397][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:50.307 UTC [43397][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-26 08:42:50.355 UTC [43397][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.048 user=admin database=postgres host=[local] 2024-03-26 08:42:50.457 UTC [43415][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:50.457 UTC [43415][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-26 08:42:50.457 UTC [43415][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:50.458 UTC [43415][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-26 08:42:50.534 UTC [43415][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.076 user=admin database=postgres host=[local] 2024-03-26 08:42:50.590 UTC [43441][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:50.590 UTC [43441][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-26 08:42:50.590 UTC [43441][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:50.591 UTC [43441][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-26 08:42:50.678 UTC [43441][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.088 user=admin database=postgres host=[local] 2024-03-26 08:42:50.737 UTC [43465][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:50.737 UTC [43465][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-26 08:42:50.737 UTC [43465][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:50.738 UTC [43465][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-26 08:42:50.781 UTC [43465][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.044 user=admin database=postgres host=[local] 2024-03-26 08:42:50.864 UTC [43493][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:50.865 UTC [43493][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-26 08:42:50.865 UTC [43493][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:50.865 UTC [43493][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-26 08:42:50.915 UTC [43493][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.050 user=admin database=postgres host=[local] 2024-03-26 08:42:51.175 UTC [43232][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:51.175 UTC [43232][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:51.176 UTC [43232][postmaster] LOG: background worker "logical replication launcher" (PID 43287) exited with exit code 1 2024-03-26 08:42:51.177 UTC [43261][checkpointer] LOG: shutting down 2024-03-26 08:42:51.177 UTC [43261][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:51.659 UTC [43261][checkpointer] LOG: checkpoint complete: wrote 5572 buffers (34.0%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.482 s, sync=0.001 s, total=0.483 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44852 kB, estimate=44852 kB; lsn=0/40D53E0, redo lsn=0/40D53E0 2024-03-26 08:42:51.669 UTC [43232][postmaster] LOG: database system is shut down 2024-03-26 08:42:51.763 UTC [43593][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:51.763 UTC [43593][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:51.763 UTC [43593][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:51.766 UTC [43596][startup] LOG: database system was shut down at 2024-03-26 08:42:51 UTC 2024-03-26 08:42:51.768 UTC [43593][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:51.817 UTC [43593][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:51.817 UTC [43593][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:51.817 UTC [43593][postmaster] LOG: background worker "logical replication launcher" (PID 43599) exited with exit code 1 2024-03-26 08:42:51.818 UTC [43594][checkpointer] LOG: shutting down 2024-03-26 08:42:51.818 UTC [43594][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:51.818 UTC [43594][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/40D5458, redo lsn=0/40D5458 2024-03-26 08:42:51.820 UTC [43593][postmaster] LOG: database system is shut down 2024-03-26 08:42:51.945 UTC [43609][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-26 08:42:51.999 UTC [43609][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [08:42:52.040](0.227s) 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 43622 [08:42:52.155](0.115s) 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-26 08:42:49.335 UTC [43232][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:49.335 UTC [43232][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:49.424 UTC [43271][startup] LOG: database system was shut down at 2024-03-26 08:39:43 UTC 2024-03-26 08:42:49.433 UTC [43232][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:49.448 UTC [43294][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:49.449 UTC [43294][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-26 08:42:49.449 UTC [43294][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:49.451 UTC [43294][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-26 08:42:49.451 UTC [43294][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-03-26 08:42:49.461 UTC [43303][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:49.462 UTC [43303][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-26 08:42:49.462 UTC [43303][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:49.462 UTC [43303][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-26 08:42:49.462 UTC [43303][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-26 08:42:49.514 UTC [43315][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:49.514 UTC [43315][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-26 08:42:49.514 UTC [43315][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:49.514 UTC [43315][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-26 08:42:49.515 UTC [43315][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.000 user=admin database=postgres host=[local] 2024-03-26 08:42:49.562 UTC [43325][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:49.562 UTC [43325][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-26 08:42:49.562 UTC [43325][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:49.563 UTC [43325][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-26 08:42:49.563 UTC [43325][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-26 08:42:49.616 UTC [43345][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:49.616 UTC [43345][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-26 08:42:49.616 UTC [43345][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:49.617 UTC [43345][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-26 08:42:49.617 UTC [43345][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-26 08:42:49.667 UTC [43353][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:50.136 UTC [43353][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-26 08:42:50.136 UTC [43353][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:50.137 UTC [43353][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-26 08:42:50.188 UTC [43353][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.520 user=admin database=postgres host=[local] 2024-03-26 08:42:50.307 UTC [43397][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:50.307 UTC [43397][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-26 08:42:50.307 UTC [43397][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:50.307 UTC [43397][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-26 08:42:50.355 UTC [43397][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.048 user=admin database=postgres host=[local] 2024-03-26 08:42:50.457 UTC [43415][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:50.457 UTC [43415][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-26 08:42:50.457 UTC [43415][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:50.458 UTC [43415][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-26 08:42:50.534 UTC [43415][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.076 user=admin database=postgres host=[local] 2024-03-26 08:42:50.590 UTC [43441][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:50.590 UTC [43441][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-26 08:42:50.590 UTC [43441][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:50.591 UTC [43441][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-26 08:42:50.678 UTC [43441][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.088 user=admin database=postgres host=[local] 2024-03-26 08:42:50.737 UTC [43465][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:50.737 UTC [43465][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-26 08:42:50.737 UTC [43465][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:50.738 UTC [43465][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-26 08:42:50.781 UTC [43465][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.044 user=admin database=postgres host=[local] 2024-03-26 08:42:50.864 UTC [43493][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:50.865 UTC [43493][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-26 08:42:50.865 UTC [43493][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:50.865 UTC [43493][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-26 08:42:50.915 UTC [43493][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.050 user=admin database=postgres host=[local] 2024-03-26 08:42:51.175 UTC [43232][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:51.175 UTC [43232][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:51.176 UTC [43232][postmaster] LOG: background worker "logical replication launcher" (PID 43287) exited with exit code 1 2024-03-26 08:42:51.177 UTC [43261][checkpointer] LOG: shutting down 2024-03-26 08:42:51.177 UTC [43261][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:51.659 UTC [43261][checkpointer] LOG: checkpoint complete: wrote 5572 buffers (34.0%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.482 s, sync=0.001 s, total=0.483 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44852 kB, estimate=44852 kB; lsn=0/40D53E0, redo lsn=0/40D53E0 2024-03-26 08:42:51.669 UTC [43232][postmaster] LOG: database system is shut down 2024-03-26 08:42:51.763 UTC [43593][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:51.763 UTC [43593][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:51.763 UTC [43593][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:51.766 UTC [43596][startup] LOG: database system was shut down at 2024-03-26 08:42:51 UTC 2024-03-26 08:42:51.768 UTC [43593][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:51.817 UTC [43593][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:51.817 UTC [43593][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:51.817 UTC [43593][postmaster] LOG: background worker "logical replication launcher" (PID 43599) exited with exit code 1 2024-03-26 08:42:51.818 UTC [43594][checkpointer] LOG: shutting down 2024-03-26 08:42:51.818 UTC [43594][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:51.818 UTC [43594][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/40D5458, redo lsn=0/40D5458 2024-03-26 08:42:51.820 UTC [43593][postmaster] LOG: database system is shut down 2024-03-26 08:42:51.945 UTC [43609][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-26 08:42:51.999 UTC [43609][postmaster] LOG: database system is shut down 2024-03-26 08:42:52.061 UTC [43622][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:52.061 UTC [43622][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:52.061 UTC [43622][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:52.064 UTC [43627][startup] LOG: database system was shut down at 2024-03-26 08:42:51 UTC 2024-03-26 08:42:52.065 UTC [43622][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:52.158 UTC [43622][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:52.159 UTC [43622][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:52.159 UTC [43622][postmaster] LOG: background worker "logical replication launcher" (PID 43630) exited with exit code 1 2024-03-26 08:42:52.159 UTC [43625][checkpointer] LOG: shutting down 2024-03-26 08:42:52.159 UTC [43625][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:52.160 UTC [43625][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/40D54D0, redo lsn=0/40D54D0 2024-03-26 08:42:52.161 UTC [43622][postmaster] LOG: database system is shut down 2024-03-26 08:42:52.290 UTC [43649][postmaster] FATAL: could not set SSL protocol version range 2024-03-26 08:42:52.290 UTC [43649][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-26 08:42:52.316 UTC [43649][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [08:42:52.381](0.226s) 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 43657 [08:42:52.556](0.176s) ok 5 - restart succeeds with correct SSL protocol bounds [08:42:52.557](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 43707 [08:42:52.787](0.231s) ok 6 - server doesn't accept non-SSL connections [08:42:52.787](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [08:42:52.812](0.024s) ok 8 - connect without server root cert sslmode=require [08:42:52.812](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [08:42:52.827](0.015s) ok 10 - connect without server root cert sslmode=verify-ca [08:42:52.827](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [08:42:52.841](0.014s) ok 12 - connect without server root cert sslmode=verify-full [08:42:52.841](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [08:42:52.860](0.019s) ok 14 - connect with wrong server root cert sslmode=require [08:42:52.860](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [08:42:52.882](0.022s) ok 16 - connect with wrong server root cert sslmode=verify-ca [08:42:52.882](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [08:42:52.905](0.023s) ok 18 - connect with wrong server root cert sslmode=verify-full [08:42:52.905](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [08:42:53.022](0.117s) ok 20 - connect with server CA cert, without root CA [08:42:53.022](0.000s) ok 21 - connect with server CA cert, without root CA: matches [08:42:53.052](0.030s) ok 22 - connect with correct server CA cert file sslmode=require [08:42:53.052](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [08:42:53.077](0.025s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [08:42:53.077](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [08:42:53.097](0.020s) ok 26 - connect with correct server CA cert file sslmode=verify-full [08:42:53.097](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [08:42:53.118](0.021s) ok 28 - cert root file that contains two certificates, order 1 [08:42:53.118](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [08:42:53.144](0.026s) ok 30 - cert root file that contains two certificates, order 2 [08:42:53.144](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [08:42:53.162](0.018s) ok 32 - connect with sslcertmode=disable [08:42:53.162](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [08:42:53.181](0.018s) ok 34 - connect with sslcertmode=allow [08:42:53.181](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [08:42:53.198](0.017s) ok 36 - connect with sslcertmode=require fails without a client certificate [08:42:53.198](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [08:42:53.217](0.018s) ok 38 - sslcrl option with invalid file name [08:42:53.217](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [08:42:53.235](0.018s) ok 40 - CRL belonging to a different CA [08:42:53.235](0.000s) ok 41 - CRL belonging to a different CA: matches [08:42:53.252](0.017s) ok 42 - directory CRL belonging to a different CA [08:42:53.252](0.000s) ok 43 - directory CRL belonging to a different CA: matches [08:42:53.271](0.019s) ok 44 - CRL with a non-revoked cert [08:42:53.271](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [08:42:53.290](0.019s) ok 46 - directory CRL with a non-revoked cert [08:42:53.290](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [08:42:53.309](0.019s) ok 48 - mismatch between host name and server certificate sslmode=require [08:42:53.309](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [08:42:53.327](0.018s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [08:42:53.327](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [08:42:53.344](0.017s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [08:42:53.344](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 43838 [08:42:53.585](0.241s) ok 54 - IP address in the Common Name [08:42:53.585](0.000s) ok 55 - IP address in the Common Name: no stderr [08:42:53.602](0.016s) ok 56 - mismatch between host name and server certificate IP address [08:42:53.602](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 43850 [08:42:53.862](0.260s) ok 58 - IP address in a dNSName [08:42:53.862](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 43860 [08:42:54.237](0.375s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [08:42:54.237](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [08:42:54.256](0.019s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [08:42:54.256](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [08:42:54.274](0.018s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [08:42:54.274](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [08:42:54.291](0.017s) ok 66 - host name not matching with X.509 Subject Alternative Names [08:42:54.292](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [08:42:54.308](0.017s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [08:42:54.308](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 43878 [08:42:54.557](0.249s) ok 70 - host name matching with a single X.509 Subject Alternative Name [08:42:54.557](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [08:42:54.576](0.019s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [08:42:54.576](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [08:42:54.595](0.019s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [08:42:54.595](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 43893 [08:42:54.850](0.255s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [08:42:54.850](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [08:42:54.869](0.019s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [08:42:54.869](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [08:42:54.886](0.017s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [08:42:54.886](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [08:42:54.904](0.018s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [08:42:54.904](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [08:42:54.923](0.019s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [08:42:54.923](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [08:42:54.941](0.018s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [08:42:54.941](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [08:42:54.958](0.016s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [08:42:54.958](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [08:42:54.973](0.016s) ok 90 - IPv6 host with CIDR mask does not match [08:42:54.974](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 43917 [08:42:55.229](0.255s) ok 92 - certificate with both a CN and SANs 1 [08:42:55.229](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [08:42:55.248](0.019s) ok 94 - certificate with both a CN and SANs 2 [08:42:55.248](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [08:42:55.265](0.017s) ok 96 - certificate with both a CN and SANs ignores CN [08:42:55.265](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 43932 [08:42:55.521](0.256s) ok 98 - certificate with both a CN and IP SANs matches CN [08:42:55.521](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [08:42:55.544](0.023s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [08:42:55.544](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [08:42:55.567](0.023s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [08:42:55.567](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 43946 [08:42:55.817](0.250s) ok 104 - certificate with both an IP CN and IP SANs 1 [08:42:55.817](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [08:42:55.836](0.018s) ok 106 - certificate with both an IP CN and IP SANs 2 [08:42:55.836](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [08:42:55.854](0.018s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [08:42:55.854](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 43960 [08:42:56.087](0.233s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [08:42:56.087](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [08:42:56.104](0.017s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [08:42:56.104](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [08:42:56.121](0.017s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [08:42:56.121](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 43974 [08:42:56.397](0.276s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [08:42:56.398](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [08:42:56.419](0.021s) ok 118 - server certificate without CN or SANs sslmode=verify-full [08:42:56.419](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 43986 [08:42:56.683](0.265s) ok 120 - sslrootcert=system does not connect with private CA [08:42:56.684](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [08:42:56.695](0.011s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [08:42:56.695](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [08:42:56.716](0.021s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [08:42:56.716](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [08:42:56.733](0.016s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [08:42:56.733](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 44001 [08:42:57.007](0.274s) ok 128 - connects without client-side CRL [08:42:57.007](0.000s) ok 129 - connects without client-side CRL: no stderr [08:42:57.027](0.020s) ok 130 - does not connect with client-side CRL file [08:42:57.027](0.000s) ok 131 - does not connect with client-side CRL file: matches [08:42:57.044](0.017s) ok 132 - does not connect with client-side CRL directory [08:42:57.044](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() [08:42:57.061](0.017s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [08:42:57.062](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [08:42:57.062](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [08:42:57.078](0.017s) ok 137 - connection success with correct range of TLS protocol versions [08:42:57.078](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [08:42:57.086](0.008s) ok 139 - connection failure with incorrect range of TLS protocol versions [08:42:57.086](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [08:42:57.093](0.007s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [08:42:57.094](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [08:42:57.101](0.007s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [08:42:57.101](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [08:42:57.101](0.000s) # running server tests [08:42:57.117](0.016s) ok 145 - certificate authorization fails without client cert [08:42:57.117](0.000s) ok 146 - certificate authorization fails without client cert: matches [08:42:57.137](0.020s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [08:42:57.137](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [08:42:57.155](0.018s) ok 149 - certificate authorization succeeds with correct client cert in DER format [08:42:57.155](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [08:42:57.174](0.019s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [08:42:57.174](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [08:42:57.192](0.018s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [08:42:57.192](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [08:42:57.210](0.018s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [08:42:57.210](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [08:42:57.228](0.018s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [08:42:57.228](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [08:42:57.244](0.016s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [08:42:57.244](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [08:42:57.259](0.015s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [08:42:57.260](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [08:42:57.280](0.020s) ok 163 - certificate authorization succeeds with DN mapping [08:42:57.280](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [08:42:57.280](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [08:42:57.300](0.020s) ok 166 - certificate authorization succeeds with DN regex mapping [08:42:57.300](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [08:42:57.320](0.020s) ok 168 - certificate authorization succeeds with CN mapping [08:42:57.320](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [08:42:57.320](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [08:42:57.320](0.000s) not ok 171 # TODO & SKIP Need Pty support [08:42:57.320](0.000s) not ok 172 # TODO & SKIP Need Pty support [08:42:57.320](0.000s) not ok 173 # TODO & SKIP Need Pty support [08:42:57.320](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_775O/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [08:42:57.348](0.028s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [08:42:57.349](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [08:42:57.349](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [08:42:57.364](0.015s) ok 178 - certificate authorization fails because of file permissions [08:42:57.364](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [08:42:57.382](0.018s) ok 180 - certificate authorization fails with client cert belonging to another user [08:42:57.382](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [08:42:57.405](0.023s) ok 182 - certificate authorization fails with revoked client cert [08:42:57.405](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [08:42:57.405](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [08:42:57.425](0.020s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [08:42:57.425](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [08:42:57.426](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [08:42:57.444](0.018s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [08:42:57.444](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [08:42:57.444](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [08:42:57.463](0.019s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [08:42:57.463](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [08:42:57.463](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 44061 [08:42:57.712](0.249s) ok 194 - intermediate client certificate is provided by client [08:42:57.712](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [08:42:57.731](0.019s) ok 196 - intermediate client certificate is missing [08:42:57.731](0.000s) ok 197 - intermediate client certificate is missing: matches [08:42:57.749](0.018s) ok 198 - logged client certificate Subjects are truncated if they're too long [08:42:57.749](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 44075 [08:42:57.997](0.248s) ok 200 - intermediate client certificate is untrusted [08:42:57.997](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 44085 [08:42:58.269](0.272s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [08:42:58.271](0.001s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [08:42:58.294](0.024s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [08:42:58.294](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 44097 [08:42:58.564](0.270s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [08:42:58.564](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. [08:42:58.564](0.000s) # got: '2' # expected: '0' [08:42:58.564](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [08:42:58.564](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. [08:42:58.565](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 63500 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: '' [08:42:58.590](0.025s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [08:42:58.590](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 44109 [08:42:58.834](0.244s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [08:42:58.855](0.021s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [08:42:58.855](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 44121 [08:42:59.120](0.265s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [08:42:59.145](0.025s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [08:42:59.145](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 44133 [08:42:59.407](0.262s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [08:42:59.427](0.020s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [08:42:59.427](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 44146 [08:42:59.683](0.256s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [08:42:59.683](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. [08:42:59.683](0.000s) # got: '2' # expected: '0' [08:42:59.684](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [08:42:59.684](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. [08:42:59.684](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 63500 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: '' [08:42:59.708](0.024s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [08:42:59.708](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 44158 [08:42:59.956](0.248s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [08:42:59.983](0.027s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [08:42:59.983](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 44170 [08:43:00.246](0.262s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [08:43:00.272](0.027s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [08:43:00.273](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 44182 [08:43:00.524](0.252s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [08:43:00.548](0.024s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [08:43:00.548](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 44194 [08:43:00.797](0.249s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [08:43:00.821](0.024s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [08:43:00.822](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-26 08:42:49.335 UTC [43232][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:49.335 UTC [43232][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:49.424 UTC [43271][startup] LOG: database system was shut down at 2024-03-26 08:39:43 UTC 2024-03-26 08:42:49.433 UTC [43232][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:49.448 UTC [43294][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:49.449 UTC [43294][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-26 08:42:49.449 UTC [43294][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:49.451 UTC [43294][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-26 08:42:49.451 UTC [43294][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-03-26 08:42:49.461 UTC [43303][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:49.462 UTC [43303][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-26 08:42:49.462 UTC [43303][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:49.462 UTC [43303][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-26 08:42:49.462 UTC [43303][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-26 08:42:49.514 UTC [43315][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:49.514 UTC [43315][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-26 08:42:49.514 UTC [43315][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:49.514 UTC [43315][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-26 08:42:49.515 UTC [43315][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.000 user=admin database=postgres host=[local] 2024-03-26 08:42:49.562 UTC [43325][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:49.562 UTC [43325][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-26 08:42:49.562 UTC [43325][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:49.563 UTC [43325][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-26 08:42:49.563 UTC [43325][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-26 08:42:49.616 UTC [43345][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:49.616 UTC [43345][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-26 08:42:49.616 UTC [43345][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:49.617 UTC [43345][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-26 08:42:49.617 UTC [43345][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-26 08:42:49.667 UTC [43353][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:50.136 UTC [43353][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-26 08:42:50.136 UTC [43353][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:50.137 UTC [43353][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-26 08:42:50.188 UTC [43353][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.520 user=admin database=postgres host=[local] 2024-03-26 08:42:50.307 UTC [43397][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:50.307 UTC [43397][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-26 08:42:50.307 UTC [43397][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:50.307 UTC [43397][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-26 08:42:50.355 UTC [43397][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.048 user=admin database=postgres host=[local] 2024-03-26 08:42:50.457 UTC [43415][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:50.457 UTC [43415][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-26 08:42:50.457 UTC [43415][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:50.458 UTC [43415][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-26 08:42:50.534 UTC [43415][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.076 user=admin database=postgres host=[local] 2024-03-26 08:42:50.590 UTC [43441][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:50.590 UTC [43441][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-26 08:42:50.590 UTC [43441][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:50.591 UTC [43441][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-26 08:42:50.678 UTC [43441][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.088 user=admin database=postgres host=[local] 2024-03-26 08:42:50.737 UTC [43465][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:50.737 UTC [43465][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-26 08:42:50.737 UTC [43465][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:50.738 UTC [43465][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-26 08:42:50.781 UTC [43465][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.044 user=admin database=postgres host=[local] 2024-03-26 08:42:50.864 UTC [43493][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-26 08:42:50.865 UTC [43493][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-26 08:42:50.865 UTC [43493][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-26 08:42:50.865 UTC [43493][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-26 08:42:50.915 UTC [43493][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.050 user=admin database=postgres host=[local] 2024-03-26 08:42:51.175 UTC [43232][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:51.175 UTC [43232][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:51.176 UTC [43232][postmaster] LOG: background worker "logical replication launcher" (PID 43287) exited with exit code 1 2024-03-26 08:42:51.177 UTC [43261][checkpointer] LOG: shutting down 2024-03-26 08:42:51.177 UTC [43261][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:51.659 UTC [43261][checkpointer] LOG: checkpoint complete: wrote 5572 buffers (34.0%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.482 s, sync=0.001 s, total=0.483 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44852 kB, estimate=44852 kB; lsn=0/40D53E0, redo lsn=0/40D53E0 2024-03-26 08:42:51.669 UTC [43232][postmaster] LOG: database system is shut down 2024-03-26 08:42:51.763 UTC [43593][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:51.763 UTC [43593][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:51.763 UTC [43593][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:51.766 UTC [43596][startup] LOG: database system was shut down at 2024-03-26 08:42:51 UTC 2024-03-26 08:42:51.768 UTC [43593][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:51.817 UTC [43593][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:51.817 UTC [43593][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:51.817 UTC [43593][postmaster] LOG: background worker "logical replication launcher" (PID 43599) exited with exit code 1 2024-03-26 08:42:51.818 UTC [43594][checkpointer] LOG: shutting down 2024-03-26 08:42:51.818 UTC [43594][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:51.818 UTC [43594][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/40D5458, redo lsn=0/40D5458 2024-03-26 08:42:51.820 UTC [43593][postmaster] LOG: database system is shut down 2024-03-26 08:42:51.945 UTC [43609][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-26 08:42:51.999 UTC [43609][postmaster] LOG: database system is shut down 2024-03-26 08:42:52.061 UTC [43622][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:52.061 UTC [43622][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:52.061 UTC [43622][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:52.064 UTC [43627][startup] LOG: database system was shut down at 2024-03-26 08:42:51 UTC 2024-03-26 08:42:52.065 UTC [43622][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:52.158 UTC [43622][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:52.159 UTC [43622][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:52.159 UTC [43622][postmaster] LOG: background worker "logical replication launcher" (PID 43630) exited with exit code 1 2024-03-26 08:42:52.159 UTC [43625][checkpointer] LOG: shutting down 2024-03-26 08:42:52.159 UTC [43625][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:52.160 UTC [43625][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/40D54D0, redo lsn=0/40D54D0 2024-03-26 08:42:52.161 UTC [43622][postmaster] LOG: database system is shut down 2024-03-26 08:42:52.290 UTC [43649][postmaster] FATAL: could not set SSL protocol version range 2024-03-26 08:42:52.290 UTC [43649][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-26 08:42:52.316 UTC [43649][postmaster] LOG: database system is shut down 2024-03-26 08:42:52.473 UTC [43657][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:52.474 UTC [43657][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:52.474 UTC [43657][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:52.491 UTC [43675][startup] LOG: database system was shut down at 2024-03-26 08:42:52 UTC 2024-03-26 08:42:52.494 UTC [43657][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:52.560 UTC [43657][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:52.560 UTC [43657][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:52.561 UTC [43657][postmaster] LOG: background worker "logical replication launcher" (PID 43678) exited with exit code 1 2024-03-26 08:42:52.562 UTC [43673][checkpointer] LOG: shutting down 2024-03-26 08:42:52.562 UTC [43673][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:52.562 UTC [43673][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/40D5548, redo lsn=0/40D5548 2024-03-26 08:42:52.563 UTC [43657][postmaster] LOG: database system is shut down 2024-03-26 08:42:52.675 UTC [43707][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:52.676 UTC [43707][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:52.676 UTC [43707][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:52.678 UTC [43711][startup] LOG: database system was shut down at 2024-03-26 08:42:52 UTC 2024-03-26 08:42:52.679 UTC [43707][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:52.782 UTC [43733][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49257 2024-03-26 08:42:52.786 UTC [43733][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-26 08:42:52.786 UTC [43733][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-03-26 08:42:52.796 UTC [43736][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49259 2024-03-26 08:42:52.805 UTC [43736][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-26 08:42:52.805 UTC [43736][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-26 08:42:52.810 UTC [43736][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-26 08:42:52.810 UTC [43736][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=49259 2024-03-26 08:42:52.821 UTC [43740][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49261 2024-03-26 08:42:52.826 UTC [43740][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-26 08:42:52.835 UTC [43744][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49263 2024-03-26 08:42:52.840 UTC [43744][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-26 08:42:52.851 UTC [43747][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49264 2024-03-26 08:42:52.859 UTC [43747][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-26 08:42:52.871 UTC [43752][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49266 2024-03-26 08:42:52.881 UTC [43752][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-26 08:42:52.896 UTC [43759][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49269 2024-03-26 08:42:52.904 UTC [43759][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-26 08:42:52.915 UTC [43768][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49271 2024-03-26 08:42:53.020 UTC [43768][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-26 08:42:53.037 UTC [43774][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49274 2024-03-26 08:42:53.049 UTC [43774][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-26 08:42:53.049 UTC [43774][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-26 08:42:53.050 UTC [43774][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-26 08:42:53.051 UTC [43774][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=49274 2024-03-26 08:42:53.067 UTC [43778][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49275 2024-03-26 08:42:53.075 UTC [43778][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-26 08:42:53.075 UTC [43778][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-26 08:42:53.076 UTC [43778][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-26 08:42:53.076 UTC [43778][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49275 2024-03-26 08:42:53.088 UTC [43781][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49277 2024-03-26 08:42:53.096 UTC [43781][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-26 08:42:53.096 UTC [43781][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-26 08:42:53.096 UTC [43781][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-26 08:42:53.096 UTC [43781][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49277 2024-03-26 08:42:53.107 UTC [43783][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49278 2024-03-26 08:42:53.116 UTC [43783][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-26 08:42:53.116 UTC [43783][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-26 08:42:53.116 UTC [43783][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-26 08:42:53.117 UTC [43783][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49278 2024-03-26 08:42:53.134 UTC [43787][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49280 2024-03-26 08:42:53.142 UTC [43787][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-26 08:42:53.142 UTC [43787][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-26 08:42:53.143 UTC [43787][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-26 08:42:53.143 UTC [43787][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49280 2024-03-26 08:42:53.153 UTC [43791][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49282 2024-03-26 08:42:53.161 UTC [43791][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-26 08:42:53.161 UTC [43791][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-26 08:42:53.161 UTC [43791][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-26 08:42:53.161 UTC [43791][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49282 2024-03-26 08:42:53.171 UTC [43796][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49284 2024-03-26 08:42:53.179 UTC [43796][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-26 08:42:53.179 UTC [43796][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-26 08:42:53.180 UTC [43796][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-26 08:42:53.180 UTC [43796][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49284 2024-03-26 08:42:53.189 UTC [43806][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49286 2024-03-26 08:42:53.197 UTC [43806][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-26 08:42:53.197 UTC [43806][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-26 08:42:53.197 UTC [43806][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49286 2024-03-26 08:42:53.207 UTC [43809][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49287 2024-03-26 08:42:53.215 UTC [43809][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-26 08:42:53.215 UTC [43809][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-26 08:42:53.216 UTC [43809][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-26 08:42:53.216 UTC [43809][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49287 2024-03-26 08:42:53.226 UTC [43813][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49289 2024-03-26 08:42:53.234 UTC [43813][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-26 08:42:53.244 UTC [43817][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49291 2024-03-26 08:42:53.251 UTC [43817][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-26 08:42:53.261 UTC [43821][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49293 2024-03-26 08:42:53.269 UTC [43821][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-26 08:42:53.269 UTC [43821][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-26 08:42:53.270 UTC [43821][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-26 08:42:53.270 UTC [43821][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49293 2024-03-26 08:42:53.280 UTC [43827][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49295 2024-03-26 08:42:53.288 UTC [43827][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-26 08:42:53.288 UTC [43827][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-26 08:42:53.289 UTC [43827][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-26 08:42:53.289 UTC [43827][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49295 2024-03-26 08:42:53.300 UTC [43832][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49298 2024-03-26 08:42:53.308 UTC [43832][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-26 08:42:53.308 UTC [43832][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-26 08:42:53.308 UTC [43832][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-26 08:42:53.308 UTC [43832][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49298 2024-03-26 08:42:53.318 UTC [43834][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49299 2024-03-26 08:42:53.326 UTC [43834][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-26 08:42:53.326 UTC [43834][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-26 08:42:53.326 UTC [43834][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-26 08:42:53.326 UTC [43834][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49299 2024-03-26 08:42:53.336 UTC [43836][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49300 2024-03-26 08:42:53.348 UTC [43707][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:53.348 UTC [43707][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:53.349 UTC [43707][postmaster] LOG: background worker "logical replication launcher" (PID 43714) exited with exit code 1 2024-03-26 08:42:53.349 UTC [43709][checkpointer] LOG: shutting down 2024-03-26 08:42:53.349 UTC [43709][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:53.349 UTC [43709][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/40D55C0, redo lsn=0/40D55C0 2024-03-26 08:42:53.350 UTC [43707][postmaster] LOG: database system is shut down 2024-03-26 08:42:53.469 UTC [43838][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:53.469 UTC [43838][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:53.469 UTC [43838][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:53.473 UTC [43841][startup] LOG: database system was shut down at 2024-03-26 08:42:53 UTC 2024-03-26 08:42:53.474 UTC [43838][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:53.574 UTC [43846][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49301 2024-03-26 08:42:53.582 UTC [43846][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-26 08:42:53.582 UTC [43846][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-26 08:42:53.584 UTC [43846][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-26 08:42:53.584 UTC [43846][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49301 2024-03-26 08:42:53.594 UTC [43848][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49302 2024-03-26 08:42:53.605 UTC [43838][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:53.605 UTC [43838][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:53.605 UTC [43838][postmaster] LOG: background worker "logical replication launcher" (PID 43844) exited with exit code 1 2024-03-26 08:42:53.605 UTC [43839][checkpointer] LOG: shutting down 2024-03-26 08:42:53.605 UTC [43839][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:53.606 UTC [43839][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/40D5638, redo lsn=0/40D5638 2024-03-26 08:42:53.607 UTC [43838][postmaster] LOG: database system is shut down 2024-03-26 08:42:53.738 UTC [43850][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:53.739 UTC [43850][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:53.739 UTC [43850][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:53.748 UTC [43853][startup] LOG: database system was shut down at 2024-03-26 08:42:53 UTC 2024-03-26 08:42:53.749 UTC [43850][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:53.847 UTC [43858][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49303 2024-03-26 08:42:53.858 UTC [43858][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-26 08:42:53.858 UTC [43858][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-26 08:42:53.860 UTC [43858][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-26 08:42:53.861 UTC [43858][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=49303 2024-03-26 08:42:53.865 UTC [43850][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:53.865 UTC [43850][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:53.866 UTC [43850][postmaster] LOG: background worker "logical replication launcher" (PID 43856) exited with exit code 1 2024-03-26 08:42:53.866 UTC [43851][checkpointer] LOG: shutting down 2024-03-26 08:42:53.866 UTC [43851][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:53.867 UTC [43851][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/40D56B0, redo lsn=0/40D56B0 2024-03-26 08:42:53.868 UTC [43850][postmaster] LOG: database system is shut down 2024-03-26 08:42:54.027 UTC [43860][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:54.028 UTC [43860][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:54.144 UTC [43860][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:54.176 UTC [43863][startup] LOG: database system was shut down at 2024-03-26 08:42:53 UTC 2024-03-26 08:42:54.178 UTC [43860][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:54.225 UTC [43868][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49304 2024-03-26 08:42:54.233 UTC [43868][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-26 08:42:54.233 UTC [43868][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-26 08:42:54.235 UTC [43868][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-26 08:42:54.236 UTC [43868][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49304 2024-03-26 08:42:54.246 UTC [43870][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49305 2024-03-26 08:42:54.254 UTC [43870][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-26 08:42:54.254 UTC [43870][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-26 08:42:54.255 UTC [43870][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-26 08:42:54.255 UTC [43870][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49305 2024-03-26 08:42:54.265 UTC [43872][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49306 2024-03-26 08:42:54.273 UTC [43872][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-26 08:42:54.273 UTC [43872][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-26 08:42:54.273 UTC [43872][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-26 08:42:54.273 UTC [43872][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49306 2024-03-26 08:42:54.283 UTC [43874][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49307 2024-03-26 08:42:54.300 UTC [43876][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49308 2024-03-26 08:42:54.312 UTC [43860][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:54.312 UTC [43860][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:54.312 UTC [43860][postmaster] LOG: background worker "logical replication launcher" (PID 43866) exited with exit code 1 2024-03-26 08:42:54.312 UTC [43861][checkpointer] LOG: shutting down 2024-03-26 08:42:54.312 UTC [43861][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:54.313 UTC [43861][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/40D5728, redo lsn=0/40D5728 2024-03-26 08:42:54.314 UTC [43860][postmaster] LOG: database system is shut down 2024-03-26 08:42:54.439 UTC [43878][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:54.439 UTC [43878][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:54.439 UTC [43878][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:54.442 UTC [43881][startup] LOG: database system was shut down at 2024-03-26 08:42:54 UTC 2024-03-26 08:42:54.445 UTC [43878][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:54.544 UTC [43886][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49309 2024-03-26 08:42:54.553 UTC [43886][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-26 08:42:54.553 UTC [43886][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-26 08:42:54.556 UTC [43886][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-26 08:42:54.556 UTC [43886][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49309 2024-03-26 08:42:54.567 UTC [43888][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49310 2024-03-26 08:42:54.587 UTC [43890][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49311 2024-03-26 08:42:54.602 UTC [43878][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:54.602 UTC [43878][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:54.603 UTC [43878][postmaster] LOG: background worker "logical replication launcher" (PID 43884) exited with exit code 1 2024-03-26 08:42:54.604 UTC [43879][checkpointer] LOG: shutting down 2024-03-26 08:42:54.604 UTC [43879][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:54.605 UTC [43879][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/40D57A0, redo lsn=0/40D57A0 2024-03-26 08:42:54.606 UTC [43878][postmaster] LOG: database system is shut down 2024-03-26 08:42:54.728 UTC [43893][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:54.728 UTC [43893][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:54.728 UTC [43893][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:54.731 UTC [43896][startup] LOG: database system was shut down at 2024-03-26 08:42:54 UTC 2024-03-26 08:42:54.732 UTC [43893][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:54.836 UTC [43901][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49312 2024-03-26 08:42:54.846 UTC [43901][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-26 08:42:54.846 UTC [43901][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-26 08:42:54.849 UTC [43901][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-26 08:42:54.849 UTC [43901][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=49312 2024-03-26 08:42:54.860 UTC [43903][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49313 2024-03-26 08:42:54.868 UTC [43903][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-26 08:42:54.868 UTC [43903][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-26 08:42:54.868 UTC [43903][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-26 08:42:54.868 UTC [43903][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49313 2024-03-26 08:42:54.878 UTC [43905][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49314 2024-03-26 08:42:54.895 UTC [43907][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49315 2024-03-26 08:42:54.903 UTC [43907][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-26 08:42:54.903 UTC [43907][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-26 08:42:54.903 UTC [43907][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-26 08:42:54.904 UTC [43907][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49315 2024-03-26 08:42:54.913 UTC [43909][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49316 2024-03-26 08:42:54.921 UTC [43909][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-26 08:42:54.921 UTC [43909][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-26 08:42:54.922 UTC [43909][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-26 08:42:54.922 UTC [43909][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49316 2024-03-26 08:42:54.932 UTC [43911][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49317 2024-03-26 08:42:54.940 UTC [43911][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-26 08:42:54.940 UTC [43911][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-26 08:42:54.940 UTC [43911][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-26 08:42:54.940 UTC [43911][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49317 2024-03-26 08:42:54.950 UTC [43913][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49318 2024-03-26 08:42:54.966 UTC [43915][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49319 2024-03-26 08:42:54.977 UTC [43893][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:54.977 UTC [43893][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:54.977 UTC [43893][postmaster] LOG: background worker "logical replication launcher" (PID 43899) exited with exit code 1 2024-03-26 08:42:54.977 UTC [43894][checkpointer] LOG: shutting down 2024-03-26 08:42:54.977 UTC [43894][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:54.978 UTC [43894][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/40D5818, redo lsn=0/40D5818 2024-03-26 08:42:54.979 UTC [43893][postmaster] LOG: database system is shut down 2024-03-26 08:42:55.111 UTC [43917][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:55.111 UTC [43917][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:55.116 UTC [43917][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:55.119 UTC [43920][startup] LOG: database system was shut down at 2024-03-26 08:42:54 UTC 2024-03-26 08:42:55.121 UTC [43917][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:55.210 UTC [43925][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49320 2024-03-26 08:42:55.218 UTC [43925][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-26 08:42:55.225 UTC [43925][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-26 08:42:55.228 UTC [43925][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-26 08:42:55.228 UTC [43925][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49320 2024-03-26 08:42:55.238 UTC [43927][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49321 2024-03-26 08:42:55.246 UTC [43927][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-26 08:42:55.246 UTC [43927][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-26 08:42:55.247 UTC [43927][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-26 08:42:55.247 UTC [43927][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49321 2024-03-26 08:42:55.257 UTC [43929][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49322 2024-03-26 08:42:55.272 UTC [43917][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:55.272 UTC [43917][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:55.273 UTC [43917][postmaster] LOG: background worker "logical replication launcher" (PID 43923) exited with exit code 1 2024-03-26 08:42:55.273 UTC [43918][checkpointer] LOG: shutting down 2024-03-26 08:42:55.273 UTC [43918][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:55.274 UTC [43918][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/40D5890, redo lsn=0/40D5890 2024-03-26 08:42:55.274 UTC [43917][postmaster] LOG: database system is shut down 2024-03-26 08:42:55.399 UTC [43932][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:55.399 UTC [43932][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:55.400 UTC [43932][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:55.402 UTC [43935][startup] LOG: database system was shut down at 2024-03-26 08:42:55 UTC 2024-03-26 08:42:55.403 UTC [43932][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:55.504 UTC [43940][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49323 2024-03-26 08:42:55.517 UTC [43940][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-26 08:42:55.517 UTC [43940][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-26 08:42:55.520 UTC [43940][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-26 08:42:55.520 UTC [43940][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=49323 2024-03-26 08:42:55.535 UTC [43942][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49324 2024-03-26 08:42:55.542 UTC [43942][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-26 08:42:55.542 UTC [43942][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-26 08:42:55.543 UTC [43942][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-26 08:42:55.543 UTC [43942][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49324 2024-03-26 08:42:55.558 UTC [43944][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49325 2024-03-26 08:42:55.566 UTC [43944][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-26 08:42:55.566 UTC [43944][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-26 08:42:55.566 UTC [43944][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-26 08:42:55.566 UTC [43944][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49325 2024-03-26 08:42:55.576 UTC [43932][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:55.576 UTC [43932][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:55.577 UTC [43932][postmaster] LOG: background worker "logical replication launcher" (PID 43938) exited with exit code 1 2024-03-26 08:42:55.577 UTC [43933][checkpointer] LOG: shutting down 2024-03-26 08:42:55.577 UTC [43933][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:55.578 UTC [43933][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/40D5908, redo lsn=0/40D5908 2024-03-26 08:42:55.579 UTC [43932][postmaster] LOG: database system is shut down 2024-03-26 08:42:55.698 UTC [43946][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:55.699 UTC [43946][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:55.699 UTC [43946][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:55.701 UTC [43949][startup] LOG: database system was shut down at 2024-03-26 08:42:55 UTC 2024-03-26 08:42:55.703 UTC [43946][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:55.805 UTC [43954][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49326 2024-03-26 08:42:55.814 UTC [43954][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-26 08:42:55.814 UTC [43954][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-26 08:42:55.816 UTC [43954][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-26 08:42:55.816 UTC [43954][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49326 2024-03-26 08:42:55.826 UTC [43956][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49327 2024-03-26 08:42:55.834 UTC [43956][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-26 08:42:55.834 UTC [43956][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-26 08:42:55.835 UTC [43956][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-26 08:42:55.835 UTC [43956][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49327 2024-03-26 08:42:55.845 UTC [43958][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49328 2024-03-26 08:42:55.858 UTC [43946][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:55.858 UTC [43946][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:55.859 UTC [43946][postmaster] LOG: background worker "logical replication launcher" (PID 43952) exited with exit code 1 2024-03-26 08:42:55.859 UTC [43947][checkpointer] LOG: shutting down 2024-03-26 08:42:55.859 UTC [43947][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:55.860 UTC [43947][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/40D5980, redo lsn=0/40D5980 2024-03-26 08:42:55.861 UTC [43946][postmaster] LOG: database system is shut down 2024-03-26 08:42:55.979 UTC [43960][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:55.979 UTC [43960][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:55.980 UTC [43960][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:55.982 UTC [43963][startup] LOG: database system was shut down at 2024-03-26 08:42:55 UTC 2024-03-26 08:42:55.984 UTC [43960][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:56.073 UTC [43968][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49329 2024-03-26 08:42:56.083 UTC [43968][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-26 08:42:56.083 UTC [43968][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-26 08:42:56.086 UTC [43968][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-26 08:42:56.086 UTC [43968][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=49329 2024-03-26 08:42:56.095 UTC [43970][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49330 2024-03-26 08:42:56.103 UTC [43970][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-26 08:42:56.103 UTC [43970][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-26 08:42:56.103 UTC [43970][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-26 08:42:56.103 UTC [43970][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49330 2024-03-26 08:42:56.113 UTC [43972][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49331 2024-03-26 08:42:56.120 UTC [43972][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-26 08:42:56.120 UTC [43972][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-26 08:42:56.120 UTC [43972][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-26 08:42:56.120 UTC [43972][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49331 2024-03-26 08:42:56.124 UTC [43960][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:56.125 UTC [43960][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:56.125 UTC [43960][postmaster] LOG: background worker "logical replication launcher" (PID 43966) exited with exit code 1 2024-03-26 08:42:56.125 UTC [43961][checkpointer] LOG: shutting down 2024-03-26 08:42:56.125 UTC [43961][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:56.126 UTC [43961][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/40D59F8, redo lsn=0/40D59F8 2024-03-26 08:42:56.127 UTC [43960][postmaster] LOG: database system is shut down 2024-03-26 08:42:56.261 UTC [43974][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:56.261 UTC [43974][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:56.262 UTC [43974][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:56.264 UTC [43977][startup] LOG: database system was shut down at 2024-03-26 08:42:56 UTC 2024-03-26 08:42:56.266 UTC [43974][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:56.379 UTC [43982][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49332 2024-03-26 08:42:56.392 UTC [43982][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-26 08:42:56.392 UTC [43982][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-26 08:42:56.396 UTC [43982][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-26 08:42:56.396 UTC [43982][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=49332 2024-03-26 08:42:56.409 UTC [43984][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49333 2024-03-26 08:42:56.422 UTC [43974][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:56.422 UTC [43974][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:56.423 UTC [43974][postmaster] LOG: background worker "logical replication launcher" (PID 43980) exited with exit code 1 2024-03-26 08:42:56.423 UTC [43975][checkpointer] LOG: shutting down 2024-03-26 08:42:56.423 UTC [43975][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:56.424 UTC [43975][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/40D5A70, redo lsn=0/40D5A70 2024-03-26 08:42:56.425 UTC [43974][postmaster] LOG: database system is shut down 2024-03-26 08:42:56.560 UTC [43986][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:56.560 UTC [43986][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:56.560 UTC [43986][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:56.564 UTC [43989][startup] LOG: database system was shut down at 2024-03-26 08:42:56 UTC 2024-03-26 08:42:56.565 UTC [43986][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:56.669 UTC [43994][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49334 2024-03-26 08:42:56.682 UTC [43994][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-26 08:42:56.704 UTC [43997][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49335 2024-03-26 08:42:56.713 UTC [43997][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-26 08:42:56.713 UTC [43997][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-26 08:42:56.715 UTC [43997][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-26 08:42:56.715 UTC [43997][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49335 2024-03-26 08:42:56.725 UTC [43999][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49336 2024-03-26 08:42:56.736 UTC [43986][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:56.736 UTC [43986][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:56.736 UTC [43986][postmaster] LOG: background worker "logical replication launcher" (PID 43992) exited with exit code 1 2024-03-26 08:42:56.736 UTC [43987][checkpointer] LOG: shutting down 2024-03-26 08:42:56.736 UTC [43987][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:56.737 UTC [43987][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/40D5AE8, redo lsn=0/40D5AE8 2024-03-26 08:42:56.738 UTC [43986][postmaster] LOG: database system is shut down 2024-03-26 08:42:56.876 UTC [44001][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:56.876 UTC [44001][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:56.876 UTC [44001][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:56.879 UTC [44004][startup] LOG: database system was shut down at 2024-03-26 08:42:56 UTC 2024-03-26 08:42:56.881 UTC [44001][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:56.984 UTC [44009][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49337 2024-03-26 08:42:56.998 UTC [44009][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-26 08:42:56.998 UTC [44009][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-26 08:42:57.001 UTC [44009][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-26 08:42:57.005 UTC [44009][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.021 user=ssltestuser database=trustdb host=localhost port=49337 2024-03-26 08:42:57.018 UTC [44011][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49338 2024-03-26 08:42:57.026 UTC [44011][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: ssl/tls alert certificate revoked 2024-03-26 08:42:57.036 UTC [44013][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49339 2024-03-26 08:42:57.043 UTC [44013][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: ssl/tls alert certificate revoked 2024-03-26 08:42:57.052 UTC [44015][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49340 2024-03-26 08:42:57.059 UTC [44015][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-26 08:42:57.059 UTC [44015][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-26 08:42:57.060 UTC [44015][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-26 08:42:57.061 UTC [44015][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49340 2024-03-26 08:42:57.070 UTC [44017][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49341 2024-03-26 08:42:57.077 UTC [44017][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-26 08:42:57.077 UTC [44017][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-26 08:42:57.077 UTC [44017][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-26 08:42:57.078 UTC [44017][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49341 2024-03-26 08:42:57.109 UTC [44022][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49342 2024-03-26 08:42:57.116 UTC [44022][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-03-26 08:42:57.125 UTC [44024][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49343 2024-03-26 08:42:57.134 UTC [44024][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-26 08:42:57.134 UTC [44024][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-26 08:42:57.136 UTC [44024][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_775O/client.key$$ 2024-03-26 08:42:57.136 UTC [44024][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=49343 2024-03-26 08:42:57.145 UTC [44026][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49344 2024-03-26 08:42:57.154 UTC [44026][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-26 08:42:57.154 UTC [44026][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-26 08:42:57.154 UTC [44026][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_775O/client-der.key$$ 2024-03-26 08:42:57.154 UTC [44026][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49344 2024-03-26 08:42:57.163 UTC [44028][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49345 2024-03-26 08:42:57.172 UTC [44028][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-26 08:42:57.172 UTC [44028][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-26 08:42:57.173 UTC [44028][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_775O/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-03-26 08:42:57.173 UTC [44028][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49345 2024-03-26 08:42:57.182 UTC [44030][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49346 2024-03-26 08:42:57.191 UTC [44030][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-26 08:42:57.191 UTC [44030][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-26 08:42:57.191 UTC [44030][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_775O/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-03-26 08:42:57.191 UTC [44030][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49346 2024-03-26 08:42:57.200 UTC [44032][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49347 2024-03-26 08:42:57.209 UTC [44032][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-26 08:42:57.209 UTC [44032][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-26 08:42:57.209 UTC [44032][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_775O/client.key$$ 2024-03-26 08:42:57.209 UTC [44032][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49347 2024-03-26 08:42:57.218 UTC [44034][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49348 2024-03-26 08:42:57.227 UTC [44034][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-26 08:42:57.227 UTC [44034][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-26 08:42:57.227 UTC [44034][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_775O/client.key$$ 2024-03-26 08:42:57.227 UTC [44034][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49348 2024-03-26 08:42:57.236 UTC [44036][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49349 2024-03-26 08:42:57.243 UTC [44036][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-03-26 08:42:57.252 UTC [44038][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49350 2024-03-26 08:42:57.259 UTC [44038][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-26 08:42:57.267 UTC [44040][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49351 2024-03-26 08:42:57.276 UTC [44040][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-26 08:42:57.276 UTC [44040][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-26 08:42:57.279 UTC [44040][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_775O/client-dn.key$$ 2024-03-26 08:42:57.279 UTC [44040][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_dn host=localhost port=49351 2024-03-26 08:42:57.288 UTC [44042][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49352 2024-03-26 08:42:57.296 UTC [44042][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-26 08:42:57.296 UTC [44042][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-26 08:42:57.299 UTC [44042][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_775O/client-dn.key$$ 2024-03-26 08:42:57.299 UTC [44042][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_dn_re host=localhost port=49352 2024-03-26 08:42:57.308 UTC [44044][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49353 2024-03-26 08:42:57.316 UTC [44044][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-26 08:42:57.316 UTC [44044][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-26 08:42:57.319 UTC [44044][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_775O/client-dn.key$$ 2024-03-26 08:42:57.319 UTC [44044][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_cn host=localhost port=49353 2024-03-26 08:42:57.338 UTC [44047][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49354 2024-03-26 08:42:57.347 UTC [44047][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-26 08:42:57.347 UTC [44047][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-26 08:42:57.347 UTC [44047][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-26 08:42:57.348 UTC [44047][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=49354 2024-03-26 08:42:57.357 UTC [44049][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49355 2024-03-26 08:42:57.363 UTC [44049][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-26 08:42:57.372 UTC [44051][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49356 2024-03-26 08:42:57.381 UTC [44051][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-26 08:42:57.381 UTC [44051][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-26 08:42:57.381 UTC [44051][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-03-26 08:42:57.381 UTC [44051][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-26 08:42:57.391 UTC [44053][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49357 2024-03-26 08:42:57.404 UTC [44053][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-26 08:42:57.404 UTC [44053][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-26 08:42:57.413 UTC [44055][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49358 2024-03-26 08:42:57.422 UTC [44055][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-26 08:42:57.422 UTC [44055][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-26 08:42:57.424 UTC [44055][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_775O/client.key$$ 2024-03-26 08:42:57.425 UTC [44055][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=verifydb host=localhost port=49358 2024-03-26 08:42:57.434 UTC [44057][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49359 2024-03-26 08:42:57.443 UTC [44057][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-26 08:42:57.443 UTC [44057][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-03-26 08:42:57.443 UTC [44057][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-03-26 08:42:57.443 UTC [44057][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-26 08:42:57.452 UTC [44059][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49360 2024-03-26 08:42:57.461 UTC [44059][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-26 08:42:57.461 UTC [44059][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-26 08:42:57.462 UTC [44059][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_775O/client.key$$ 2024-03-26 08:42:57.462 UTC [44059][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=yetanotheruser database=verifydb host=localhost port=49360 2024-03-26 08:42:57.466 UTC [44001][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:57.466 UTC [44001][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:57.467 UTC [44001][postmaster] LOG: background worker "logical replication launcher" (PID 44007) exited with exit code 1 2024-03-26 08:42:57.467 UTC [44002][checkpointer] LOG: shutting down 2024-03-26 08:42:57.467 UTC [44002][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:57.468 UTC [44002][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/40D5B60, redo lsn=0/40D5B60 2024-03-26 08:42:57.469 UTC [44001][postmaster] LOG: database system is shut down 2024-03-26 08:42:57.587 UTC [44061][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:57.588 UTC [44061][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:57.588 UTC [44061][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:57.591 UTC [44064][startup] LOG: database system was shut down at 2024-03-26 08:42:57 UTC 2024-03-26 08:42:57.592 UTC [44061][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:57.697 UTC [44069][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49361 2024-03-26 08:42:57.708 UTC [44069][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-26 08:42:57.708 UTC [44069][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-26 08:42:57.711 UTC [44069][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_775O/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-26 08:42:57.711 UTC [44069][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.014 user=ssltestuser database=certdb host=localhost port=49361 2024-03-26 08:42:57.721 UTC [44071][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49362 2024-03-26 08:42:57.730 UTC [44071][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-26 08:42:57.730 UTC [44071][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-26 08:42:57.739 UTC [44073][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49363 2024-03-26 08:42:57.748 UTC [44073][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-26 08:42:57.748 UTC [44073][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-26 08:42:57.752 UTC [44061][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:57.752 UTC [44061][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:57.752 UTC [44061][postmaster] LOG: background worker "logical replication launcher" (PID 44067) exited with exit code 1 2024-03-26 08:42:57.752 UTC [44062][checkpointer] LOG: shutting down 2024-03-26 08:42:57.753 UTC [44062][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:57.753 UTC [44062][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/40D5BD8, redo lsn=0/40D5BD8 2024-03-26 08:42:57.754 UTC [44061][postmaster] LOG: database system is shut down 2024-03-26 08:42:57.886 UTC [44075][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:57.887 UTC [44075][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:57.887 UTC [44075][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:57.889 UTC [44078][startup] LOG: database system was shut down at 2024-03-26 08:42:57 UTC 2024-03-26 08:42:57.891 UTC [44075][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:57.982 UTC [44083][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49364 2024-03-26 08:42:57.996 UTC [44083][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-26 08:42:57.996 UTC [44083][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-26 08:42:58.001 UTC [44075][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:58.001 UTC [44075][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:58.002 UTC [44075][postmaster] LOG: background worker "logical replication launcher" (PID 44081) exited with exit code 1 2024-03-26 08:42:58.002 UTC [44076][checkpointer] LOG: shutting down 2024-03-26 08:42:58.002 UTC [44076][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:58.003 UTC [44076][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/40D5C50, redo lsn=0/40D5C50 2024-03-26 08:42:58.004 UTC [44075][postmaster] LOG: database system is shut down 2024-03-26 08:42:58.139 UTC [44085][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:58.140 UTC [44085][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:58.140 UTC [44085][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:58.143 UTC [44088][startup] LOG: database system was shut down at 2024-03-26 08:42:58 UTC 2024-03-26 08:42:58.145 UTC [44085][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:58.251 UTC [44093][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49365 2024-03-26 08:42:58.268 UTC [44093][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-26 08:42:58.268 UTC [44093][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-26 08:42:58.283 UTC [44095][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49366 2024-03-26 08:42:58.293 UTC [44095][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-26 08:42:58.293 UTC [44095][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-26 08:42:58.298 UTC [44085][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:58.298 UTC [44085][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:58.299 UTC [44085][postmaster] LOG: background worker "logical replication launcher" (PID 44091) exited with exit code 1 2024-03-26 08:42:58.299 UTC [44086][checkpointer] LOG: shutting down 2024-03-26 08:42:58.299 UTC [44086][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:58.299 UTC [44086][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/40D5CC8, redo lsn=0/40D5CC8 2024-03-26 08:42:58.300 UTC [44085][postmaster] LOG: database system is shut down 2024-03-26 08:42:58.435 UTC [44097][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:58.435 UTC [44097][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:58.436 UTC [44097][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:58.439 UTC [44100][startup] LOG: database system was shut down at 2024-03-26 08:42:58 UTC 2024-03-26 08:42:58.440 UTC [44097][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:58.551 UTC [44105][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49367 2024-03-26 08:42:58.562 UTC [44105][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-26 08:42:58.562 UTC [44105][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-26 08:42:58.576 UTC [44107][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49368 2024-03-26 08:42:58.586 UTC [44107][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-26 08:42:58.586 UTC [44107][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-26 08:42:58.589 UTC [44107][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-26 08:42:58.589 UTC [44107][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49368 2024-03-26 08:42:58.594 UTC [44097][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:58.594 UTC [44097][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:58.594 UTC [44097][postmaster] LOG: background worker "logical replication launcher" (PID 44103) exited with exit code 1 2024-03-26 08:42:58.595 UTC [44098][checkpointer] LOG: shutting down 2024-03-26 08:42:58.595 UTC [44098][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:58.595 UTC [44098][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/40D5D40, redo lsn=0/40D5D40 2024-03-26 08:42:58.596 UTC [44097][postmaster] LOG: database system is shut down 2024-03-26 08:42:58.722 UTC [44109][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:58.723 UTC [44109][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:58.723 UTC [44109][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:58.727 UTC [44112][startup] LOG: database system was shut down at 2024-03-26 08:42:58 UTC 2024-03-26 08:42:58.729 UTC [44109][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:58.825 UTC [44117][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49369 2024-03-26 08:42:58.833 UTC [44117][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-26 08:42:58.833 UTC [44117][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-26 08:42:58.844 UTC [44119][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49370 2024-03-26 08:42:58.851 UTC [44119][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-26 08:42:58.851 UTC [44119][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-26 08:42:58.854 UTC [44119][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-26 08:42:58.854 UTC [44119][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49370 2024-03-26 08:42:58.858 UTC [44109][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:58.858 UTC [44109][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:58.859 UTC [44109][postmaster] LOG: background worker "logical replication launcher" (PID 44115) exited with exit code 1 2024-03-26 08:42:58.859 UTC [44110][checkpointer] LOG: shutting down 2024-03-26 08:42:58.859 UTC [44110][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:58.859 UTC [44110][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/40D5DB8, redo lsn=0/40D5DB8 2024-03-26 08:42:58.860 UTC [44109][postmaster] LOG: database system is shut down 2024-03-26 08:42:59.000 UTC [44121][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:59.000 UTC [44121][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:59.001 UTC [44121][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:59.004 UTC [44124][startup] LOG: database system was shut down at 2024-03-26 08:42:58 UTC 2024-03-26 08:42:59.005 UTC [44121][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:59.107 UTC [44129][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49371 2024-03-26 08:42:59.118 UTC [44129][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-26 08:42:59.118 UTC [44129][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-26 08:42:59.132 UTC [44131][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49372 2024-03-26 08:42:59.141 UTC [44131][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-26 08:42:59.141 UTC [44131][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-26 08:42:59.143 UTC [44131][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-26 08:42:59.144 UTC [44131][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49372 2024-03-26 08:42:59.148 UTC [44121][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:59.148 UTC [44121][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:59.149 UTC [44121][postmaster] LOG: background worker "logical replication launcher" (PID 44127) exited with exit code 1 2024-03-26 08:42:59.149 UTC [44122][checkpointer] LOG: shutting down 2024-03-26 08:42:59.149 UTC [44122][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:59.150 UTC [44122][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/40D5E30, redo lsn=0/40D5E30 2024-03-26 08:42:59.151 UTC [44121][postmaster] LOG: database system is shut down 2024-03-26 08:42:59.289 UTC [44133][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:59.289 UTC [44133][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:59.290 UTC [44133][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:59.292 UTC [44136][startup] LOG: database system was shut down at 2024-03-26 08:42:59 UTC 2024-03-26 08:42:59.294 UTC [44133][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:59.399 UTC [44142][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49373 2024-03-26 08:42:59.406 UTC [44142][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-26 08:42:59.406 UTC [44142][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-26 08:42:59.416 UTC [44144][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49374 2024-03-26 08:42:59.423 UTC [44144][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-26 08:42:59.423 UTC [44144][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-26 08:42:59.426 UTC [44144][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-26 08:42:59.426 UTC [44144][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49374 2024-03-26 08:42:59.430 UTC [44133][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:59.430 UTC [44133][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:59.431 UTC [44133][postmaster] LOG: background worker "logical replication launcher" (PID 44139) exited with exit code 1 2024-03-26 08:42:59.431 UTC [44134][checkpointer] LOG: shutting down 2024-03-26 08:42:59.431 UTC [44134][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:59.431 UTC [44134][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/40D5EA8, redo lsn=0/40D5EA8 2024-03-26 08:42:59.432 UTC [44133][postmaster] LOG: database system is shut down 2024-03-26 08:42:59.560 UTC [44146][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:59.560 UTC [44146][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:59.560 UTC [44146][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:59.563 UTC [44149][startup] LOG: database system was shut down at 2024-03-26 08:42:59 UTC 2024-03-26 08:42:59.565 UTC [44146][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:59.672 UTC [44154][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49375 2024-03-26 08:42:59.681 UTC [44154][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-26 08:42:59.681 UTC [44154][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-26 08:42:59.695 UTC [44156][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49376 2024-03-26 08:42:59.704 UTC [44156][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-26 08:42:59.704 UTC [44156][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-26 08:42:59.707 UTC [44156][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-26 08:42:59.707 UTC [44156][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49376 2024-03-26 08:42:59.711 UTC [44146][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:59.711 UTC [44146][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:59.712 UTC [44146][postmaster] LOG: background worker "logical replication launcher" (PID 44152) exited with exit code 1 2024-03-26 08:42:59.712 UTC [44147][checkpointer] LOG: shutting down 2024-03-26 08:42:59.712 UTC [44147][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:59.713 UTC [44147][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/40D5F20, redo lsn=0/40D5F20 2024-03-26 08:42:59.714 UTC [44146][postmaster] LOG: database system is shut down 2024-03-26 08:42:59.850 UTC [44158][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:42:59.851 UTC [44158][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:42:59.851 UTC [44158][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:42:59.854 UTC [44161][startup] LOG: database system was shut down at 2024-03-26 08:42:59 UTC 2024-03-26 08:42:59.856 UTC [44158][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:42:59.946 UTC [44166][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49377 2024-03-26 08:42:59.955 UTC [44166][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-26 08:42:59.955 UTC [44166][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-26 08:42:59.969 UTC [44168][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49378 2024-03-26 08:42:59.979 UTC [44168][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-26 08:42:59.979 UTC [44168][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-26 08:42:59.982 UTC [44168][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-26 08:42:59.982 UTC [44168][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=49378 2024-03-26 08:42:59.987 UTC [44158][postmaster] LOG: received fast shutdown request 2024-03-26 08:42:59.987 UTC [44158][postmaster] LOG: aborting any active transactions 2024-03-26 08:42:59.987 UTC [44158][postmaster] LOG: background worker "logical replication launcher" (PID 44164) exited with exit code 1 2024-03-26 08:42:59.987 UTC [44159][checkpointer] LOG: shutting down 2024-03-26 08:42:59.987 UTC [44159][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:42:59.988 UTC [44159][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/40D5F98, redo lsn=0/40D5F98 2024-03-26 08:42:59.989 UTC [44158][postmaster] LOG: database system is shut down 2024-03-26 08:43:00.122 UTC [44170][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:43:00.122 UTC [44170][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:43:00.123 UTC [44170][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:43:00.125 UTC [44173][startup] LOG: database system was shut down at 2024-03-26 08:42:59 UTC 2024-03-26 08:43:00.127 UTC [44170][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:43:00.233 UTC [44178][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49379 2024-03-26 08:43:00.243 UTC [44178][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-26 08:43:00.243 UTC [44178][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-26 08:43:00.259 UTC [44180][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49380 2024-03-26 08:43:00.268 UTC [44180][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-26 08:43:00.268 UTC [44180][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-26 08:43:00.271 UTC [44180][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-26 08:43:00.272 UTC [44180][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=49380 2024-03-26 08:43:00.276 UTC [44170][postmaster] LOG: received fast shutdown request 2024-03-26 08:43:00.276 UTC [44170][postmaster] LOG: aborting any active transactions 2024-03-26 08:43:00.276 UTC [44170][postmaster] LOG: background worker "logical replication launcher" (PID 44176) exited with exit code 1 2024-03-26 08:43:00.277 UTC [44171][checkpointer] LOG: shutting down 2024-03-26 08:43:00.277 UTC [44171][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:43:00.277 UTC [44171][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/40D6028, redo lsn=0/40D6028 2024-03-26 08:43:00.278 UTC [44170][postmaster] LOG: database system is shut down 2024-03-26 08:43:00.411 UTC [44182][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:43:00.411 UTC [44182][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:43:00.412 UTC [44182][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:43:00.414 UTC [44185][startup] LOG: database system was shut down at 2024-03-26 08:43:00 UTC 2024-03-26 08:43:00.416 UTC [44182][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:43:00.514 UTC [44190][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49381 2024-03-26 08:43:00.523 UTC [44190][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-26 08:43:00.523 UTC [44190][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-26 08:43:00.535 UTC [44192][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49382 2024-03-26 08:43:00.544 UTC [44192][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-26 08:43:00.544 UTC [44192][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-26 08:43:00.547 UTC [44192][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-26 08:43:00.547 UTC [44192][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49382 2024-03-26 08:43:00.552 UTC [44182][postmaster] LOG: received fast shutdown request 2024-03-26 08:43:00.552 UTC [44182][postmaster] LOG: aborting any active transactions 2024-03-26 08:43:00.552 UTC [44182][postmaster] LOG: background worker "logical replication launcher" (PID 44188) exited with exit code 1 2024-03-26 08:43:00.552 UTC [44183][checkpointer] LOG: shutting down 2024-03-26 08:43:00.552 UTC [44183][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:43:00.553 UTC [44183][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/40D60A0, redo lsn=0/40D60A0 2024-03-26 08:43:00.554 UTC [44182][postmaster] LOG: database system is shut down 2024-03-26 08:43:00.677 UTC [44194][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-26 08:43:00.677 UTC [44194][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63500 2024-03-26 08:43:00.677 UTC [44194][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/adJYm6nfiK/.s.PGSQL.63500" 2024-03-26 08:43:00.680 UTC [44197][startup] LOG: database system was shut down at 2024-03-26 08:43:00 UTC 2024-03-26 08:43:00.682 UTC [44194][postmaster] LOG: database system is ready to accept connections 2024-03-26 08:43:00.785 UTC [44202][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49383 2024-03-26 08:43:00.795 UTC [44202][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-26 08:43:00.795 UTC [44202][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-26 08:43:00.808 UTC [44204][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49384 2024-03-26 08:43:00.817 UTC [44204][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-26 08:43:00.817 UTC [44204][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-26 08:43:00.820 UTC [44204][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-26 08:43:00.820 UTC [44204][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49384 2024-03-26 08:43:00.825 UTC [44194][postmaster] LOG: received fast shutdown request 2024-03-26 08:43:00.825 UTC [44194][postmaster] LOG: aborting any active transactions 2024-03-26 08:43:00.826 UTC [44194][postmaster] LOG: background worker "logical replication launcher" (PID 44200) exited with exit code 1 2024-03-26 08:43:00.826 UTC [44195][checkpointer] LOG: shutting down 2024-03-26 08:43:00.826 UTC [44195][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-26 08:43:00.826 UTC [44195][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/40D6118, redo lsn=0/40D6118 2024-03-26 08:43:00.827 UTC [44194][postmaster] LOG: database system is shut down 2024-03-26 08:43:00.966 UTC [44206][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-03-26 08:43:00.966 UTC [44206][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [08:43:01.067](0.245s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"