[05:43:29.222](0.062s) # setting up data directory # Checking port 63841 # Found port 63841 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=63841 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l Log file: /Users/admin/pgsql/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [05:43:29.229](0.006s) # 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 38190 [05:43:29.559](0.330s) 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 38641 [05:43:33.882](4.323s) # 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-10 05:43:29.459 UTC [38190][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:29.459 UTC [38190][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:29.472 UTC [38202][startup] LOG: database system was shut down at 2024-03-10 05:39:08 UTC 2024-03-10 05:43:29.481 UTC [38190][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:29.555 UTC [38226][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:29.555 UTC [38226][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-10 05:43:29.555 UTC [38226][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:29.558 UTC [38226][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-10 05:43:29.558 UTC [38226][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-03-10 05:43:29.582 UTC [38230][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:29.582 UTC [38230][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-10 05:43:29.582 UTC [38230][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:29.583 UTC [38230][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-10 05:43:29.583 UTC [38230][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-10 05:43:29.633 UTC [38239][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:29.634 UTC [38239][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-10 05:43:29.634 UTC [38239][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:29.636 UTC [38239][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-10 05:43:29.637 UTC [38239][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-03-10 05:43:29.692 UTC [38256][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:29.693 UTC [38256][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-10 05:43:29.693 UTC [38256][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:29.700 UTC [38256][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-10 05:43:29.701 UTC [38256][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=admin database=postgres host=[local] 2024-03-10 05:43:29.748 UTC [38271][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:29.749 UTC [38271][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-10 05:43:29.749 UTC [38271][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:29.749 UTC [38271][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-10 05:43:29.750 UTC [38271][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-10 05:43:29.811 UTC [38287][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:29.812 UTC [38287][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-10 05:43:29.812 UTC [38287][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:29.813 UTC [38287][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-10 05:43:29.882 UTC [38287][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.071 user=admin database=postgres host=[local] 2024-03-10 05:43:29.939 UTC [38303][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:29.940 UTC [38303][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-10 05:43:29.940 UTC [38303][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:29.941 UTC [38303][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-10 05:43:30.317 UTC [38303][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.377 user=admin database=postgres host=[local] 2024-03-10 05:43:30.568 UTC [38319][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:30.568 UTC [38319][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-10 05:43:30.568 UTC [38319][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:30.569 UTC [38319][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-10 05:43:30.627 UTC [38319][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.058 user=admin database=postgres host=[local] 2024-03-10 05:43:30.699 UTC [38351][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:30.785 UTC [38351][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-10 05:43:30.785 UTC [38351][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:30.786 UTC [38351][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-10 05:43:30.909 UTC [38351][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.210 user=admin database=postgres host=[local] 2024-03-10 05:43:31.016 UTC [38388][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:31.017 UTC [38388][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-10 05:43:31.017 UTC [38388][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:31.017 UTC [38388][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-10 05:43:31.100 UTC [38388][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.084 user=admin database=postgres host=[local] 2024-03-10 05:43:31.154 UTC [38412][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:31.154 UTC [38412][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-10 05:43:31.154 UTC [38412][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:31.154 UTC [38412][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-10 05:43:31.342 UTC [38412][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.188 user=admin database=postgres host=[local] 2024-03-10 05:43:32.882 UTC [38190][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:32.882 UTC [38190][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:32.883 UTC [38190][postmaster] LOG: background worker "logical replication launcher" (PID 38207) exited with exit code 1 2024-03-10 05:43:32.883 UTC [38200][checkpointer] LOG: shutting down 2024-03-10 05:43:32.883 UTC [38200][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:33.564 UTC [38200][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.681 s, sync=0.001 s, total=0.681 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4104958, redo lsn=0/4104958 2024-03-10 05:43:33.575 UTC [38190][postmaster] LOG: database system is shut down 2024-03-10 05:43:33.772 UTC [38641][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:33.772 UTC [38641][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:33.772 UTC [38641][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:33.775 UTC [38649][startup] LOG: database system was shut down at 2024-03-10 05:43:33 UTC 2024-03-10 05:43:33.776 UTC [38641][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:33.886 UTC [38641][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:33.886 UTC [38641][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:33.886 UTC [38641][postmaster] LOG: background worker "logical replication launcher" (PID 38654) exited with exit code 1 2024-03-10 05:43:33.887 UTC [38646][checkpointer] LOG: shutting down 2024-03-10 05:43:33.887 UTC [38646][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:33.929 UTC [38646][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.043 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41049D0, redo lsn=0/41049D0 2024-03-10 05:43:33.930 UTC [38641][postmaster] LOG: database system is shut down 2024-03-10 05:43:34.018 UTC [38661][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-10 05:43:34.028 UTC [38661][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [05:43:34.115](0.233s) 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 38665 [05:43:34.344](0.229s) 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-10 05:43:29.459 UTC [38190][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:29.459 UTC [38190][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:29.472 UTC [38202][startup] LOG: database system was shut down at 2024-03-10 05:39:08 UTC 2024-03-10 05:43:29.481 UTC [38190][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:29.555 UTC [38226][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:29.555 UTC [38226][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-10 05:43:29.555 UTC [38226][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:29.558 UTC [38226][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-10 05:43:29.558 UTC [38226][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-03-10 05:43:29.582 UTC [38230][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:29.582 UTC [38230][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-10 05:43:29.582 UTC [38230][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:29.583 UTC [38230][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-10 05:43:29.583 UTC [38230][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-10 05:43:29.633 UTC [38239][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:29.634 UTC [38239][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-10 05:43:29.634 UTC [38239][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:29.636 UTC [38239][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-10 05:43:29.637 UTC [38239][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-03-10 05:43:29.692 UTC [38256][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:29.693 UTC [38256][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-10 05:43:29.693 UTC [38256][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:29.700 UTC [38256][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-10 05:43:29.701 UTC [38256][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=admin database=postgres host=[local] 2024-03-10 05:43:29.748 UTC [38271][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:29.749 UTC [38271][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-10 05:43:29.749 UTC [38271][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:29.749 UTC [38271][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-10 05:43:29.750 UTC [38271][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-10 05:43:29.811 UTC [38287][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:29.812 UTC [38287][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-10 05:43:29.812 UTC [38287][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:29.813 UTC [38287][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-10 05:43:29.882 UTC [38287][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.071 user=admin database=postgres host=[local] 2024-03-10 05:43:29.939 UTC [38303][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:29.940 UTC [38303][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-10 05:43:29.940 UTC [38303][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:29.941 UTC [38303][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-10 05:43:30.317 UTC [38303][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.377 user=admin database=postgres host=[local] 2024-03-10 05:43:30.568 UTC [38319][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:30.568 UTC [38319][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-10 05:43:30.568 UTC [38319][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:30.569 UTC [38319][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-10 05:43:30.627 UTC [38319][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.058 user=admin database=postgres host=[local] 2024-03-10 05:43:30.699 UTC [38351][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:30.785 UTC [38351][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-10 05:43:30.785 UTC [38351][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:30.786 UTC [38351][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-10 05:43:30.909 UTC [38351][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.210 user=admin database=postgres host=[local] 2024-03-10 05:43:31.016 UTC [38388][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:31.017 UTC [38388][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-10 05:43:31.017 UTC [38388][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:31.017 UTC [38388][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-10 05:43:31.100 UTC [38388][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.084 user=admin database=postgres host=[local] 2024-03-10 05:43:31.154 UTC [38412][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:31.154 UTC [38412][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-10 05:43:31.154 UTC [38412][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:31.154 UTC [38412][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-10 05:43:31.342 UTC [38412][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.188 user=admin database=postgres host=[local] 2024-03-10 05:43:32.882 UTC [38190][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:32.882 UTC [38190][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:32.883 UTC [38190][postmaster] LOG: background worker "logical replication launcher" (PID 38207) exited with exit code 1 2024-03-10 05:43:32.883 UTC [38200][checkpointer] LOG: shutting down 2024-03-10 05:43:32.883 UTC [38200][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:33.564 UTC [38200][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.681 s, sync=0.001 s, total=0.681 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4104958, redo lsn=0/4104958 2024-03-10 05:43:33.575 UTC [38190][postmaster] LOG: database system is shut down 2024-03-10 05:43:33.772 UTC [38641][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:33.772 UTC [38641][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:33.772 UTC [38641][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:33.775 UTC [38649][startup] LOG: database system was shut down at 2024-03-10 05:43:33 UTC 2024-03-10 05:43:33.776 UTC [38641][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:33.886 UTC [38641][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:33.886 UTC [38641][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:33.886 UTC [38641][postmaster] LOG: background worker "logical replication launcher" (PID 38654) exited with exit code 1 2024-03-10 05:43:33.887 UTC [38646][checkpointer] LOG: shutting down 2024-03-10 05:43:33.887 UTC [38646][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:33.929 UTC [38646][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.043 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41049D0, redo lsn=0/41049D0 2024-03-10 05:43:33.930 UTC [38641][postmaster] LOG: database system is shut down 2024-03-10 05:43:34.018 UTC [38661][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-10 05:43:34.028 UTC [38661][postmaster] LOG: database system is shut down 2024-03-10 05:43:34.299 UTC [38665][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:34.299 UTC [38665][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:34.299 UTC [38665][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:34.301 UTC [38682][startup] LOG: database system was shut down at 2024-03-10 05:43:33 UTC 2024-03-10 05:43:34.302 UTC [38665][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:34.348 UTC [38665][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:34.348 UTC [38665][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:34.349 UTC [38665][postmaster] LOG: background worker "logical replication launcher" (PID 38685) exited with exit code 1 2024-03-10 05:43:34.349 UTC [38680][checkpointer] LOG: shutting down 2024-03-10 05:43:34.349 UTC [38680][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:34.350 UTC [38680][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/4104A48, redo lsn=0/4104A48 2024-03-10 05:43:34.351 UTC [38665][postmaster] LOG: database system is shut down 2024-03-10 05:43:34.474 UTC [38691][postmaster] FATAL: could not set SSL protocol version range 2024-03-10 05:43:34.474 UTC [38691][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-10 05:43:34.474 UTC [38691][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [05:43:34.569](0.225s) 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 38708 [05:43:34.904](0.335s) ok 5 - restart succeeds with correct SSL protocol bounds [05:43:34.904](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 38758 [05:43:35.140](0.236s) ok 6 - server doesn't accept non-SSL connections [05:43:35.140](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [05:43:35.162](0.022s) ok 8 - connect without server root cert sslmode=require [05:43:35.163](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [05:43:35.178](0.015s) ok 10 - connect without server root cert sslmode=verify-ca [05:43:35.178](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [05:43:35.193](0.015s) ok 12 - connect without server root cert sslmode=verify-full [05:43:35.193](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [05:43:35.211](0.018s) ok 14 - connect with wrong server root cert sslmode=require [05:43:35.211](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [05:43:35.227](0.016s) ok 16 - connect with wrong server root cert sslmode=verify-ca [05:43:35.227](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [05:43:35.246](0.019s) ok 18 - connect with wrong server root cert sslmode=verify-full [05:43:35.246](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [05:43:35.267](0.021s) ok 20 - connect with server CA cert, without root CA [05:43:35.267](0.000s) ok 21 - connect with server CA cert, without root CA: matches [05:43:35.287](0.020s) ok 22 - connect with correct server CA cert file sslmode=require [05:43:35.287](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [05:43:35.305](0.018s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [05:43:35.305](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [05:43:35.323](0.018s) ok 26 - connect with correct server CA cert file sslmode=verify-full [05:43:35.323](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [05:43:35.343](0.020s) ok 28 - cert root file that contains two certificates, order 1 [05:43:35.343](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [05:43:35.363](0.020s) ok 30 - cert root file that contains two certificates, order 2 [05:43:35.363](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [05:43:35.389](0.026s) ok 32 - connect with sslcertmode=disable [05:43:35.390](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [05:43:35.414](0.025s) ok 34 - connect with sslcertmode=allow [05:43:35.414](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [05:43:35.438](0.024s) ok 36 - connect with sslcertmode=require fails without a client certificate [05:43:35.438](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [05:43:35.464](0.026s) ok 38 - sslcrl option with invalid file name [05:43:35.465](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [05:43:35.488](0.023s) ok 40 - CRL belonging to a different CA [05:43:35.488](0.000s) ok 41 - CRL belonging to a different CA: matches [05:43:35.509](0.021s) ok 42 - directory CRL belonging to a different CA [05:43:35.509](0.000s) ok 43 - directory CRL belonging to a different CA: matches [05:43:35.532](0.023s) ok 44 - CRL with a non-revoked cert [05:43:35.532](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [05:43:35.551](0.019s) ok 46 - directory CRL with a non-revoked cert [05:43:35.551](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [05:43:35.569](0.017s) ok 48 - mismatch between host name and server certificate sslmode=require [05:43:35.569](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [05:43:35.586](0.018s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [05:43:35.587](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [05:43:35.604](0.017s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [05:43:35.604](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 38894 [05:43:36.286](0.682s) ok 54 - IP address in the Common Name [05:43:36.286](0.000s) ok 55 - IP address in the Common Name: no stderr [05:43:36.304](0.017s) ok 56 - mismatch between host name and server certificate IP address [05:43:36.304](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 38909 [05:43:36.551](0.247s) ok 58 - IP address in a dNSName [05:43:36.551](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 38919 [05:43:36.788](0.236s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [05:43:36.788](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [05:43:36.805](0.018s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [05:43:36.805](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [05:43:36.823](0.018s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [05:43:36.823](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [05:43:36.841](0.017s) ok 66 - host name not matching with X.509 Subject Alternative Names [05:43:36.841](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [05:43:36.858](0.018s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [05:43:36.858](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 38937 [05:43:37.104](0.246s) ok 70 - host name matching with a single X.509 Subject Alternative Name [05:43:37.105](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [05:43:37.121](0.017s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [05:43:37.121](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [05:43:37.138](0.016s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [05:43:37.138](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 38952 [05:43:37.390](0.252s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [05:43:37.390](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [05:43:37.407](0.017s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [05:43:37.407](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [05:43:37.424](0.016s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [05:43:37.424](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [05:43:37.443](0.019s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [05:43:37.443](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [05:43:37.462](0.019s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [05:43:37.462](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [05:43:37.481](0.019s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [05:43:37.481](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [05:43:37.499](0.017s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [05:43:37.499](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [05:43:37.516](0.018s) ok 90 - IPv6 host with CIDR mask does not match [05:43:37.516](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 38976 [05:43:37.761](0.245s) ok 92 - certificate with both a CN and SANs 1 [05:43:37.761](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [05:43:37.780](0.018s) ok 94 - certificate with both a CN and SANs 2 [05:43:37.780](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [05:43:37.797](0.017s) ok 96 - certificate with both a CN and SANs ignores CN [05:43:37.797](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 38991 [05:43:38.062](0.265s) ok 98 - certificate with both a CN and IP SANs matches CN [05:43:38.062](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [05:43:38.081](0.019s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [05:43:38.081](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [05:43:38.098](0.018s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [05:43:38.098](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 39005 [05:43:38.353](0.255s) ok 104 - certificate with both an IP CN and IP SANs 1 [05:43:38.354](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [05:43:38.372](0.019s) ok 106 - certificate with both an IP CN and IP SANs 2 [05:43:38.372](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [05:43:38.388](0.016s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [05:43:38.388](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 39019 [05:43:38.616](0.228s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [05:43:38.616](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [05:43:38.633](0.017s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [05:43:38.633](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [05:43:38.650](0.017s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [05:43:38.650](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 39036 [05:43:38.902](0.252s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [05:43:38.902](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [05:43:38.920](0.018s) ok 118 - server certificate without CN or SANs sslmode=verify-full [05:43:38.920](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 39050 [05:43:39.170](0.250s) ok 120 - sslrootcert=system does not connect with private CA [05:43:39.170](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [05:43:39.179](0.009s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [05:43:39.179](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [05:43:39.201](0.022s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [05:43:39.201](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [05:43:39.218](0.017s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [05:43:39.218](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 39065 [05:43:39.484](0.265s) ok 128 - connects without client-side CRL [05:43:39.484](0.000s) ok 129 - connects without client-side CRL: no stderr [05:43:39.503](0.019s) ok 130 - does not connect with client-side CRL file [05:43:39.503](0.000s) ok 131 - does not connect with client-side CRL file: matches [05:43:39.519](0.016s) ok 132 - does not connect with client-side CRL directory [05:43:39.519](0.000s) ok 133 - does not connect with client-side CRL directory: matches # Running: psql -X -A -F , -P null=_null_ -d sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [05:43:39.536](0.017s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [05:43:39.536](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [05:43:39.536](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [05:43:39.553](0.017s) ok 137 - connection success with correct range of TLS protocol versions [05:43:39.553](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [05:43:39.561](0.008s) ok 139 - connection failure with incorrect range of TLS protocol versions [05:43:39.561](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [05:43:39.568](0.007s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [05:43:39.568](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [05:43:39.576](0.007s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [05:43:39.576](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [05:43:39.576](0.000s) # running server tests [05:43:39.592](0.016s) ok 145 - certificate authorization fails without client cert [05:43:39.592](0.000s) ok 146 - certificate authorization fails without client cert: matches [05:43:39.613](0.020s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [05:43:39.613](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [05:43:39.631](0.018s) ok 149 - certificate authorization succeeds with correct client cert in DER format [05:43:39.631](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [05:43:39.649](0.018s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [05:43:39.649](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [05:43:39.667](0.018s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [05:43:39.667](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [05:43:39.685](0.018s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [05:43:39.685](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [05:43:39.703](0.018s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [05:43:39.703](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [05:43:39.719](0.016s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [05:43:39.719](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [05:43:39.736](0.016s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [05:43:39.736](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [05:43:39.764](0.028s) ok 163 - certificate authorization succeeds with DN mapping [05:43:39.764](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [05:43:39.764](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [05:43:39.787](0.023s) ok 166 - certificate authorization succeeds with DN regex mapping [05:43:39.787](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [05:43:39.808](0.020s) ok 168 - certificate authorization succeeds with CN mapping [05:43:39.808](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [05:43:39.808](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [05:43:39.808](0.000s) not ok 171 # TODO & SKIP Need Pty support [05:43:39.808](0.000s) not ok 172 # TODO & SKIP Need Pty support [05:43:39.808](0.000s) not ok 173 # TODO & SKIP Need Pty support [05:43:39.808](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_5fMi/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [05:43:39.835](0.027s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [05:43:39.836](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [05:43:39.836](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [05:43:39.850](0.015s) ok 178 - certificate authorization fails because of file permissions [05:43:39.850](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [05:43:39.867](0.017s) ok 180 - certificate authorization fails with client cert belonging to another user [05:43:39.868](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [05:43:39.885](0.017s) ok 182 - certificate authorization fails with revoked client cert [05:43:39.885](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [05:43:39.885](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [05:43:39.905](0.020s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [05:43:39.905](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [05:43:39.906](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [05:43:39.923](0.018s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [05:43:39.924](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [05:43:39.924](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [05:43:39.942](0.018s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [05:43:39.942](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [05:43:39.942](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 39125 [05:43:40.198](0.257s) ok 194 - intermediate client certificate is provided by client [05:43:40.199](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [05:43:40.217](0.018s) ok 196 - intermediate client certificate is missing [05:43:40.217](0.000s) ok 197 - intermediate client certificate is missing: matches [05:43:40.234](0.017s) ok 198 - logged client certificate Subjects are truncated if they're too long [05:43:40.234](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 39139 [05:43:40.469](0.235s) ok 200 - intermediate client certificate is untrusted [05:43:40.469](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 39149 [05:43:40.729](0.260s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [05:43:40.729](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [05:43:40.751](0.022s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [05:43:40.751](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 39161 [05:43:40.996](0.245s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [05:43:40.997](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. [05:43:40.997](0.000s) # got: '2' # expected: '0' [05:43:40.997](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [05:43:40.997](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. [05:43:40.997](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 63841 failed: server closed the connection unexpectedly # This probably means the server terminated abnormally # before or while processing the request. # SSL SYSCALL error: Connection reset by peer' # expected: '' [05:43:41.017](0.021s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [05:43:41.018](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 39173 [05:43:41.266](0.249s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [05:43:41.285](0.019s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [05:43:41.285](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 39185 [05:43:41.528](0.243s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [05:43:41.553](0.025s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [05:43:41.553](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 39197 [05:43:41.792](0.239s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [05:43:41.815](0.024s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [05:43:41.816](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 39209 [05:43:42.067](0.251s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [05:43:42.067](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. [05:43:42.067](0.000s) # got: '2' # expected: '0' [05:43:42.067](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [05:43:42.067](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. [05:43:42.067](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 63841 failed: server closed the connection unexpectedly # This probably means the server terminated abnormally # before or while processing the request. # SSL SYSCALL error: Connection reset by peer' # expected: '' [05:43:42.237](0.170s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [05:43:42.237](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 39221 [05:43:42.473](0.236s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [05:43:42.619](0.146s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [05:43:42.619](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 39233 [05:43:42.871](0.252s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [05:43:42.893](0.022s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [05:43:42.894](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 39245 [05:43:43.134](0.241s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [05:43:43.156](0.022s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [05:43:43.156](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 39257 [05:43:43.406](0.250s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [05:43:43.429](0.023s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [05:43:43.429](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-10 05:43:29.459 UTC [38190][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:29.459 UTC [38190][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:29.472 UTC [38202][startup] LOG: database system was shut down at 2024-03-10 05:39:08 UTC 2024-03-10 05:43:29.481 UTC [38190][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:29.555 UTC [38226][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:29.555 UTC [38226][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-10 05:43:29.555 UTC [38226][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:29.558 UTC [38226][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-10 05:43:29.558 UTC [38226][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-03-10 05:43:29.582 UTC [38230][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:29.582 UTC [38230][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-10 05:43:29.582 UTC [38230][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:29.583 UTC [38230][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-10 05:43:29.583 UTC [38230][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-10 05:43:29.633 UTC [38239][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:29.634 UTC [38239][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-10 05:43:29.634 UTC [38239][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:29.636 UTC [38239][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-10 05:43:29.637 UTC [38239][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-03-10 05:43:29.692 UTC [38256][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:29.693 UTC [38256][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-10 05:43:29.693 UTC [38256][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:29.700 UTC [38256][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-10 05:43:29.701 UTC [38256][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=admin database=postgres host=[local] 2024-03-10 05:43:29.748 UTC [38271][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:29.749 UTC [38271][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-10 05:43:29.749 UTC [38271][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:29.749 UTC [38271][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-10 05:43:29.750 UTC [38271][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-10 05:43:29.811 UTC [38287][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:29.812 UTC [38287][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-10 05:43:29.812 UTC [38287][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:29.813 UTC [38287][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-10 05:43:29.882 UTC [38287][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.071 user=admin database=postgres host=[local] 2024-03-10 05:43:29.939 UTC [38303][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:29.940 UTC [38303][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-10 05:43:29.940 UTC [38303][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:29.941 UTC [38303][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-10 05:43:30.317 UTC [38303][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.377 user=admin database=postgres host=[local] 2024-03-10 05:43:30.568 UTC [38319][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:30.568 UTC [38319][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-10 05:43:30.568 UTC [38319][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:30.569 UTC [38319][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-10 05:43:30.627 UTC [38319][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.058 user=admin database=postgres host=[local] 2024-03-10 05:43:30.699 UTC [38351][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:30.785 UTC [38351][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-10 05:43:30.785 UTC [38351][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:30.786 UTC [38351][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-10 05:43:30.909 UTC [38351][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.210 user=admin database=postgres host=[local] 2024-03-10 05:43:31.016 UTC [38388][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:31.017 UTC [38388][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-10 05:43:31.017 UTC [38388][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:31.017 UTC [38388][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-10 05:43:31.100 UTC [38388][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.084 user=admin database=postgres host=[local] 2024-03-10 05:43:31.154 UTC [38412][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-10 05:43:31.154 UTC [38412][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-10 05:43:31.154 UTC [38412][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-10 05:43:31.154 UTC [38412][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-10 05:43:31.342 UTC [38412][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.188 user=admin database=postgres host=[local] 2024-03-10 05:43:32.882 UTC [38190][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:32.882 UTC [38190][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:32.883 UTC [38190][postmaster] LOG: background worker "logical replication launcher" (PID 38207) exited with exit code 1 2024-03-10 05:43:32.883 UTC [38200][checkpointer] LOG: shutting down 2024-03-10 05:43:32.883 UTC [38200][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:33.564 UTC [38200][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.681 s, sync=0.001 s, total=0.681 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4104958, redo lsn=0/4104958 2024-03-10 05:43:33.575 UTC [38190][postmaster] LOG: database system is shut down 2024-03-10 05:43:33.772 UTC [38641][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:33.772 UTC [38641][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:33.772 UTC [38641][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:33.775 UTC [38649][startup] LOG: database system was shut down at 2024-03-10 05:43:33 UTC 2024-03-10 05:43:33.776 UTC [38641][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:33.886 UTC [38641][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:33.886 UTC [38641][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:33.886 UTC [38641][postmaster] LOG: background worker "logical replication launcher" (PID 38654) exited with exit code 1 2024-03-10 05:43:33.887 UTC [38646][checkpointer] LOG: shutting down 2024-03-10 05:43:33.887 UTC [38646][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:33.929 UTC [38646][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.043 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41049D0, redo lsn=0/41049D0 2024-03-10 05:43:33.930 UTC [38641][postmaster] LOG: database system is shut down 2024-03-10 05:43:34.018 UTC [38661][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-10 05:43:34.028 UTC [38661][postmaster] LOG: database system is shut down 2024-03-10 05:43:34.299 UTC [38665][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:34.299 UTC [38665][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:34.299 UTC [38665][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:34.301 UTC [38682][startup] LOG: database system was shut down at 2024-03-10 05:43:33 UTC 2024-03-10 05:43:34.302 UTC [38665][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:34.348 UTC [38665][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:34.348 UTC [38665][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:34.349 UTC [38665][postmaster] LOG: background worker "logical replication launcher" (PID 38685) exited with exit code 1 2024-03-10 05:43:34.349 UTC [38680][checkpointer] LOG: shutting down 2024-03-10 05:43:34.349 UTC [38680][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:34.350 UTC [38680][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/4104A48, redo lsn=0/4104A48 2024-03-10 05:43:34.351 UTC [38665][postmaster] LOG: database system is shut down 2024-03-10 05:43:34.474 UTC [38691][postmaster] FATAL: could not set SSL protocol version range 2024-03-10 05:43:34.474 UTC [38691][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-10 05:43:34.474 UTC [38691][postmaster] LOG: database system is shut down 2024-03-10 05:43:34.796 UTC [38708][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:34.796 UTC [38708][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:34.796 UTC [38708][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:34.802 UTC [38721][startup] LOG: database system was shut down at 2024-03-10 05:43:34 UTC 2024-03-10 05:43:34.803 UTC [38708][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:34.909 UTC [38708][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:34.909 UTC [38708][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:34.910 UTC [38708][postmaster] LOG: background worker "logical replication launcher" (PID 38725) exited with exit code 1 2024-03-10 05:43:34.910 UTC [38717][checkpointer] LOG: shutting down 2024-03-10 05:43:34.910 UTC [38717][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:34.911 UTC [38717][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/4104AC0, redo lsn=0/4104AC0 2024-03-10 05:43:34.912 UTC [38708][postmaster] LOG: database system is shut down 2024-03-10 05:43:35.031 UTC [38758][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:35.031 UTC [38758][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:35.031 UTC [38758][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:35.034 UTC [38769][startup] LOG: database system was shut down at 2024-03-10 05:43:34 UTC 2024-03-10 05:43:35.035 UTC [38758][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:35.136 UTC [38777][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49257 2024-03-10 05:43:35.139 UTC [38777][client backend] [[unknown]][0/1:0] FATAL: no pg_hba.conf entry for host "127.0.0.1", user "ssltestuser", database "trustdb", no encryption 2024-03-10 05:43:35.139 UTC [38777][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-03-10 05:43:35.151 UTC [38787][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49258 2024-03-10 05:43:35.159 UTC [38787][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-10 05:43:35.159 UTC [38787][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:35.161 UTC [38787][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid sslmode=require$$ 2024-03-10 05:43:35.161 UTC [38787][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49258 2024-03-10 05:43:35.172 UTC [38797][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49260 2024-03-10 05:43:35.177 UTC [38797][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-10 05:43:35.187 UTC [38800][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49262 2024-03-10 05:43:35.192 UTC [38800][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-10 05:43:35.202 UTC [38804][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49264 2024-03-10 05:43:35.210 UTC [38804][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-10 05:43:35.220 UTC [38808][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49266 2024-03-10 05:43:35.226 UTC [38808][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-10 05:43:35.238 UTC [38813][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49268 2024-03-10 05:43:35.245 UTC [38813][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-10 05:43:35.258 UTC [38819][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49270 2024-03-10 05:43:35.266 UTC [38819][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-10 05:43:35.277 UTC [38825][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49273 2024-03-10 05:43:35.285 UTC [38825][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-10 05:43:35.285 UTC [38825][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:35.286 UTC [38825][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require$$ 2024-03-10 05:43:35.286 UTC [38825][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49273 2024-03-10 05:43:35.296 UTC [38828][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49275 2024-03-10 05:43:35.304 UTC [38828][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-10 05:43:35.304 UTC [38828][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:35.304 UTC [38828][client backend] [001_ssltests.pl][3/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$ 2024-03-10 05:43:35.304 UTC [38828][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49275 2024-03-10 05:43:35.314 UTC [38830][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49276 2024-03-10 05:43:35.321 UTC [38830][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-10 05:43:35.321 UTC [38830][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:35.322 UTC [38830][client backend] [001_ssltests.pl][4/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-full$$ 2024-03-10 05:43:35.322 UTC [38830][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49276 2024-03-10 05:43:35.333 UTC [38833][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49277 2024-03-10 05:43:35.341 UTC [38833][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-10 05:43:35.341 UTC [38833][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:35.342 UTC [38833][client backend] [001_ssltests.pl][5/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-1.crt sslmode=verify-ca$$ 2024-03-10 05:43:35.342 UTC [38833][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49277 2024-03-10 05:43:35.353 UTC [38838][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49280 2024-03-10 05:43:35.361 UTC [38838][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-10 05:43:35.361 UTC [38838][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:35.362 UTC [38838][client backend] [001_ssltests.pl][6/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/both-cas-2.crt sslmode=verify-ca$$ 2024-03-10 05:43:35.362 UTC [38838][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49280 2024-03-10 05:43:35.378 UTC [38842][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49281 2024-03-10 05:43:35.387 UTC [38842][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-10 05:43:35.387 UTC [38842][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:35.388 UTC [38842][client backend] [001_ssltests.pl][7/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=disable$$ 2024-03-10 05:43:35.388 UTC [38842][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49281 2024-03-10 05:43:35.404 UTC [38850][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49283 2024-03-10 05:43:35.412 UTC [38850][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-10 05:43:35.412 UTC [38850][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:35.413 UTC [38850][client backend] [001_ssltests.pl][8/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require sslcertmode=allow$$ 2024-03-10 05:43:35.413 UTC [38850][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49283 2024-03-10 05:43:35.428 UTC [38856][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49285 2024-03-10 05:43:35.436 UTC [38856][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-10 05:43:35.436 UTC [38856][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:35.437 UTC [38856][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49285 2024-03-10 05:43:35.453 UTC [38860][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49287 2024-03-10 05:43:35.463 UTC [38860][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-10 05:43:35.463 UTC [38860][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:35.463 UTC [38860][client backend] [001_ssltests.pl][10/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=invalid$$ 2024-03-10 05:43:35.463 UTC [38860][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49287 2024-03-10 05:43:35.479 UTC [38864][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49289 2024-03-10 05:43:35.487 UTC [38864][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-10 05:43:35.500 UTC [38870][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49292 2024-03-10 05:43:35.508 UTC [38870][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-10 05:43:35.522 UTC [38875][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49294 2024-03-10 05:43:35.530 UTC [38875][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-10 05:43:35.530 UTC [38875][client backend] [[unknown]][11/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:35.531 UTC [38875][client backend] [001_ssltests.pl][11/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrl=ssl/root+server.crl$$ 2024-03-10 05:43:35.531 UTC [38875][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49294 2024-03-10 05:43:35.541 UTC [38879][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49296 2024-03-10 05:43:35.549 UTC [38879][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-10 05:43:35.549 UTC [38879][client backend] [[unknown]][12/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:35.550 UTC [38879][client backend] [001_ssltests.pl][12/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslcrldir=ssl/root+server-crldir$$ 2024-03-10 05:43:35.550 UTC [38879][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49296 2024-03-10 05:43:35.560 UTC [38881][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49297 2024-03-10 05:43:35.567 UTC [38881][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-10 05:43:35.567 UTC [38881][client backend] [[unknown]][13/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:35.568 UTC [38881][client backend] [001_ssltests.pl][13/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=require host=wronghost.test$$ 2024-03-10 05:43:35.568 UTC [38881][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49297 2024-03-10 05:43:35.577 UTC [38883][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49298 2024-03-10 05:43:35.585 UTC [38883][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-10 05:43:35.585 UTC [38883][client backend] [[unknown]][14/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:35.585 UTC [38883][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=wronghost.test$$ 2024-03-10 05:43:35.586 UTC [38883][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49298 2024-03-10 05:43:35.596 UTC [38885][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49299 2024-03-10 05:43:35.607 UTC [38758][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:35.607 UTC [38758][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:35.608 UTC [38758][postmaster] LOG: background worker "logical replication launcher" (PID 38772) exited with exit code 1 2024-03-10 05:43:35.608 UTC [38767][checkpointer] LOG: shutting down 2024-03-10 05:43:35.608 UTC [38767][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:35.609 UTC [38767][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/4104B38, redo lsn=0/4104B38 2024-03-10 05:43:35.610 UTC [38758][postmaster] LOG: database system is shut down 2024-03-10 05:43:36.174 UTC [38894][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:36.175 UTC [38894][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:36.175 UTC [38894][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:36.181 UTC [38899][startup] LOG: database system was shut down at 2024-03-10 05:43:35 UTC 2024-03-10 05:43:36.185 UTC [38894][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:36.275 UTC [38905][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49301 2024-03-10 05:43:36.283 UTC [38905][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-10 05:43:36.283 UTC [38905][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:36.285 UTC [38905][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-10 05:43:36.285 UTC [38905][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49301 2024-03-10 05:43:36.295 UTC [38907][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49302 2024-03-10 05:43:36.311 UTC [38894][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:36.311 UTC [38894][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:36.312 UTC [38894][postmaster] LOG: background worker "logical replication launcher" (PID 38903) exited with exit code 1 2024-03-10 05:43:36.312 UTC [38897][checkpointer] LOG: shutting down 2024-03-10 05:43:36.312 UTC [38897][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:36.313 UTC [38897][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/4104BB0, redo lsn=0/4104BB0 2024-03-10 05:43:36.314 UTC [38894][postmaster] LOG: database system is shut down 2024-03-10 05:43:36.429 UTC [38909][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:36.430 UTC [38909][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:36.430 UTC [38909][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:36.432 UTC [38912][startup] LOG: database system was shut down at 2024-03-10 05:43:36 UTC 2024-03-10 05:43:36.433 UTC [38909][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:36.539 UTC [38917][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49303 2024-03-10 05:43:36.548 UTC [38917][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-10 05:43:36.548 UTC [38917][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:36.550 UTC [38917][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-10 05:43:36.550 UTC [38917][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49303 2024-03-10 05:43:36.555 UTC [38909][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:36.555 UTC [38909][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:36.555 UTC [38909][postmaster] LOG: background worker "logical replication launcher" (PID 38915) exited with exit code 1 2024-03-10 05:43:36.555 UTC [38910][checkpointer] LOG: shutting down 2024-03-10 05:43:36.555 UTC [38910][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:36.556 UTC [38910][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/4104C28, redo lsn=0/4104C28 2024-03-10 05:43:36.557 UTC [38909][postmaster] LOG: database system is shut down 2024-03-10 05:43:36.681 UTC [38919][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:36.681 UTC [38919][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:36.682 UTC [38919][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:36.684 UTC [38922][startup] LOG: database system was shut down at 2024-03-10 05:43:36 UTC 2024-03-10 05:43:36.685 UTC [38919][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:36.776 UTC [38927][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49304 2024-03-10 05:43:36.784 UTC [38927][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-10 05:43:36.784 UTC [38927][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:36.786 UTC [38927][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-03-10 05:43:36.787 UTC [38927][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49304 2024-03-10 05:43:36.796 UTC [38929][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49305 2024-03-10 05:43:36.804 UTC [38929][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-10 05:43:36.804 UTC [38929][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:36.804 UTC [38929][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-03-10 05:43:36.804 UTC [38929][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49305 2024-03-10 05:43:36.814 UTC [38931][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49306 2024-03-10 05:43:36.822 UTC [38931][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-10 05:43:36.822 UTC [38931][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:36.822 UTC [38931][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=foo.wildcard.pg-ssltest.test$$ 2024-03-10 05:43:36.822 UTC [38931][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49306 2024-03-10 05:43:36.832 UTC [38933][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49307 2024-03-10 05:43:36.850 UTC [38935][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49308 2024-03-10 05:43:36.862 UTC [38919][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:36.862 UTC [38919][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:36.862 UTC [38919][postmaster] LOG: background worker "logical replication launcher" (PID 38925) exited with exit code 1 2024-03-10 05:43:36.862 UTC [38920][checkpointer] LOG: shutting down 2024-03-10 05:43:36.862 UTC [38920][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:36.863 UTC [38920][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/4104CA0, redo lsn=0/4104CA0 2024-03-10 05:43:36.864 UTC [38919][postmaster] LOG: database system is shut down 2024-03-10 05:43:36.986 UTC [38937][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:36.987 UTC [38937][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:36.987 UTC [38937][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:36.989 UTC [38940][startup] LOG: database system was shut down at 2024-03-10 05:43:36 UTC 2024-03-10 05:43:36.990 UTC [38937][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:37.093 UTC [38945][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49309 2024-03-10 05:43:37.101 UTC [38945][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-10 05:43:37.101 UTC [38945][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:37.103 UTC [38945][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=single.alt-name.pg-ssltest.test$$ 2024-03-10 05:43:37.103 UTC [38945][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49309 2024-03-10 05:43:37.113 UTC [38947][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49310 2024-03-10 05:43:37.129 UTC [38949][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49311 2024-03-10 05:43:37.145 UTC [38937][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:37.145 UTC [38937][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:37.145 UTC [38937][postmaster] LOG: background worker "logical replication launcher" (PID 38943) exited with exit code 1 2024-03-10 05:43:37.145 UTC [38938][checkpointer] LOG: shutting down 2024-03-10 05:43:37.145 UTC [38938][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:37.146 UTC [38938][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/4104D18, redo lsn=0/4104D18 2024-03-10 05:43:37.147 UTC [38937][postmaster] LOG: database system is shut down 2024-03-10 05:43:37.273 UTC [38952][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:37.273 UTC [38952][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:37.274 UTC [38952][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:37.276 UTC [38955][startup] LOG: database system was shut down at 2024-03-10 05:43:37 UTC 2024-03-10 05:43:37.277 UTC [38952][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:37.378 UTC [38960][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49312 2024-03-10 05:43:37.387 UTC [38960][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-10 05:43:37.387 UTC [38960][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:37.389 UTC [38960][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-10 05:43:37.389 UTC [38960][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49312 2024-03-10 05:43:37.399 UTC [38962][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49313 2024-03-10 05:43:37.406 UTC [38962][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-10 05:43:37.406 UTC [38962][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:37.406 UTC [38962][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.000.002.001$$ 2024-03-10 05:43:37.407 UTC [38962][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49313 2024-03-10 05:43:37.416 UTC [38964][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49314 2024-03-10 05:43:37.433 UTC [38966][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49315 2024-03-10 05:43:37.441 UTC [38966][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-10 05:43:37.441 UTC [38966][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:37.442 UTC [38966][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:DB8::1$$ 2024-03-10 05:43:37.442 UTC [38966][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49315 2024-03-10 05:43:37.452 UTC [38968][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49316 2024-03-10 05:43:37.460 UTC [38968][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-10 05:43:37.460 UTC [38968][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:37.461 UTC [38968][client backend] [001_ssltests.pl][3/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8:0:0:0:0:0:1$$ 2024-03-10 05:43:37.461 UTC [38968][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49316 2024-03-10 05:43:37.471 UTC [38970][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49317 2024-03-10 05:43:37.479 UTC [38970][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-10 05:43:37.480 UTC [38970][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:37.480 UTC [38970][client backend] [001_ssltests.pl][4/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::0.0.0.1$$ 2024-03-10 05:43:37.480 UTC [38970][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49317 2024-03-10 05:43:37.490 UTC [38972][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49318 2024-03-10 05:43:37.508 UTC [38974][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49319 2024-03-10 05:43:37.519 UTC [38952][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:37.519 UTC [38952][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:37.520 UTC [38952][postmaster] LOG: background worker "logical replication launcher" (PID 38958) exited with exit code 1 2024-03-10 05:43:37.520 UTC [38953][checkpointer] LOG: shutting down 2024-03-10 05:43:37.520 UTC [38953][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:37.521 UTC [38953][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/4104D90, redo lsn=0/4104D90 2024-03-10 05:43:37.522 UTC [38952][postmaster] LOG: database system is shut down 2024-03-10 05:43:37.645 UTC [38976][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:37.646 UTC [38976][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:37.646 UTC [38976][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:37.648 UTC [38979][startup] LOG: database system was shut down at 2024-03-10 05:43:37 UTC 2024-03-10 05:43:37.649 UTC [38976][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:37.750 UTC [38984][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49320 2024-03-10 05:43:37.758 UTC [38984][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-10 05:43:37.758 UTC [38984][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:37.760 UTC [38984][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-03-10 05:43:37.760 UTC [38984][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49320 2024-03-10 05:43:37.770 UTC [38986][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49321 2024-03-10 05:43:37.778 UTC [38986][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-10 05:43:37.778 UTC [38986][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:37.779 UTC [38986][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-03-10 05:43:37.779 UTC [38986][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49321 2024-03-10 05:43:37.789 UTC [38988][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49322 2024-03-10 05:43:37.804 UTC [38976][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:37.804 UTC [38976][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:37.805 UTC [38976][postmaster] LOG: background worker "logical replication launcher" (PID 38982) exited with exit code 1 2024-03-10 05:43:37.805 UTC [38977][checkpointer] LOG: shutting down 2024-03-10 05:43:37.805 UTC [38977][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:37.806 UTC [38977][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/4104E08, redo lsn=0/4104E08 2024-03-10 05:43:37.806 UTC [38976][postmaster] LOG: database system is shut down 2024-03-10 05:43:37.935 UTC [38991][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:37.935 UTC [38991][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:37.935 UTC [38991][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:37.937 UTC [38994][startup] LOG: database system was shut down at 2024-03-10 05:43:37 UTC 2024-03-10 05:43:37.938 UTC [38991][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:38.048 UTC [38999][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49323 2024-03-10 05:43:38.057 UTC [38999][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-10 05:43:38.057 UTC [38999][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:38.060 UTC [38999][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$ 2024-03-10 05:43:38.061 UTC [38999][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=49323 2024-03-10 05:43:38.071 UTC [39001][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49324 2024-03-10 05:43:38.079 UTC [39001][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-10 05:43:38.079 UTC [39001][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:38.080 UTC [39001][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-10 05:43:38.080 UTC [39001][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49324 2024-03-10 05:43:38.089 UTC [39003][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49325 2024-03-10 05:43:38.097 UTC [39003][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-10 05:43:38.097 UTC [39003][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:38.097 UTC [39003][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$ 2024-03-10 05:43:38.098 UTC [39003][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49325 2024-03-10 05:43:38.102 UTC [38991][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:38.102 UTC [38991][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:38.102 UTC [38991][postmaster] LOG: background worker "logical replication launcher" (PID 38997) exited with exit code 1 2024-03-10 05:43:38.102 UTC [38992][checkpointer] LOG: shutting down 2024-03-10 05:43:38.102 UTC [38992][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:38.103 UTC [38992][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/4104E80, redo lsn=0/4104E80 2024-03-10 05:43:38.104 UTC [38991][postmaster] LOG: database system is shut down 2024-03-10 05:43:38.237 UTC [39005][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:38.238 UTC [39005][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:38.238 UTC [39005][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:38.240 UTC [39008][startup] LOG: database system was shut down at 2024-03-10 05:43:38 UTC 2024-03-10 05:43:38.242 UTC [39005][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:38.339 UTC [39013][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49326 2024-03-10 05:43:38.349 UTC [39013][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-10 05:43:38.349 UTC [39013][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:38.352 UTC [39013][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.2$$ 2024-03-10 05:43:38.352 UTC [39013][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=49326 2024-03-10 05:43:38.363 UTC [39015][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49327 2024-03-10 05:43:38.371 UTC [39015][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-10 05:43:38.371 UTC [39015][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:38.371 UTC [39015][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=2001:db8::1$$ 2024-03-10 05:43:38.371 UTC [39015][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49327 2024-03-10 05:43:38.381 UTC [39017][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49328 2024-03-10 05:43:38.392 UTC [39005][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:38.392 UTC [39005][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:38.392 UTC [39005][postmaster] LOG: background worker "logical replication launcher" (PID 39011) exited with exit code 1 2024-03-10 05:43:38.392 UTC [39006][checkpointer] LOG: shutting down 2024-03-10 05:43:38.392 UTC [39006][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:38.393 UTC [39006][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/4104EF8, redo lsn=0/4104EF8 2024-03-10 05:43:38.394 UTC [39005][postmaster] LOG: database system is shut down 2024-03-10 05:43:38.513 UTC [39019][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:38.513 UTC [39019][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:38.513 UTC [39019][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:38.516 UTC [39024][startup] LOG: database system was shut down at 2024-03-10 05:43:38 UTC 2024-03-10 05:43:38.518 UTC [39019][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:38.605 UTC [39030][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49329 2024-03-10 05:43:38.613 UTC [39030][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-10 05:43:38.613 UTC [39030][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:38.615 UTC [39030][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-10 05:43:38.615 UTC [39030][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49329 2024-03-10 05:43:38.625 UTC [39032][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49330 2024-03-10 05:43:38.632 UTC [39032][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-10 05:43:38.632 UTC [39032][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:38.632 UTC [39032][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-03-10 05:43:38.632 UTC [39032][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49330 2024-03-10 05:43:38.641 UTC [39034][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49331 2024-03-10 05:43:38.649 UTC [39034][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-10 05:43:38.649 UTC [39034][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:38.649 UTC [39034][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-03-10 05:43:38.649 UTC [39034][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49331 2024-03-10 05:43:38.653 UTC [39019][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:38.653 UTC [39019][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:38.654 UTC [39019][postmaster] LOG: background worker "logical replication launcher" (PID 39027) exited with exit code 1 2024-03-10 05:43:38.654 UTC [39022][checkpointer] LOG: shutting down 2024-03-10 05:43:38.654 UTC [39022][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:38.655 UTC [39022][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/4104F70, redo lsn=0/4104F70 2024-03-10 05:43:38.656 UTC [39019][postmaster] LOG: database system is shut down 2024-03-10 05:43:38.796 UTC [39036][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:38.796 UTC [39036][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:38.796 UTC [39036][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:38.799 UTC [39041][startup] LOG: database system was shut down at 2024-03-10 05:43:38 UTC 2024-03-10 05:43:38.801 UTC [39036][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:38.890 UTC [39046][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49332 2024-03-10 05:43:38.898 UTC [39046][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-10 05:43:38.898 UTC [39046][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:38.901 UTC [39046][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-ca host=common-name.pg-ssltest.test$$ 2024-03-10 05:43:38.901 UTC [39046][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49332 2024-03-10 05:43:38.912 UTC [39048][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49333 2024-03-10 05:43:38.923 UTC [39036][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:38.924 UTC [39036][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:38.924 UTC [39036][postmaster] LOG: background worker "logical replication launcher" (PID 39044) exited with exit code 1 2024-03-10 05:43:38.924 UTC [39039][checkpointer] LOG: shutting down 2024-03-10 05:43:38.924 UTC [39039][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:38.925 UTC [39039][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/4104FE8, redo lsn=0/4104FE8 2024-03-10 05:43:38.926 UTC [39036][postmaster] LOG: database system is shut down 2024-03-10 05:43:39.060 UTC [39050][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:39.061 UTC [39050][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:39.063 UTC [39050][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:39.065 UTC [39053][startup] LOG: database system was shut down at 2024-03-10 05:43:38 UTC 2024-03-10 05:43:39.066 UTC [39050][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:39.160 UTC [39058][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49334 2024-03-10 05:43:39.169 UTC [39058][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-10 05:43:39.190 UTC [39061][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49335 2024-03-10 05:43:39.197 UTC [39061][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-10 05:43:39.197 UTC [39061][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:39.200 UTC [39061][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=system hostaddr=127.0.0.1 sslmode=verify-full host=common-name.pg-ssltest.test$$ 2024-03-10 05:43:39.200 UTC [39061][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49335 2024-03-10 05:43:39.210 UTC [39063][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49336 2024-03-10 05:43:39.221 UTC [39050][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:39.221 UTC [39050][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:39.222 UTC [39050][postmaster] LOG: background worker "logical replication launcher" (PID 39056) exited with exit code 1 2024-03-10 05:43:39.222 UTC [39051][checkpointer] LOG: shutting down 2024-03-10 05:43:39.222 UTC [39051][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:39.223 UTC [39051][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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4105060, redo lsn=0/4105060 2024-03-10 05:43:39.224 UTC [39050][postmaster] LOG: database system is shut down 2024-03-10 05:43:39.357 UTC [39065][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:39.358 UTC [39065][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:39.358 UTC [39065][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:39.360 UTC [39068][startup] LOG: database system was shut down at 2024-03-10 05:43:39 UTC 2024-03-10 05:43:39.361 UTC [39065][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:39.468 UTC [39073][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49337 2024-03-10 05:43:39.479 UTC [39073][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-10 05:43:39.479 UTC [39073][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:39.482 UTC [39073][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$ 2024-03-10 05:43:39.482 UTC [39073][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=49337 2024-03-10 05:43:39.494 UTC [39075][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49338 2024-03-10 05:43:39.502 UTC [39075][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: ssl/tls alert certificate revoked 2024-03-10 05:43:39.511 UTC [39077][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49339 2024-03-10 05:43:39.518 UTC [39077][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: ssl/tls alert certificate revoked 2024-03-10 05:43:39.527 UTC [39079][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49340 2024-03-10 05:43:39.534 UTC [39079][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-10 05:43:39.534 UTC [39079][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:39.535 UTC [39079][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-10 05:43:39.535 UTC [39079][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49340 2024-03-10 05:43:39.544 UTC [39081][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49341 2024-03-10 05:43:39.552 UTC [39081][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-10 05:43:39.552 UTC [39081][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.2, cipher=ECDHE-RSA-AES256-GCM-SHA384, bits=256) 2024-03-10 05:43:39.552 UTC [39081][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=require ssl_min_protocol_version=TLSv1.2 ssl_max_protocol_version=TLSv1.2$$ 2024-03-10 05:43:39.552 UTC [39081][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49341 2024-03-10 05:43:39.584 UTC [39086][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49342 2024-03-10 05:43:39.591 UTC [39086][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-03-10 05:43:39.600 UTC [39088][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49343 2024-03-10 05:43:39.609 UTC [39088][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-10 05:43:39.609 UTC [39088][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:39.612 UTC [39088][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_5fMi/client.key$$ 2024-03-10 05:43:39.612 UTC [39088][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=49343 2024-03-10 05:43:39.621 UTC [39090][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49344 2024-03-10 05:43:39.630 UTC [39090][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-10 05:43:39.630 UTC [39090][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:39.630 UTC [39090][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_5fMi/client-der.key$$ 2024-03-10 05:43:39.630 UTC [39090][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49344 2024-03-10 05:43:39.639 UTC [39092][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49345 2024-03-10 05:43:39.648 UTC [39092][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-10 05:43:39.648 UTC [39092][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:39.648 UTC [39092][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_5fMi/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-03-10 05:43:39.649 UTC [39092][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49345 2024-03-10 05:43:39.658 UTC [39094][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49346 2024-03-10 05:43:39.666 UTC [39094][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-10 05:43:39.666 UTC [39094][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:39.666 UTC [39094][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_5fMi/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-03-10 05:43:39.666 UTC [39094][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49346 2024-03-10 05:43:39.675 UTC [39096][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49347 2024-03-10 05:43:39.684 UTC [39096][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-10 05:43:39.684 UTC [39096][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:39.684 UTC [39096][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_5fMi/client.key$$ 2024-03-10 05:43:39.684 UTC [39096][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49347 2024-03-10 05:43:39.693 UTC [39098][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49348 2024-03-10 05:43:39.702 UTC [39098][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-10 05:43:39.702 UTC [39098][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:39.702 UTC [39098][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_5fMi/client.key$$ 2024-03-10 05:43:39.702 UTC [39098][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49348 2024-03-10 05:43:39.711 UTC [39100][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49349 2024-03-10 05:43:39.719 UTC [39100][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-03-10 05:43:39.727 UTC [39102][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49350 2024-03-10 05:43:39.735 UTC [39102][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-10 05:43:39.745 UTC [39104][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49351 2024-03-10 05:43:39.760 UTC [39104][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-10 05:43:39.760 UTC [39104][client backend] [[unknown]][11/1:0] LOG: connection authorized: user=ssltestuser database=certdb_dn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:39.763 UTC [39104][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_5fMi/client-dn.key$$ 2024-03-10 05:43:39.763 UTC [39104][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=certdb_dn host=localhost port=49351 2024-03-10 05:43:39.775 UTC [39106][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49352 2024-03-10 05:43:39.784 UTC [39106][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-10 05:43:39.784 UTC [39106][client backend] [[unknown]][12/1:0] LOG: connection authorized: user=ssltestuser database=certdb_dn_re application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:39.786 UTC [39106][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_5fMi/client-dn.key$$ 2024-03-10 05:43:39.786 UTC [39106][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-10 05:43:39.796 UTC [39108][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49353 2024-03-10 05:43:39.804 UTC [39108][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-10 05:43:39.804 UTC [39108][client backend] [[unknown]][13/1:0] LOG: connection authorized: user=ssltestuser database=certdb_cn application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:39.806 UTC [39108][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_5fMi/client-dn.key$$ 2024-03-10 05:43:39.807 UTC [39108][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-10 05:43:39.825 UTC [39111][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49354 2024-03-10 05:43:39.834 UTC [39111][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-10 05:43:39.834 UTC [39111][client backend] [[unknown]][14/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:39.834 UTC [39111][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-10 05:43:39.835 UTC [39111][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=49354 2024-03-10 05:43:39.844 UTC [39113][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49355 2024-03-10 05:43:39.850 UTC [39113][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-10 05:43:39.858 UTC [39115][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49356 2024-03-10 05:43:39.867 UTC [39115][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-10 05:43:39.867 UTC [39115][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-10 05:43:39.867 UTC [39115][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-03-10 05:43:39.867 UTC [39115][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-10 05:43:39.876 UTC [39117][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49357 2024-03-10 05:43:39.884 UTC [39117][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-10 05:43:39.884 UTC [39117][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-10 05:43:39.893 UTC [39119][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49358 2024-03-10 05:43:39.902 UTC [39119][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-10 05:43:39.902 UTC [39119][client backend] [[unknown]][16/1:0] LOG: connection authorized: user=ssltestuser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:39.904 UTC [39119][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_5fMi/client.key$$ 2024-03-10 05:43:39.904 UTC [39119][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=verifydb host=localhost port=49358 2024-03-10 05:43:39.914 UTC [39121][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49359 2024-03-10 05:43:39.923 UTC [39121][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-10 05:43:39.923 UTC [39121][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-03-10 05:43:39.923 UTC [39121][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-03-10 05:43:39.923 UTC [39121][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-10 05:43:39.932 UTC [39123][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49360 2024-03-10 05:43:39.940 UTC [39123][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-10 05:43:39.940 UTC [39123][client backend] [[unknown]][18/1:0] LOG: connection authorized: user=yetanotheruser database=verifydb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:39.941 UTC [39123][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_5fMi/client.key$$ 2024-03-10 05:43:39.941 UTC [39123][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=yetanotheruser database=verifydb host=localhost port=49360 2024-03-10 05:43:39.945 UTC [39065][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:39.945 UTC [39065][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:39.946 UTC [39065][postmaster] LOG: background worker "logical replication launcher" (PID 39071) exited with exit code 1 2024-03-10 05:43:39.946 UTC [39066][checkpointer] LOG: shutting down 2024-03-10 05:43:39.946 UTC [39066][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:39.948 UTC [39066][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.003 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41050D8, redo lsn=0/41050D8 2024-03-10 05:43:39.949 UTC [39065][postmaster] LOG: database system is shut down 2024-03-10 05:43:40.084 UTC [39125][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:40.084 UTC [39125][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:40.084 UTC [39125][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:40.087 UTC [39128][startup] LOG: database system was shut down at 2024-03-10 05:43:39 UTC 2024-03-10 05:43:40.088 UTC [39125][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:40.182 UTC [39133][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49361 2024-03-10 05:43:40.195 UTC [39133][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-10 05:43:40.195 UTC [39133][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=certdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:40.197 UTC [39133][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_5fMi/client.key sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 host=localhost sslmode=require sslcert=ssl/client+client_ca.crt$$ 2024-03-10 05:43:40.197 UTC [39133][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.015 user=ssltestuser database=certdb host=localhost port=49361 2024-03-10 05:43:40.208 UTC [39135][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49362 2024-03-10 05:43:40.216 UTC [39135][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-10 05:43:40.216 UTC [39135][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: unable to get local issuer certificate. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921344, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-10 05:43:40.225 UTC [39137][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49363 2024-03-10 05:43:40.233 UTC [39137][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-10 05:43:40.233 UTC [39137][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: unable to get local issuer certificate. Failed certificate data (unverified): subject ".../CN=ssl-123456789012345678901234567890123456789012345678901234567890", serial number 2315702411956921348, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-10 05:43:40.237 UTC [39125][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:40.237 UTC [39125][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:40.238 UTC [39125][postmaster] LOG: background worker "logical replication launcher" (PID 39131) exited with exit code 1 2024-03-10 05:43:40.238 UTC [39126][checkpointer] LOG: shutting down 2024-03-10 05:43:40.238 UTC [39126][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:40.240 UTC [39126][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4105150, redo lsn=0/4105150 2024-03-10 05:43:40.241 UTC [39125][postmaster] LOG: database system is shut down 2024-03-10 05:43:40.361 UTC [39139][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:40.361 UTC [39139][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:40.361 UTC [39139][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:40.363 UTC [39142][startup] LOG: database system was shut down at 2024-03-10 05:43:40 UTC 2024-03-10 05:43:40.364 UTC [39139][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:40.459 UTC [39147][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49364 2024-03-10 05:43:40.468 UTC [39147][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-10 05:43:40.468 UTC [39147][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 1: unable to get local issuer certificate. Failed certificate data (unverified): subject "/CN=Test CA for PostgreSQL SSL regression test client certs", serial number 2315702411956921089, issuer "/CN=Test root CA for PostgreSQL SSL regression test suite". 2024-03-10 05:43:40.472 UTC [39139][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:40.473 UTC [39139][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:40.473 UTC [39139][postmaster] LOG: background worker "logical replication launcher" (PID 39145) exited with exit code 1 2024-03-10 05:43:40.473 UTC [39140][checkpointer] LOG: shutting down 2024-03-10 05:43:40.473 UTC [39140][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:40.474 UTC [39140][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41051C8, redo lsn=0/41051C8 2024-03-10 05:43:40.475 UTC [39139][postmaster] LOG: database system is shut down 2024-03-10 05:43:40.602 UTC [39149][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:40.603 UTC [39149][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:40.603 UTC [39149][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:40.605 UTC [39152][startup] LOG: database system was shut down at 2024-03-10 05:43:40 UTC 2024-03-10 05:43:40.607 UTC [39149][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:40.714 UTC [39157][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49365 2024-03-10 05:43:40.727 UTC [39157][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-10 05:43:40.727 UTC [39157][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=ssltestuser", serial number 2315702411956921346, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-10 05:43:40.741 UTC [39159][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49366 2024-03-10 05:43:40.750 UTC [39159][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-10 05:43:40.750 UTC [39159][not initialized] [[unknown]][:0] DETAIL: Client certificate verification failed at depth 0: certificate revoked. Failed certificate data (unverified): subject "/CN=\xce\x9f\xce\xb4\xcf\x85\xcf\x83\xcf\x83\xce\xad\xce\xb1\xcf\x82", serial number 2315702411956921349, issuer "/CN=Test CA for PostgreSQL SSL regression test client certs". 2024-03-10 05:43:40.755 UTC [39149][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:40.755 UTC [39149][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:40.755 UTC [39149][postmaster] LOG: background worker "logical replication launcher" (PID 39155) exited with exit code 1 2024-03-10 05:43:40.755 UTC [39150][checkpointer] LOG: shutting down 2024-03-10 05:43:40.755 UTC [39150][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:40.757 UTC [39150][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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4105240, redo lsn=0/4105240 2024-03-10 05:43:40.758 UTC [39149][postmaster] LOG: database system is shut down 2024-03-10 05:43:40.883 UTC [39161][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:40.883 UTC [39161][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:40.883 UTC [39161][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:40.887 UTC [39164][startup] LOG: database system was shut down at 2024-03-10 05:43:40 UTC 2024-03-10 05:43:40.888 UTC [39161][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:40.989 UTC [39169][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49367 2024-03-10 05:43:40.995 UTC [39169][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:43:40.995 UTC [39169][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:43:41.006 UTC [39171][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49368 2024-03-10 05:43:41.014 UTC [39171][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-10 05:43:41.014 UTC [39171][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:41.016 UTC [39171][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-10 05:43:41.016 UTC [39171][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49368 2024-03-10 05:43:41.021 UTC [39161][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:41.021 UTC [39161][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:41.022 UTC [39161][postmaster] LOG: background worker "logical replication launcher" (PID 39167) exited with exit code 1 2024-03-10 05:43:41.022 UTC [39162][checkpointer] LOG: shutting down 2024-03-10 05:43:41.022 UTC [39162][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:41.022 UTC [39162][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/41052B8, redo lsn=0/41052B8 2024-03-10 05:43:41.023 UTC [39161][postmaster] LOG: database system is shut down 2024-03-10 05:43:41.170 UTC [39173][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:41.170 UTC [39173][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:41.171 UTC [39173][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:41.176 UTC [39176][startup] LOG: database system was shut down at 2024-03-10 05:43:41 UTC 2024-03-10 05:43:41.177 UTC [39173][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:41.259 UTC [39181][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49369 2024-03-10 05:43:41.265 UTC [39181][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:43:41.265 UTC [39181][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:43:41.275 UTC [39183][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49370 2024-03-10 05:43:41.282 UTC [39183][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-10 05:43:41.282 UTC [39183][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:41.284 UTC [39183][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-10 05:43:41.284 UTC [39183][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49370 2024-03-10 05:43:41.289 UTC [39173][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:41.289 UTC [39173][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:41.290 UTC [39173][postmaster] LOG: background worker "logical replication launcher" (PID 39179) exited with exit code 1 2024-03-10 05:43:41.290 UTC [39174][checkpointer] LOG: shutting down 2024-03-10 05:43:41.290 UTC [39174][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:41.290 UTC [39174][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/4105330, redo lsn=0/4105330 2024-03-10 05:43:41.291 UTC [39173][postmaster] LOG: database system is shut down 2024-03-10 05:43:41.411 UTC [39185][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:41.411 UTC [39185][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:41.411 UTC [39185][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:41.414 UTC [39188][startup] LOG: database system was shut down at 2024-03-10 05:43:41 UTC 2024-03-10 05:43:41.415 UTC [39185][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:41.518 UTC [39193][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49371 2024-03-10 05:43:41.527 UTC [39193][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:43:41.527 UTC [39193][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:43:41.540 UTC [39195][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49372 2024-03-10 05:43:41.549 UTC [39195][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-10 05:43:41.549 UTC [39195][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:41.552 UTC [39195][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-10 05:43:41.552 UTC [39195][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49372 2024-03-10 05:43:41.557 UTC [39185][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:41.557 UTC [39185][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:41.557 UTC [39185][postmaster] LOG: background worker "logical replication launcher" (PID 39191) exited with exit code 1 2024-03-10 05:43:41.557 UTC [39186][checkpointer] LOG: shutting down 2024-03-10 05:43:41.557 UTC [39186][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:41.559 UTC [39186][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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41053A8, redo lsn=0/41053A8 2024-03-10 05:43:41.560 UTC [39185][postmaster] LOG: database system is shut down 2024-03-10 05:43:41.681 UTC [39197][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:41.681 UTC [39197][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:41.681 UTC [39197][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:41.684 UTC [39200][startup] LOG: database system was shut down at 2024-03-10 05:43:41 UTC 2024-03-10 05:43:41.685 UTC [39197][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:41.782 UTC [39205][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49373 2024-03-10 05:43:41.790 UTC [39205][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:43:41.790 UTC [39205][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:43:41.803 UTC [39207][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49374 2024-03-10 05:43:41.812 UTC [39207][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-10 05:43:41.812 UTC [39207][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:41.814 UTC [39207][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-10 05:43:41.814 UTC [39207][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49374 2024-03-10 05:43:41.819 UTC [39197][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:41.819 UTC [39197][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:41.820 UTC [39197][postmaster] LOG: background worker "logical replication launcher" (PID 39203) exited with exit code 1 2024-03-10 05:43:41.820 UTC [39198][checkpointer] LOG: shutting down 2024-03-10 05:43:41.820 UTC [39198][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:41.821 UTC [39198][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/4105420, redo lsn=0/4105420 2024-03-10 05:43:41.822 UTC [39197][postmaster] LOG: database system is shut down 2024-03-10 05:43:41.951 UTC [39209][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:41.952 UTC [39209][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:41.952 UTC [39209][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:41.954 UTC [39212][startup] LOG: database system was shut down at 2024-03-10 05:43:41 UTC 2024-03-10 05:43:41.955 UTC [39209][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:42.059 UTC [39217][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49375 2024-03-10 05:43:42.066 UTC [39217][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:43:42.066 UTC [39217][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:43:42.076 UTC [39219][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49376 2024-03-10 05:43:42.084 UTC [39219][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-10 05:43:42.084 UTC [39219][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:42.235 UTC [39219][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-10 05:43:42.236 UTC [39219][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.160 user=ssltestuser database=trustdb host=localhost port=49376 2024-03-10 05:43:42.241 UTC [39209][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:42.241 UTC [39209][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:42.242 UTC [39209][postmaster] LOG: background worker "logical replication launcher" (PID 39215) exited with exit code 1 2024-03-10 05:43:42.242 UTC [39210][checkpointer] LOG: shutting down 2024-03-10 05:43:42.242 UTC [39210][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:42.260 UTC [39210][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.019 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4105498, redo lsn=0/4105498 2024-03-10 05:43:42.261 UTC [39209][postmaster] LOG: database system is shut down 2024-03-10 05:43:42.372 UTC [39221][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:42.373 UTC [39221][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:42.392 UTC [39221][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:42.414 UTC [39224][startup] LOG: database system was shut down at 2024-03-10 05:43:42 UTC 2024-03-10 05:43:42.415 UTC [39221][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:42.466 UTC [39229][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49377 2024-03-10 05:43:42.471 UTC [39229][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:43:42.471 UTC [39229][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:43:42.482 UTC [39231][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49378 2024-03-10 05:43:42.489 UTC [39231][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-10 05:43:42.499 UTC [39231][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:42.617 UTC [39231][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-10 05:43:42.618 UTC [39231][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.135 user=ssltestuser database=trustdb host=localhost port=49378 2024-03-10 05:43:42.622 UTC [39221][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:42.623 UTC [39221][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:42.623 UTC [39221][postmaster] LOG: background worker "logical replication launcher" (PID 39227) exited with exit code 1 2024-03-10 05:43:42.623 UTC [39222][checkpointer] LOG: shutting down 2024-03-10 05:43:42.623 UTC [39222][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:42.661 UTC [39222][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.039 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4105510, redo lsn=0/4105510 2024-03-10 05:43:42.662 UTC [39221][postmaster] LOG: database system is shut down 2024-03-10 05:43:42.747 UTC [39233][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:42.748 UTC [39233][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:42.748 UTC [39233][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:42.750 UTC [39236][startup] LOG: database system was shut down at 2024-03-10 05:43:42 UTC 2024-03-10 05:43:42.751 UTC [39233][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:42.862 UTC [39241][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49379 2024-03-10 05:43:42.869 UTC [39241][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:43:42.869 UTC [39241][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:43:42.882 UTC [39243][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49380 2024-03-10 05:43:42.890 UTC [39243][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-10 05:43:42.890 UTC [39243][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:42.892 UTC [39243][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-10 05:43:42.892 UTC [39243][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49380 2024-03-10 05:43:42.897 UTC [39233][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:42.897 UTC [39233][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:42.898 UTC [39233][postmaster] LOG: background worker "logical replication launcher" (PID 39239) exited with exit code 1 2024-03-10 05:43:42.898 UTC [39234][checkpointer] LOG: shutting down 2024-03-10 05:43:42.898 UTC [39234][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:42.902 UTC [39234][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.005 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4105588, redo lsn=0/4105588 2024-03-10 05:43:42.903 UTC [39233][postmaster] LOG: database system is shut down 2024-03-10 05:43:43.020 UTC [39245][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:43.021 UTC [39245][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:43.021 UTC [39245][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:43.024 UTC [39248][startup] LOG: database system was shut down at 2024-03-10 05:43:42 UTC 2024-03-10 05:43:43.025 UTC [39245][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:43.126 UTC [39253][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49381 2024-03-10 05:43:43.133 UTC [39253][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:43:43.133 UTC [39253][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:43:43.144 UTC [39255][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49382 2024-03-10 05:43:43.152 UTC [39255][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-10 05:43:43.152 UTC [39255][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:43.155 UTC [39255][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-10 05:43:43.155 UTC [39255][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49382 2024-03-10 05:43:43.160 UTC [39245][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:43.160 UTC [39245][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:43.161 UTC [39245][postmaster] LOG: background worker "logical replication launcher" (PID 39251) exited with exit code 1 2024-03-10 05:43:43.161 UTC [39246][checkpointer] LOG: shutting down 2024-03-10 05:43:43.161 UTC [39246][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:43.163 UTC [39246][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4105600, redo lsn=0/4105600 2024-03-10 05:43:43.164 UTC [39245][postmaster] LOG: database system is shut down 2024-03-10 05:43:43.290 UTC [39257][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-10 05:43:43.291 UTC [39257][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63841 2024-03-10 05:43:43.291 UTC [39257][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/dY4yc1Wr2l/.s.PGSQL.63841" 2024-03-10 05:43:43.293 UTC [39260][startup] LOG: database system was shut down at 2024-03-10 05:43:43 UTC 2024-03-10 05:43:43.295 UTC [39257][postmaster] LOG: database system is ready to accept connections 2024-03-10 05:43:43.397 UTC [39265][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49383 2024-03-10 05:43:43.404 UTC [39265][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-10 05:43:43.404 UTC [39265][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-10 05:43:43.417 UTC [39267][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49384 2024-03-10 05:43:43.425 UTC [39267][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-10 05:43:43.425 UTC [39267][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-10 05:43:43.428 UTC [39267][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=ocsp-good.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca sslocspstapling=0$$ 2024-03-10 05:43:43.428 UTC [39267][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49384 2024-03-10 05:43:43.433 UTC [39257][postmaster] LOG: received fast shutdown request 2024-03-10 05:43:43.433 UTC [39257][postmaster] LOG: aborting any active transactions 2024-03-10 05:43:43.434 UTC [39257][postmaster] LOG: background worker "logical replication launcher" (PID 39263) exited with exit code 1 2024-03-10 05:43:43.434 UTC [39258][checkpointer] LOG: shutting down 2024-03-10 05:43:43.435 UTC [39258][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-10 05:43:43.436 UTC [39258][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4105678, redo lsn=0/4105678 2024-03-10 05:43:43.437 UTC [39257][postmaster] LOG: database system is shut down 2024-03-10 05:43:43.559 UTC [39269][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-03-10 05:43:43.559 UTC [39269][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [05:43:43.649](0.220s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"