[08:11:34.812](0.090s) # setting up data directory # Checking port 50696 # Found port 50696 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=50696 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ Log file: /Users/admin/pgsql/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [08:11:34.853](0.041s) # 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 39211 [08:11:35.517](0.664s) 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 39716 [08:11:40.674](5.157s) # 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-30 08:11:35.435 UTC [39211][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:35.435 UTC [39211][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:35.452 UTC [39235][startup] LOG: database system was shut down at 2024-03-30 08:07:02 UTC 2024-03-30 08:11:35.461 UTC [39211][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:35.511 UTC [39262][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:35.511 UTC [39262][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-30 08:11:35.511 UTC [39262][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:35.515 UTC [39262][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-30 08:11:35.516 UTC [39262][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=admin database=postgres host=[local] 2024-03-30 08:11:35.532 UTC [39267][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:35.550 UTC [39267][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-30 08:11:35.550 UTC [39267][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:35.558 UTC [39267][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-30 08:11:35.560 UTC [39267][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.027 user=admin database=postgres host=[local] 2024-03-30 08:11:35.591 UTC [39282][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:35.591 UTC [39282][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-30 08:11:35.591 UTC [39282][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:35.592 UTC [39282][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-30 08:11:35.593 UTC [39282][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=admin database=postgres host=[local] 2024-03-30 08:11:35.639 UTC [39298][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:35.639 UTC [39298][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-30 08:11:35.639 UTC [39298][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:35.640 UTC [39298][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-30 08:11:35.641 UTC [39298][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-30 08:11:35.700 UTC [39307][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:35.700 UTC [39307][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-30 08:11:35.700 UTC [39307][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:35.703 UTC [39307][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-30 08:11:35.704 UTC [39307][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=admin database=postgres host=[local] 2024-03-30 08:11:35.758 UTC [39317][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:35.758 UTC [39317][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-30 08:11:35.758 UTC [39317][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:35.759 UTC [39317][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-30 08:11:35.815 UTC [39317][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.057 user=admin database=postgres host=[local] 2024-03-30 08:11:35.889 UTC [39341][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:35.890 UTC [39341][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-30 08:11:35.890 UTC [39341][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:35.892 UTC [39341][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-30 08:11:35.988 UTC [39341][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.098 user=admin database=postgres host=[local] 2024-03-30 08:11:36.027 UTC [39366][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:36.027 UTC [39366][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-30 08:11:36.027 UTC [39366][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:36.028 UTC [39366][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-30 08:11:36.131 UTC [39366][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.103 user=admin database=postgres host=[local] 2024-03-30 08:11:36.172 UTC [39386][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:36.173 UTC [39386][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-30 08:11:36.173 UTC [39386][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:36.174 UTC [39386][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-30 08:11:36.395 UTC [39386][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.223 user=admin database=postgres host=[local] 2024-03-30 08:11:36.471 UTC [39401][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:36.471 UTC [39401][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-30 08:11:36.471 UTC [39401][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:36.472 UTC [39401][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-30 08:11:36.838 UTC [39401][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.367 user=admin database=postgres host=[local] 2024-03-30 08:11:37.110 UTC [39435][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:37.186 UTC [39435][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-30 08:11:37.186 UTC [39435][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:37.187 UTC [39435][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-30 08:11:37.812 UTC [39435][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.701 user=admin database=postgres host=[local] 2024-03-30 08:11:38.418 UTC [39211][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:38.418 UTC [39211][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:38.423 UTC [39211][postmaster] LOG: background worker "logical replication launcher" (PID 39245) exited with exit code 1 2024-03-30 08:11:38.424 UTC [39227][checkpointer] LOG: shutting down 2024-03-30 08:11:38.424 UTC [39227][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:40.296 UTC [39227][checkpointer] LOG: checkpoint complete: wrote 5572 buffers (34.0%); 0 WAL file(s) added, 0 removed, 3 recycled; write=1.872 s, sync=0.001 s, total=1.873 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44852 kB, estimate=44852 kB; lsn=0/40D3CB0, redo lsn=0/40D3CB0 2024-03-30 08:11:40.307 UTC [39211][postmaster] LOG: database system is shut down 2024-03-30 08:11:40.548 UTC [39716][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:40.548 UTC [39716][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:40.561 UTC [39716][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:40.565 UTC [39722][startup] LOG: database system was shut down at 2024-03-30 08:11:40 UTC 2024-03-30 08:11:40.566 UTC [39716][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:40.679 UTC [39716][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:40.679 UTC [39716][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:40.680 UTC [39716][postmaster] LOG: background worker "logical replication launcher" (PID 39725) exited with exit code 1 2024-03-30 08:11:40.680 UTC [39720][checkpointer] LOG: shutting down 2024-03-30 08:11:40.680 UTC [39720][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:40.685 UTC [39720][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.006 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D3D28, redo lsn=0/40D3D28 2024-03-30 08:11:40.687 UTC [39716][postmaster] LOG: database system is shut down 2024-03-30 08:11:40.809 UTC [39737][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-30 08:11:40.919 UTC [39737][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [08:11:41.029](0.356s) 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 39758 [08:11:41.140](0.111s) 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-30 08:11:35.435 UTC [39211][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:35.435 UTC [39211][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:35.452 UTC [39235][startup] LOG: database system was shut down at 2024-03-30 08:07:02 UTC 2024-03-30 08:11:35.461 UTC [39211][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:35.511 UTC [39262][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:35.511 UTC [39262][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-30 08:11:35.511 UTC [39262][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:35.515 UTC [39262][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-30 08:11:35.516 UTC [39262][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=admin database=postgres host=[local] 2024-03-30 08:11:35.532 UTC [39267][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:35.550 UTC [39267][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-30 08:11:35.550 UTC [39267][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:35.558 UTC [39267][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-30 08:11:35.560 UTC [39267][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.027 user=admin database=postgres host=[local] 2024-03-30 08:11:35.591 UTC [39282][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:35.591 UTC [39282][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-30 08:11:35.591 UTC [39282][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:35.592 UTC [39282][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-30 08:11:35.593 UTC [39282][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=admin database=postgres host=[local] 2024-03-30 08:11:35.639 UTC [39298][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:35.639 UTC [39298][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-30 08:11:35.639 UTC [39298][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:35.640 UTC [39298][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-30 08:11:35.641 UTC [39298][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-30 08:11:35.700 UTC [39307][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:35.700 UTC [39307][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-30 08:11:35.700 UTC [39307][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:35.703 UTC [39307][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-30 08:11:35.704 UTC [39307][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=admin database=postgres host=[local] 2024-03-30 08:11:35.758 UTC [39317][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:35.758 UTC [39317][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-30 08:11:35.758 UTC [39317][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:35.759 UTC [39317][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-30 08:11:35.815 UTC [39317][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.057 user=admin database=postgres host=[local] 2024-03-30 08:11:35.889 UTC [39341][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:35.890 UTC [39341][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-30 08:11:35.890 UTC [39341][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:35.892 UTC [39341][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-30 08:11:35.988 UTC [39341][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.098 user=admin database=postgres host=[local] 2024-03-30 08:11:36.027 UTC [39366][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:36.027 UTC [39366][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-30 08:11:36.027 UTC [39366][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:36.028 UTC [39366][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-30 08:11:36.131 UTC [39366][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.103 user=admin database=postgres host=[local] 2024-03-30 08:11:36.172 UTC [39386][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:36.173 UTC [39386][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-30 08:11:36.173 UTC [39386][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:36.174 UTC [39386][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-30 08:11:36.395 UTC [39386][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.223 user=admin database=postgres host=[local] 2024-03-30 08:11:36.471 UTC [39401][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:36.471 UTC [39401][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-30 08:11:36.471 UTC [39401][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:36.472 UTC [39401][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-30 08:11:36.838 UTC [39401][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.367 user=admin database=postgres host=[local] 2024-03-30 08:11:37.110 UTC [39435][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:37.186 UTC [39435][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-30 08:11:37.186 UTC [39435][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:37.187 UTC [39435][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-30 08:11:37.812 UTC [39435][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.701 user=admin database=postgres host=[local] 2024-03-30 08:11:38.418 UTC [39211][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:38.418 UTC [39211][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:38.423 UTC [39211][postmaster] LOG: background worker "logical replication launcher" (PID 39245) exited with exit code 1 2024-03-30 08:11:38.424 UTC [39227][checkpointer] LOG: shutting down 2024-03-30 08:11:38.424 UTC [39227][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:40.296 UTC [39227][checkpointer] LOG: checkpoint complete: wrote 5572 buffers (34.0%); 0 WAL file(s) added, 0 removed, 3 recycled; write=1.872 s, sync=0.001 s, total=1.873 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44852 kB, estimate=44852 kB; lsn=0/40D3CB0, redo lsn=0/40D3CB0 2024-03-30 08:11:40.307 UTC [39211][postmaster] LOG: database system is shut down 2024-03-30 08:11:40.548 UTC [39716][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:40.548 UTC [39716][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:40.561 UTC [39716][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:40.565 UTC [39722][startup] LOG: database system was shut down at 2024-03-30 08:11:40 UTC 2024-03-30 08:11:40.566 UTC [39716][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:40.679 UTC [39716][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:40.679 UTC [39716][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:40.680 UTC [39716][postmaster] LOG: background worker "logical replication launcher" (PID 39725) exited with exit code 1 2024-03-30 08:11:40.680 UTC [39720][checkpointer] LOG: shutting down 2024-03-30 08:11:40.680 UTC [39720][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:40.685 UTC [39720][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.006 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D3D28, redo lsn=0/40D3D28 2024-03-30 08:11:40.687 UTC [39716][postmaster] LOG: database system is shut down 2024-03-30 08:11:40.809 UTC [39737][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-30 08:11:40.919 UTC [39737][postmaster] LOG: database system is shut down 2024-03-30 08:11:41.055 UTC [39758][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:41.056 UTC [39758][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:41.056 UTC [39758][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:41.058 UTC [39762][startup] LOG: database system was shut down at 2024-03-30 08:11:40 UTC 2024-03-30 08:11:41.060 UTC [39758][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:41.145 UTC [39758][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:41.145 UTC [39758][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:41.148 UTC [39758][postmaster] LOG: background worker "logical replication launcher" (PID 39765) exited with exit code 1 2024-03-30 08:11:41.148 UTC [39760][checkpointer] LOG: shutting down 2024-03-30 08:11:41.148 UTC [39760][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:41.233 UTC [39760][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.086 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D3DA0, redo lsn=0/40D3DA0 2024-03-30 08:11:41.237 UTC [39758][postmaster] LOG: database system is shut down 2024-03-30 08:11:41.281 UTC [39781][postmaster] FATAL: could not set SSL protocol version range 2024-03-30 08:11:41.281 UTC [39781][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-30 08:11:41.281 UTC [39781][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [08:11:41.367](0.227s) 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 39787 [08:11:41.483](0.116s) ok 5 - restart succeeds with correct SSL protocol bounds [08:11:41.483](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 39817 [08:11:41.725](0.242s) ok 6 - server doesn't accept non-SSL connections [08:11:41.725](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [08:11:41.757](0.032s) ok 8 - connect without server root cert sslmode=require [08:11:41.757](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [08:11:41.785](0.028s) ok 10 - connect without server root cert sslmode=verify-ca [08:11:41.786](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [08:11:41.807](0.022s) ok 12 - connect without server root cert sslmode=verify-full [08:11:41.807](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [08:11:41.830](0.023s) ok 14 - connect with wrong server root cert sslmode=require [08:11:41.831](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [08:11:41.866](0.036s) ok 16 - connect with wrong server root cert sslmode=verify-ca [08:11:41.867](0.001s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [08:11:41.895](0.028s) ok 18 - connect with wrong server root cert sslmode=verify-full [08:11:41.895](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [08:11:41.918](0.023s) ok 20 - connect with server CA cert, without root CA [08:11:41.919](0.000s) ok 21 - connect with server CA cert, without root CA: matches [08:11:41.944](0.025s) ok 22 - connect with correct server CA cert file sslmode=require [08:11:41.944](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [08:11:41.971](0.027s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [08:11:41.971](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [08:11:41.999](0.028s) ok 26 - connect with correct server CA cert file sslmode=verify-full [08:11:41.999](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [08:11:42.028](0.029s) ok 28 - cert root file that contains two certificates, order 1 [08:11:42.028](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [08:11:42.054](0.026s) ok 30 - cert root file that contains two certificates, order 2 [08:11:42.054](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [08:11:42.083](0.029s) ok 32 - connect with sslcertmode=disable [08:11:42.084](0.001s) ok 33 - connect with sslcertmode=disable: no stderr [08:11:42.108](0.024s) ok 34 - connect with sslcertmode=allow [08:11:42.108](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [08:11:42.129](0.021s) ok 36 - connect with sslcertmode=require fails without a client certificate [08:11:42.129](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [08:11:42.155](0.026s) ok 38 - sslcrl option with invalid file name [08:11:42.155](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [08:11:42.177](0.022s) ok 40 - CRL belonging to a different CA [08:11:42.177](0.000s) ok 41 - CRL belonging to a different CA: matches [08:11:42.214](0.036s) ok 42 - directory CRL belonging to a different CA [08:11:42.214](0.000s) ok 43 - directory CRL belonging to a different CA: matches [08:11:42.251](0.037s) ok 44 - CRL with a non-revoked cert [08:11:42.251](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [08:11:42.291](0.040s) ok 46 - directory CRL with a non-revoked cert [08:11:42.291](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [08:11:42.331](0.040s) ok 48 - mismatch between host name and server certificate sslmode=require [08:11:42.331](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [08:11:42.364](0.033s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [08:11:42.364](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [08:11:42.413](0.049s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [08:11:42.413](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 39925 [08:11:42.670](0.257s) ok 54 - IP address in the Common Name [08:11:42.670](0.000s) ok 55 - IP address in the Common Name: no stderr [08:11:42.694](0.025s) ok 56 - mismatch between host name and server certificate IP address [08:11:42.694](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 39937 [08:11:42.949](0.255s) ok 58 - IP address in a dNSName [08:11:42.949](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 39947 [08:11:43.208](0.259s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [08:11:43.208](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [08:11:43.234](0.026s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [08:11:43.235](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [08:11:43.259](0.024s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [08:11:43.259](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [08:11:43.281](0.022s) ok 66 - host name not matching with X.509 Subject Alternative Names [08:11:43.281](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [08:11:43.301](0.020s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [08:11:43.301](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 39966 [08:11:43.555](0.254s) ok 70 - host name matching with a single X.509 Subject Alternative Name [08:11:43.555](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [08:11:43.583](0.028s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [08:11:43.584](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [08:11:43.607](0.024s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [08:11:43.607](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 39981 [08:11:43.852](0.244s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [08:11:43.852](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [08:11:43.893](0.041s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [08:11:43.893](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [08:11:43.934](0.041s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [08:11:43.935](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [08:11:43.989](0.055s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [08:11:43.990](0.001s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [08:11:44.036](0.046s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [08:11:44.037](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [08:11:44.073](0.037s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [08:11:44.073](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [08:11:44.106](0.033s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [08:11:44.107](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [08:11:44.159](0.052s) ok 90 - IPv6 host with CIDR mask does not match [08:11:44.159](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 40008 [08:11:44.430](0.271s) ok 92 - certificate with both a CN and SANs 1 [08:11:44.430](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [08:11:44.461](0.031s) ok 94 - certificate with both a CN and SANs 2 [08:11:44.461](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [08:11:44.492](0.031s) ok 96 - certificate with both a CN and SANs ignores CN [08:11:44.493](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 40024 [08:11:44.781](0.288s) ok 98 - certificate with both a CN and IP SANs matches CN [08:11:44.781](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [08:11:44.859](0.078s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [08:11:44.859](0.001s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [08:11:44.921](0.062s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [08:11:44.921](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 40040 [08:11:45.192](0.270s) ok 104 - certificate with both an IP CN and IP SANs 1 [08:11:45.192](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [08:11:45.251](0.059s) ok 106 - certificate with both an IP CN and IP SANs 2 [08:11:45.251](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [08:11:45.308](0.057s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [08:11:45.308](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 40056 [08:11:45.584](0.276s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [08:11:45.584](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [08:11:45.626](0.043s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [08:11:45.626](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [08:11:45.733](0.106s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [08:11:45.734](0.001s) 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 40073 [08:11:46.098](0.364s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [08:11:46.098](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [08:11:46.133](0.035s) ok 118 - server certificate without CN or SANs sslmode=verify-full [08:11:46.133](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 40088 [08:11:46.413](0.281s) ok 120 - sslrootcert=system does not connect with private CA [08:11:46.414](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [08:11:46.449](0.035s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [08:11:46.449](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [08:11:46.485](0.036s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [08:11:46.485](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [08:11:46.507](0.022s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [08:11:46.507](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 40106 [08:11:46.756](0.249s) ok 128 - connects without client-side CRL [08:11:46.756](0.000s) ok 129 - connects without client-side CRL: no stderr [08:11:46.778](0.023s) ok 130 - does not connect with client-side CRL file [08:11:46.779](0.000s) ok 131 - does not connect with client-side CRL file: matches [08:11:46.802](0.024s) ok 132 - does not connect with client-side CRL directory [08:11:46.802](0.000s) ok 133 - does not connect with client-side CRL directory: matches # Running: psql -X -A -F , -P null=_null_ -d sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [08:11:46.828](0.025s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [08:11:46.828](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [08:11:46.828](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [08:11:46.854](0.025s) ok 137 - connection success with correct range of TLS protocol versions [08:11:46.854](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [08:11:46.865](0.012s) ok 139 - connection failure with incorrect range of TLS protocol versions [08:11:46.866](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [08:11:46.878](0.012s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [08:11:46.878](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [08:11:46.889](0.012s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [08:11:46.890](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [08:11:46.890](0.000s) # running server tests [08:11:46.916](0.026s) ok 145 - certificate authorization fails without client cert [08:11:46.916](0.000s) ok 146 - certificate authorization fails without client cert: matches [08:11:46.944](0.028s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [08:11:46.944](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [08:11:46.967](0.024s) ok 149 - certificate authorization succeeds with correct client cert in DER format [08:11:46.967](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [08:11:46.992](0.025s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [08:11:46.992](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [08:11:47.016](0.024s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [08:11:47.016](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [08:11:47.042](0.026s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [08:11:47.042](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [08:11:47.079](0.037s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [08:11:47.079](0.001s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [08:11:47.113](0.034s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [08:11:47.114](0.001s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [08:11:47.146](0.032s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [08:11:47.146](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [08:11:47.187](0.040s) ok 163 - certificate authorization succeeds with DN mapping [08:11:47.187](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [08:11:47.187](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [08:11:47.227](0.040s) ok 166 - certificate authorization succeeds with DN regex mapping [08:11:47.227](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [08:11:47.274](0.047s) ok 168 - certificate authorization succeeds with CN mapping [08:11:47.275](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [08:11:47.275](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [08:11:47.275](0.000s) not ok 171 # TODO & SKIP Need Pty support [08:11:47.275](0.000s) not ok 172 # TODO & SKIP Need Pty support [08:11:47.275](0.000s) not ok 173 # TODO & SKIP Need Pty support [08:11:47.276](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_qvmR/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [08:11:47.358](0.082s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [08:11:47.358](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [08:11:47.358](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [08:11:47.393](0.035s) ok 178 - certificate authorization fails because of file permissions [08:11:47.394](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [08:11:47.427](0.034s) ok 180 - certificate authorization fails with client cert belonging to another user [08:11:47.428](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [08:11:47.464](0.037s) ok 182 - certificate authorization fails with revoked client cert [08:11:47.464](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [08:11:47.465](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [08:11:47.496](0.031s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [08:11:47.496](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [08:11:47.496](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [08:11:47.523](0.028s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [08:11:47.524](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [08:11:47.524](0.001s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [08:11:47.555](0.030s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [08:11:47.555](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [08:11:47.555](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 40171 [08:11:47.901](0.345s) ok 194 - intermediate client certificate is provided by client [08:11:47.901](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [08:11:47.944](0.043s) ok 196 - intermediate client certificate is missing [08:11:47.944](0.000s) ok 197 - intermediate client certificate is missing: matches [08:11:48.218](0.274s) ok 198 - logged client certificate Subjects are truncated if they're too long [08:11:48.219](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 40191 [08:11:48.516](0.297s) ok 200 - intermediate client certificate is untrusted [08:11:48.516](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 40201 [08:11:48.768](0.252s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [08:11:48.768](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [08:11:48.801](0.033s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [08:11:48.802](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 40213 [08:11:49.080](0.278s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [08:11:49.080](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1' # at /Users/admin/pgsql/src/test/ssl/t/001_ssltests.pl line 923. [08:11:49.081](0.000s) # got: '2' # expected: '0' [08:11:49.081](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [08:11:49.081](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr' # at /Users/admin/pgsql/src/test/ssl/t/001_ssltests.pl line 923. [08:11:49.081](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 50696 failed: server closed the connection unexpectedly # This probably means the server terminated abnormally # before or while processing the request. # SSL SYSCALL error: Connection reset by peer' # expected: '' [08:11:49.118](0.037s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [08:11:49.118](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 40225 [08:11:49.832](0.714s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [08:11:49.866](0.034s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [08:11:49.866](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 40237 [08:11:50.108](0.242s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [08:11:50.141](0.033s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [08:11:50.141](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 40249 [08:11:50.382](0.241s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [08:11:50.411](0.028s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [08:11:50.411](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 40261 [08:11:50.650](0.239s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [08:11:50.650](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1' # at /Users/admin/pgsql/src/test/ssl/t/001_ssltests.pl line 1001. [08:11:50.651](0.000s) # got: '2' # expected: '0' [08:11:50.651](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [08:11:50.651](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr' # at /Users/admin/pgsql/src/test/ssl/t/001_ssltests.pl line 1001. [08:11:50.651](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 50696 failed: server closed the connection unexpectedly # This probably means the server terminated abnormally # before or while processing the request. # SSL SYSCALL error: Connection reset by peer' # expected: '' [08:11:50.675](0.024s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [08:11:50.675](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 40273 [08:11:50.923](0.248s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [08:11:50.954](0.030s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [08:11:50.954](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 40285 [08:11:51.196](0.242s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [08:11:51.222](0.025s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [08:11:51.222](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 40297 [08:11:51.453](0.232s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [08:11:51.477](0.023s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [08:11:51.477](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 40309 [08:11:51.721](0.244s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [08:11:51.745](0.024s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [08:11:51.745](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-30 08:11:35.435 UTC [39211][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:35.435 UTC [39211][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:35.452 UTC [39235][startup] LOG: database system was shut down at 2024-03-30 08:07:02 UTC 2024-03-30 08:11:35.461 UTC [39211][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:35.511 UTC [39262][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:35.511 UTC [39262][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-30 08:11:35.511 UTC [39262][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:35.515 UTC [39262][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-30 08:11:35.516 UTC [39262][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=admin database=postgres host=[local] 2024-03-30 08:11:35.532 UTC [39267][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:35.550 UTC [39267][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-30 08:11:35.550 UTC [39267][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:35.558 UTC [39267][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-30 08:11:35.560 UTC [39267][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.027 user=admin database=postgres host=[local] 2024-03-30 08:11:35.591 UTC [39282][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:35.591 UTC [39282][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-30 08:11:35.591 UTC [39282][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:35.592 UTC [39282][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-30 08:11:35.593 UTC [39282][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=admin database=postgres host=[local] 2024-03-30 08:11:35.639 UTC [39298][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:35.639 UTC [39298][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-30 08:11:35.639 UTC [39298][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:35.640 UTC [39298][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-30 08:11:35.641 UTC [39298][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-30 08:11:35.700 UTC [39307][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:35.700 UTC [39307][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-30 08:11:35.700 UTC [39307][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:35.703 UTC [39307][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-30 08:11:35.704 UTC [39307][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.004 user=admin database=postgres host=[local] 2024-03-30 08:11:35.758 UTC [39317][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:35.758 UTC [39317][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-30 08:11:35.758 UTC [39317][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:35.759 UTC [39317][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-30 08:11:35.815 UTC [39317][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.057 user=admin database=postgres host=[local] 2024-03-30 08:11:35.889 UTC [39341][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:35.890 UTC [39341][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-30 08:11:35.890 UTC [39341][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:35.892 UTC [39341][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-30 08:11:35.988 UTC [39341][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.098 user=admin database=postgres host=[local] 2024-03-30 08:11:36.027 UTC [39366][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:36.027 UTC [39366][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-30 08:11:36.027 UTC [39366][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:36.028 UTC [39366][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-30 08:11:36.131 UTC [39366][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.103 user=admin database=postgres host=[local] 2024-03-30 08:11:36.172 UTC [39386][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:36.173 UTC [39386][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-30 08:11:36.173 UTC [39386][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:36.174 UTC [39386][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-30 08:11:36.395 UTC [39386][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.223 user=admin database=postgres host=[local] 2024-03-30 08:11:36.471 UTC [39401][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:36.471 UTC [39401][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-30 08:11:36.471 UTC [39401][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:36.472 UTC [39401][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-30 08:11:36.838 UTC [39401][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.367 user=admin database=postgres host=[local] 2024-03-30 08:11:37.110 UTC [39435][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-30 08:11:37.186 UTC [39435][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-30 08:11:37.186 UTC [39435][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-30 08:11:37.187 UTC [39435][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-30 08:11:37.812 UTC [39435][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.701 user=admin database=postgres host=[local] 2024-03-30 08:11:38.418 UTC [39211][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:38.418 UTC [39211][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:38.423 UTC [39211][postmaster] LOG: background worker "logical replication launcher" (PID 39245) exited with exit code 1 2024-03-30 08:11:38.424 UTC [39227][checkpointer] LOG: shutting down 2024-03-30 08:11:38.424 UTC [39227][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:40.296 UTC [39227][checkpointer] LOG: checkpoint complete: wrote 5572 buffers (34.0%); 0 WAL file(s) added, 0 removed, 3 recycled; write=1.872 s, sync=0.001 s, total=1.873 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44852 kB, estimate=44852 kB; lsn=0/40D3CB0, redo lsn=0/40D3CB0 2024-03-30 08:11:40.307 UTC [39211][postmaster] LOG: database system is shut down 2024-03-30 08:11:40.548 UTC [39716][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:40.548 UTC [39716][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:40.561 UTC [39716][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:40.565 UTC [39722][startup] LOG: database system was shut down at 2024-03-30 08:11:40 UTC 2024-03-30 08:11:40.566 UTC [39716][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:40.679 UTC [39716][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:40.679 UTC [39716][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:40.680 UTC [39716][postmaster] LOG: background worker "logical replication launcher" (PID 39725) exited with exit code 1 2024-03-30 08:11:40.680 UTC [39720][checkpointer] LOG: shutting down 2024-03-30 08:11:40.680 UTC [39720][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:40.685 UTC [39720][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.006 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D3D28, redo lsn=0/40D3D28 2024-03-30 08:11:40.687 UTC [39716][postmaster] LOG: database system is shut down 2024-03-30 08:11:40.809 UTC [39737][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-30 08:11:40.919 UTC [39737][postmaster] LOG: database system is shut down 2024-03-30 08:11:41.055 UTC [39758][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:41.056 UTC [39758][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:41.056 UTC [39758][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:41.058 UTC [39762][startup] LOG: database system was shut down at 2024-03-30 08:11:40 UTC 2024-03-30 08:11:41.060 UTC [39758][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:41.145 UTC [39758][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:41.145 UTC [39758][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:41.148 UTC [39758][postmaster] LOG: background worker "logical replication launcher" (PID 39765) exited with exit code 1 2024-03-30 08:11:41.148 UTC [39760][checkpointer] LOG: shutting down 2024-03-30 08:11:41.148 UTC [39760][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:41.233 UTC [39760][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.086 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D3DA0, redo lsn=0/40D3DA0 2024-03-30 08:11:41.237 UTC [39758][postmaster] LOG: database system is shut down 2024-03-30 08:11:41.281 UTC [39781][postmaster] FATAL: could not set SSL protocol version range 2024-03-30 08:11:41.281 UTC [39781][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-30 08:11:41.281 UTC [39781][postmaster] LOG: database system is shut down 2024-03-30 08:11:41.390 UTC [39787][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:41.391 UTC [39787][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:41.391 UTC [39787][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:41.394 UTC [39791][startup] LOG: database system was shut down at 2024-03-30 08:11:41 UTC 2024-03-30 08:11:41.395 UTC [39787][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:41.490 UTC [39787][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:41.490 UTC [39787][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:41.491 UTC [39787][postmaster] LOG: background worker "logical replication launcher" (PID 39794) exited with exit code 1 2024-03-30 08:11:41.491 UTC [39789][checkpointer] LOG: shutting down 2024-03-30 08:11:41.492 UTC [39789][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:41.493 UTC [39789][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/40D3E18, redo lsn=0/40D3E18 2024-03-30 08:11:41.495 UTC [39787][postmaster] LOG: database system is shut down 2024-03-30 08:11:41.611 UTC [39817][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:41.611 UTC [39817][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:41.612 UTC [39817][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:41.616 UTC [39823][startup] LOG: database system was shut down at 2024-03-30 08:11:41 UTC 2024-03-30 08:11:41.619 UTC [39817][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:41.713 UTC [39838][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49263 2024-03-30 08:11:41.722 UTC [39838][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-30 08:11:41.722 UTC [39838][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-03-30 08:11:41.741 UTC [39844][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49266 2024-03-30 08:11:41.751 UTC [39844][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-30 08:11:41.751 UTC [39844][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-30 08:11:41.755 UTC [39844][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-30 08:11:41.755 UTC [39844][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=49266 2024-03-30 08:11:41.773 UTC [39850][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49269 2024-03-30 08:11:41.784 UTC [39850][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-30 08:11:41.797 UTC [39852][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49270 2024-03-30 08:11:41.805 UTC [39852][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-30 08:11:41.818 UTC [39856][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49272 2024-03-30 08:11:41.828 UTC [39856][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-30 08:11:41.852 UTC [39859][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49273 2024-03-30 08:11:41.865 UTC [39859][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-30 08:11:41.884 UTC [39864][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49275 2024-03-30 08:11:41.893 UTC [39864][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-30 08:11:41.908 UTC [39873][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49277 2024-03-30 08:11:41.917 UTC [39873][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-30 08:11:41.929 UTC [39877][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49279 2024-03-30 08:11:41.940 UTC [39877][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-30 08:11:41.940 UTC [39877][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-30 08:11:41.941 UTC [39877][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-30 08:11:41.942 UTC [39877][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=49279 2024-03-30 08:11:41.955 UTC [39881][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49281 2024-03-30 08:11:41.968 UTC [39881][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-30 08:11:41.968 UTC [39881][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-30 08:11:41.969 UTC [39881][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-30 08:11:41.969 UTC [39881][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=49281 2024-03-30 08:11:41.984 UTC [39886][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49283 2024-03-30 08:11:41.997 UTC [39886][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-30 08:11:41.997 UTC [39886][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-30 08:11:41.998 UTC [39886][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-30 08:11:41.998 UTC [39886][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=49283 2024-03-30 08:11:42.013 UTC [39891][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49286 2024-03-30 08:11:42.025 UTC [39891][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-30 08:11:42.026 UTC [39891][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-30 08:11:42.026 UTC [39891][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-30 08:11:42.026 UTC [39891][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=49286 2024-03-30 08:11:42.039 UTC [39896][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49288 2024-03-30 08:11:42.052 UTC [39896][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-30 08:11:42.052 UTC [39896][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-30 08:11:42.052 UTC [39896][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-30 08:11:42.053 UTC [39896][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=49288 2024-03-30 08:11:42.067 UTC [39900][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49290 2024-03-30 08:11:42.080 UTC [39900][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-30 08:11:42.081 UTC [39900][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-30 08:11:42.081 UTC [39900][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-30 08:11:42.081 UTC [39900][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=49290 2024-03-30 08:11:42.096 UTC [39903][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49291 2024-03-30 08:11:42.106 UTC [39903][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-30 08:11:42.106 UTC [39903][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-30 08:11:42.106 UTC [39903][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-30 08:11:42.107 UTC [39903][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49291 2024-03-30 08:11:42.119 UTC [39905][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49292 2024-03-30 08:11:42.127 UTC [39905][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-30 08:11:42.127 UTC [39905][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-30 08:11:42.128 UTC [39905][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49292 2024-03-30 08:11:42.144 UTC [39907][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49293 2024-03-30 08:11:42.153 UTC [39907][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-30 08:11:42.153 UTC [39907][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-30 08:11:42.154 UTC [39907][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-30 08:11:42.154 UTC [39907][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49293 2024-03-30 08:11:42.167 UTC [39909][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49294 2024-03-30 08:11:42.176 UTC [39909][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-30 08:11:42.200 UTC [39911][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49295 2024-03-30 08:11:42.212 UTC [39911][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-30 08:11:42.235 UTC [39913][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49296 2024-03-30 08:11:42.248 UTC [39913][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-30 08:11:42.248 UTC [39913][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-30 08:11:42.249 UTC [39913][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-30 08:11:42.249 UTC [39913][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=49296 2024-03-30 08:11:42.272 UTC [39915][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49297 2024-03-30 08:11:42.286 UTC [39915][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-30 08:11:42.286 UTC [39915][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-30 08:11:42.288 UTC [39915][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-30 08:11:42.288 UTC [39915][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=49297 2024-03-30 08:11:42.313 UTC [39918][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49298 2024-03-30 08:11:42.328 UTC [39918][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-30 08:11:42.328 UTC [39918][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-30 08:11:42.329 UTC [39918][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-30 08:11:42.329 UTC [39918][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=49298 2024-03-30 08:11:42.348 UTC [39920][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49299 2024-03-30 08:11:42.360 UTC [39920][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-30 08:11:42.360 UTC [39920][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-30 08:11:42.361 UTC [39920][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-30 08:11:42.363 UTC [39920][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=49299 2024-03-30 08:11:42.384 UTC [39922][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49300 2024-03-30 08:11:42.418 UTC [39817][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:42.418 UTC [39817][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:42.419 UTC [39817][postmaster] LOG: background worker "logical replication launcher" (PID 39826) exited with exit code 1 2024-03-30 08:11:42.420 UTC [39821][checkpointer] LOG: shutting down 2024-03-30 08:11:42.420 UTC [39821][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:42.427 UTC [39821][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.008 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D3E90, redo lsn=0/40D3E90 2024-03-30 08:11:42.429 UTC [39817][postmaster] LOG: database system is shut down 2024-03-30 08:11:42.550 UTC [39925][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:42.551 UTC [39925][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:42.551 UTC [39925][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:42.554 UTC [39928][startup] LOG: database system was shut down at 2024-03-30 08:11:42 UTC 2024-03-30 08:11:42.557 UTC [39925][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:42.655 UTC [39933][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49301 2024-03-30 08:11:42.665 UTC [39933][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-30 08:11:42.665 UTC [39933][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-30 08:11:42.668 UTC [39933][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-30 08:11:42.668 UTC [39933][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=49301 2024-03-30 08:11:42.685 UTC [39935][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49302 2024-03-30 08:11:42.699 UTC [39925][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:42.699 UTC [39925][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:42.700 UTC [39925][postmaster] LOG: background worker "logical replication launcher" (PID 39931) exited with exit code 1 2024-03-30 08:11:42.700 UTC [39926][checkpointer] LOG: shutting down 2024-03-30 08:11:42.701 UTC [39926][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:42.701 UTC [39926][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/40D3F08, redo lsn=0/40D3F08 2024-03-30 08:11:42.702 UTC [39925][postmaster] LOG: database system is shut down 2024-03-30 08:11:42.830 UTC [39937][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:42.830 UTC [39937][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:42.830 UTC [39937][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:42.833 UTC [39940][startup] LOG: database system was shut down at 2024-03-30 08:11:42 UTC 2024-03-30 08:11:42.835 UTC [39937][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:42.935 UTC [39945][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49303 2024-03-30 08:11:42.945 UTC [39945][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-30 08:11:42.945 UTC [39945][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-30 08:11:42.947 UTC [39945][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-30 08:11:42.948 UTC [39945][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=49303 2024-03-30 08:11:42.954 UTC [39937][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:42.954 UTC [39937][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:42.954 UTC [39937][postmaster] LOG: background worker "logical replication launcher" (PID 39943) exited with exit code 1 2024-03-30 08:11:42.955 UTC [39938][checkpointer] LOG: shutting down 2024-03-30 08:11:42.955 UTC [39938][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:42.955 UTC [39938][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/40D3F80, redo lsn=0/40D3F80 2024-03-30 08:11:42.957 UTC [39937][postmaster] LOG: database system is shut down 2024-03-30 08:11:43.092 UTC [39947][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:43.093 UTC [39947][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:43.093 UTC [39947][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:43.096 UTC [39950][startup] LOG: database system was shut down at 2024-03-30 08:11:42 UTC 2024-03-30 08:11:43.098 UTC [39947][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:43.194 UTC [39955][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49304 2024-03-30 08:11:43.203 UTC [39955][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-30 08:11:43.203 UTC [39955][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-30 08:11:43.206 UTC [39955][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-30 08:11:43.207 UTC [39955][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49304 2024-03-30 08:11:43.222 UTC [39958][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49305 2024-03-30 08:11:43.232 UTC [39958][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-30 08:11:43.232 UTC [39958][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-30 08:11:43.233 UTC [39958][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-30 08:11:43.233 UTC [39958][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49305 2024-03-30 08:11:43.247 UTC [39960][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49306 2024-03-30 08:11:43.256 UTC [39960][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-30 08:11:43.256 UTC [39960][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-30 08:11:43.257 UTC [39960][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-30 08:11:43.257 UTC [39960][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49306 2024-03-30 08:11:43.271 UTC [39962][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49307 2024-03-30 08:11:43.291 UTC [39964][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49308 2024-03-30 08:11:43.306 UTC [39947][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:43.306 UTC [39947][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:43.307 UTC [39947][postmaster] LOG: background worker "logical replication launcher" (PID 39953) exited with exit code 1 2024-03-30 08:11:43.307 UTC [39948][checkpointer] LOG: shutting down 2024-03-30 08:11:43.307 UTC [39948][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:43.308 UTC [39948][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/40D3FF8, redo lsn=0/40D3FF8 2024-03-30 08:11:43.309 UTC [39947][postmaster] LOG: database system is shut down 2024-03-30 08:11:43.437 UTC [39966][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:43.437 UTC [39966][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:43.438 UTC [39966][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:43.441 UTC [39969][startup] LOG: database system was shut down at 2024-03-30 08:11:43 UTC 2024-03-30 08:11:43.443 UTC [39966][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:43.537 UTC [39974][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49309 2024-03-30 08:11:43.549 UTC [39974][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-30 08:11:43.549 UTC [39974][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-30 08:11:43.553 UTC [39974][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-30 08:11:43.553 UTC [39974][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49309 2024-03-30 08:11:43.570 UTC [39976][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49310 2024-03-30 08:11:43.595 UTC [39978][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49311 2024-03-30 08:11:43.617 UTC [39966][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:43.617 UTC [39966][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:43.618 UTC [39966][postmaster] LOG: background worker "logical replication launcher" (PID 39972) exited with exit code 1 2024-03-30 08:11:43.618 UTC [39967][checkpointer] LOG: shutting down 2024-03-30 08:11:43.618 UTC [39967][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:43.619 UTC [39967][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/40D4088, redo lsn=0/40D4088 2024-03-30 08:11:43.620 UTC [39966][postmaster] LOG: database system is shut down 2024-03-30 08:11:43.738 UTC [39981][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:43.739 UTC [39981][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:43.739 UTC [39981][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:43.742 UTC [39984][startup] LOG: database system was shut down at 2024-03-30 08:11:43 UTC 2024-03-30 08:11:43.744 UTC [39981][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:43.835 UTC [39990][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49312 2024-03-30 08:11:43.846 UTC [39990][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-30 08:11:43.846 UTC [39990][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-30 08:11:43.850 UTC [39990][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-30 08:11:43.851 UTC [39990][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=49312 2024-03-30 08:11:43.878 UTC [39992][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49313 2024-03-30 08:11:43.890 UTC [39992][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-30 08:11:43.890 UTC [39992][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-30 08:11:43.891 UTC [39992][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-30 08:11:43.891 UTC [39992][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=49313 2024-03-30 08:11:43.912 UTC [39994][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49314 2024-03-30 08:11:43.967 UTC [39997][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49315 2024-03-30 08:11:43.983 UTC [39997][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-30 08:11:43.983 UTC [39997][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-30 08:11:43.986 UTC [39997][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-30 08:11:43.986 UTC [39997][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.019 user=ssltestuser database=trustdb host=localhost port=49315 2024-03-30 08:11:44.018 UTC [39999][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49316 2024-03-30 08:11:44.032 UTC [39999][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-30 08:11:44.032 UTC [39999][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-30 08:11:44.033 UTC [39999][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-30 08:11:44.034 UTC [39999][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=49316 2024-03-30 08:11:44.058 UTC [40001][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49317 2024-03-30 08:11:44.070 UTC [40001][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-30 08:11:44.070 UTC [40001][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-30 08:11:44.070 UTC [40001][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-30 08:11:44.071 UTC [40001][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=49317 2024-03-30 08:11:44.096 UTC [40003][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49318 2024-03-30 08:11:44.135 UTC [40005][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49319 2024-03-30 08:11:44.169 UTC [39981][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:44.169 UTC [39981][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:44.171 UTC [39981][postmaster] LOG: background worker "logical replication launcher" (PID 39987) exited with exit code 1 2024-03-30 08:11:44.173 UTC [39982][checkpointer] LOG: shutting down 2024-03-30 08:11:44.174 UTC [39982][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:44.177 UTC [39982][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.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D4100, redo lsn=0/40D4100 2024-03-30 08:11:44.181 UTC [39981][postmaster] LOG: database system is shut down 2024-03-30 08:11:44.305 UTC [40008][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:44.306 UTC [40008][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:44.306 UTC [40008][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:44.313 UTC [40011][startup] LOG: database system was shut down at 2024-03-30 08:11:44 UTC 2024-03-30 08:11:44.315 UTC [40008][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:44.406 UTC [40017][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49320 2024-03-30 08:11:44.423 UTC [40017][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-30 08:11:44.423 UTC [40017][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-30 08:11:44.428 UTC [40017][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-30 08:11:44.428 UTC [40017][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.022 user=ssltestuser database=trustdb host=localhost port=49320 2024-03-30 08:11:44.448 UTC [40019][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49321 2024-03-30 08:11:44.459 UTC [40019][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-30 08:11:44.459 UTC [40019][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-30 08:11:44.460 UTC [40019][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-30 08:11:44.460 UTC [40019][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49321 2024-03-30 08:11:44.480 UTC [40021][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49322 2024-03-30 08:11:44.503 UTC [40008][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:44.503 UTC [40008][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:44.505 UTC [40008][postmaster] LOG: background worker "logical replication launcher" (PID 40014) exited with exit code 1 2024-03-30 08:11:44.505 UTC [40009][checkpointer] LOG: shutting down 2024-03-30 08:11:44.506 UTC [40009][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:44.507 UTC [40009][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/40D4178, redo lsn=0/40D4178 2024-03-30 08:11:44.510 UTC [40008][postmaster] LOG: database system is shut down 2024-03-30 08:11:44.636 UTC [40024][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:44.636 UTC [40024][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:44.636 UTC [40024][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:44.642 UTC [40027][startup] LOG: database system was shut down at 2024-03-30 08:11:44 UTC 2024-03-30 08:11:44.644 UTC [40024][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:44.743 UTC [40034][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49323 2024-03-30 08:11:44.764 UTC [40034][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-30 08:11:44.764 UTC [40034][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-30 08:11:44.774 UTC [40034][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-30 08:11:44.777 UTC [40034][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.034 user=ssltestuser database=trustdb host=localhost port=49323 2024-03-30 08:11:44.821 UTC [40036][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49324 2024-03-30 08:11:44.853 UTC [40036][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-30 08:11:44.853 UTC [40036][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-30 08:11:44.855 UTC [40036][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-30 08:11:44.855 UTC [40036][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.035 user=ssltestuser database=trustdb host=localhost port=49324 2024-03-30 08:11:44.885 UTC [40038][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49325 2024-03-30 08:11:44.918 UTC [40038][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-30 08:11:44.918 UTC [40038][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-30 08:11:44.919 UTC [40038][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-30 08:11:44.920 UTC [40038][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.035 user=ssltestuser database=trustdb host=localhost port=49325 2024-03-30 08:11:44.934 UTC [40024][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:44.934 UTC [40024][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:44.936 UTC [40024][postmaster] LOG: background worker "logical replication launcher" (PID 40030) exited with exit code 1 2024-03-30 08:11:44.937 UTC [40025][checkpointer] LOG: shutting down 2024-03-30 08:11:44.937 UTC [40025][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:44.938 UTC [40025][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/40D41F0, redo lsn=0/40D41F0 2024-03-30 08:11:44.940 UTC [40024][postmaster] LOG: database system is shut down 2024-03-30 08:11:45.085 UTC [40040][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:45.086 UTC [40040][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:45.086 UTC [40040][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:45.095 UTC [40043][startup] LOG: database system was shut down at 2024-03-30 08:11:44 UTC 2024-03-30 08:11:45.101 UTC [40040][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:45.174 UTC [40049][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49326 2024-03-30 08:11:45.184 UTC [40049][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-30 08:11:45.184 UTC [40049][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-30 08:11:45.189 UTC [40049][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-30 08:11:45.190 UTC [40049][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=49326 2024-03-30 08:11:45.215 UTC [40051][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49327 2024-03-30 08:11:45.248 UTC [40051][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-30 08:11:45.248 UTC [40051][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-30 08:11:45.249 UTC [40051][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-30 08:11:45.249 UTC [40051][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.035 user=ssltestuser database=trustdb host=localhost port=49327 2024-03-30 08:11:45.289 UTC [40053][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49328 2024-03-30 08:11:45.316 UTC [40040][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:45.317 UTC [40040][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:45.318 UTC [40040][postmaster] LOG: background worker "logical replication launcher" (PID 40046) exited with exit code 1 2024-03-30 08:11:45.318 UTC [40041][checkpointer] LOG: shutting down 2024-03-30 08:11:45.319 UTC [40041][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:45.322 UTC [40041][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.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D4268, redo lsn=0/40D4268 2024-03-30 08:11:45.324 UTC [40040][postmaster] LOG: database system is shut down 2024-03-30 08:11:45.484 UTC [40056][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:45.485 UTC [40056][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:45.486 UTC [40056][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:45.495 UTC [40059][startup] LOG: database system was shut down at 2024-03-30 08:11:45 UTC 2024-03-30 08:11:45.499 UTC [40056][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:45.565 UTC [40065][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49329 2024-03-30 08:11:45.576 UTC [40065][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-30 08:11:45.576 UTC [40065][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-30 08:11:45.582 UTC [40065][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-30 08:11:45.582 UTC [40065][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49329 2024-03-30 08:11:45.604 UTC [40067][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49330 2024-03-30 08:11:45.618 UTC [40067][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-30 08:11:45.618 UTC [40067][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-30 08:11:45.621 UTC [40067][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-30 08:11:45.623 UTC [40067][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.020 user=ssltestuser database=trustdb host=localhost port=49330 2024-03-30 08:11:45.650 UTC [40069][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49331 2024-03-30 08:11:45.721 UTC [40069][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-30 08:11:45.721 UTC [40069][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-30 08:11:45.722 UTC [40069][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-30 08:11:45.729 UTC [40069][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.080 user=ssltestuser database=trustdb host=localhost port=49331 2024-03-30 08:11:45.749 UTC [40056][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:45.749 UTC [40056][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:45.751 UTC [40056][postmaster] LOG: background worker "logical replication launcher" (PID 40062) exited with exit code 1 2024-03-30 08:11:45.751 UTC [40057][checkpointer] LOG: shutting down 2024-03-30 08:11:45.751 UTC [40057][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:45.752 UTC [40057][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/40D42E0, redo lsn=0/40D42E0 2024-03-30 08:11:45.754 UTC [40056][postmaster] LOG: database system is shut down 2024-03-30 08:11:45.881 UTC [40073][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:45.882 UTC [40073][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:45.882 UTC [40073][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:45.886 UTC [40076][startup] LOG: database system was shut down at 2024-03-30 08:11:45 UTC 2024-03-30 08:11:45.888 UTC [40073][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:46.073 UTC [40083][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49332 2024-03-30 08:11:46.092 UTC [40083][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-30 08:11:46.092 UTC [40083][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-30 08:11:46.096 UTC [40083][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-30 08:11:46.096 UTC [40083][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.025 user=ssltestuser database=trustdb host=localhost port=49332 2024-03-30 08:11:46.119 UTC [40085][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49333 2024-03-30 08:11:46.143 UTC [40073][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:46.143 UTC [40073][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:46.151 UTC [40073][postmaster] LOG: background worker "logical replication launcher" (PID 40079) exited with exit code 1 2024-03-30 08:11:46.153 UTC [40074][checkpointer] LOG: shutting down 2024-03-30 08:11:46.153 UTC [40074][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:46.156 UTC [40074][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.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D4358, redo lsn=0/40D4358 2024-03-30 08:11:46.157 UTC [40073][postmaster] LOG: database system is shut down 2024-03-30 08:11:46.280 UTC [40088][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:46.281 UTC [40088][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:46.282 UTC [40088][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:46.286 UTC [40092][startup] LOG: database system was shut down at 2024-03-30 08:11:46 UTC 2024-03-30 08:11:46.289 UTC [40088][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:46.386 UTC [40098][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49334 2024-03-30 08:11:46.407 UTC [40098][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-30 08:11:46.468 UTC [40101][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49335 2024-03-30 08:11:46.479 UTC [40101][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-30 08:11:46.479 UTC [40101][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-30 08:11:46.483 UTC [40101][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-30 08:11:46.483 UTC [40101][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=49335 2024-03-30 08:11:46.498 UTC [40103][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49336 2024-03-30 08:11:46.511 UTC [40088][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:46.511 UTC [40088][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:46.512 UTC [40088][postmaster] LOG: background worker "logical replication launcher" (PID 40095) exited with exit code 1 2024-03-30 08:11:46.513 UTC [40090][checkpointer] LOG: shutting down 2024-03-30 08:11:46.513 UTC [40090][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:46.514 UTC [40090][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/40D43D0, redo lsn=0/40D43D0 2024-03-30 08:11:46.516 UTC [40088][postmaster] LOG: database system is shut down 2024-03-30 08:11:46.641 UTC [40106][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:46.641 UTC [40106][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:46.642 UTC [40106][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:46.646 UTC [40109][startup] LOG: database system was shut down at 2024-03-30 08:11:46 UTC 2024-03-30 08:11:46.652 UTC [40106][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:46.740 UTC [40114][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49337 2024-03-30 08:11:46.751 UTC [40114][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-30 08:11:46.751 UTC [40114][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-30 08:11:46.754 UTC [40114][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-30 08:11:46.754 UTC [40114][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=49337 2024-03-30 08:11:46.767 UTC [40116][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49338 2024-03-30 08:11:46.777 UTC [40116][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: ssl/tls alert certificate revoked 2024-03-30 08:11:46.790 UTC [40118][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49339 2024-03-30 08:11:46.801 UTC [40118][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: ssl/tls alert certificate revoked 2024-03-30 08:11:46.815 UTC [40120][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49340 2024-03-30 08:11:46.825 UTC [40120][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-30 08:11:46.825 UTC [40120][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-30 08:11:46.825 UTC [40120][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-30 08:11:46.827 UTC [40120][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49340 2024-03-30 08:11:46.840 UTC [40122][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49341 2024-03-30 08:11:46.851 UTC [40122][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-30 08:11:46.851 UTC [40122][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-30 08:11:46.851 UTC [40122][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-30 08:11:46.852 UTC [40122][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49341 2024-03-30 08:11:46.902 UTC [40127][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49342 2024-03-30 08:11:46.914 UTC [40127][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-03-30 08:11:46.928 UTC [40129][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49343 2024-03-30 08:11:46.939 UTC [40129][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-30 08:11:46.939 UTC [40129][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-30 08:11:46.942 UTC [40129][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_qvmR/client.key$$ 2024-03-30 08:11:46.942 UTC [40129][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.014 user=ssltestuser database=certdb host=localhost port=49343 2024-03-30 08:11:46.955 UTC [40132][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49344 2024-03-30 08:11:46.965 UTC [40132][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-30 08:11:46.965 UTC [40132][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-30 08:11:46.966 UTC [40132][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_qvmR/client-der.key$$ 2024-03-30 08:11:46.966 UTC [40132][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=49344 2024-03-30 08:11:46.979 UTC [40134][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49345 2024-03-30 08:11:46.990 UTC [40134][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-30 08:11:46.990 UTC [40134][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-30 08:11:46.991 UTC [40134][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_qvmR/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-03-30 08:11:46.991 UTC [40134][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb host=localhost port=49345 2024-03-30 08:11:47.003 UTC [40136][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49346 2024-03-30 08:11:47.014 UTC [40136][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-30 08:11:47.014 UTC [40136][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-30 08:11:47.015 UTC [40136][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_qvmR/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-03-30 08:11:47.015 UTC [40136][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb host=localhost port=49346 2024-03-30 08:11:47.027 UTC [40138][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49347 2024-03-30 08:11:47.039 UTC [40138][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-30 08:11:47.039 UTC [40138][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-30 08:11:47.040 UTC [40138][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_qvmR/client.key$$ 2024-03-30 08:11:47.041 UTC [40138][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.014 user=ssltestuser database=certdb host=localhost port=49347 2024-03-30 08:11:47.061 UTC [40140][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49348 2024-03-30 08:11:47.076 UTC [40140][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-30 08:11:47.076 UTC [40140][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-30 08:11:47.077 UTC [40140][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_qvmR/client.key$$ 2024-03-30 08:11:47.078 UTC [40140][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=ssltestuser database=certdb host=localhost port=49348 2024-03-30 08:11:47.098 UTC [40142][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49349 2024-03-30 08:11:47.111 UTC [40142][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-03-30 08:11:47.131 UTC [40144][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49350 2024-03-30 08:11:47.144 UTC [40144][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-30 08:11:47.162 UTC [40146][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49351 2024-03-30 08:11:47.179 UTC [40146][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-30 08:11:47.179 UTC [40146][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-30 08:11:47.183 UTC [40146][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_qvmR/client-dn.key$$ 2024-03-30 08:11:47.184 UTC [40146][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.023 user=ssltestuser database=certdb_dn host=localhost port=49351 2024-03-30 08:11:47.203 UTC [40149][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49352 2024-03-30 08:11:47.218 UTC [40149][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-30 08:11:47.218 UTC [40149][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-30 08:11:47.224 UTC [40149][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_qvmR/client-dn.key$$ 2024-03-30 08:11:47.225 UTC [40149][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.022 user=ssltestuser database=certdb_dn_re host=localhost port=49352 2024-03-30 08:11:47.247 UTC [40151][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49353 2024-03-30 08:11:47.265 UTC [40151][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-30 08:11:47.265 UTC [40151][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-30 08:11:47.272 UTC [40151][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_qvmR/client-dn.key$$ 2024-03-30 08:11:47.272 UTC [40151][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.026 user=ssltestuser database=certdb_cn host=localhost port=49353 2024-03-30 08:11:47.337 UTC [40155][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49354 2024-03-30 08:11:47.353 UTC [40155][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-30 08:11:47.354 UTC [40155][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-30 08:11:47.354 UTC [40155][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-30 08:11:47.355 UTC [40155][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.020 user=ssltestuser database=certdb host=localhost port=49354 2024-03-30 08:11:47.375 UTC [40157][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49355 2024-03-30 08:11:47.390 UTC [40157][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-30 08:11:47.408 UTC [40159][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49356 2024-03-30 08:11:47.425 UTC [40159][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-30 08:11:47.425 UTC [40159][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-30 08:11:47.425 UTC [40159][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-03-30 08:11:47.425 UTC [40159][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-30 08:11:47.442 UTC [40161][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49357 2024-03-30 08:11:47.462 UTC [40161][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-30 08:11:47.462 UTC [40161][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-30 08:11:47.477 UTC [40163][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49358 2024-03-30 08:11:47.490 UTC [40163][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-30 08:11:47.490 UTC [40163][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-30 08:11:47.494 UTC [40163][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_qvmR/client.key$$ 2024-03-30 08:11:47.494 UTC [40163][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=verifydb host=localhost port=49358 2024-03-30 08:11:47.508 UTC [40165][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49359 2024-03-30 08:11:47.521 UTC [40165][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-30 08:11:47.521 UTC [40165][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-03-30 08:11:47.521 UTC [40165][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-03-30 08:11:47.521 UTC [40165][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-30 08:11:47.539 UTC [40167][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49360 2024-03-30 08:11:47.552 UTC [40167][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-30 08:11:47.552 UTC [40167][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-30 08:11:47.553 UTC [40167][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_qvmR/client.key$$ 2024-03-30 08:11:47.554 UTC [40167][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=yetanotheruser database=verifydb host=localhost port=49360 2024-03-30 08:11:47.564 UTC [40106][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:47.564 UTC [40106][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:47.565 UTC [40106][postmaster] LOG: background worker "logical replication launcher" (PID 40112) exited with exit code 1 2024-03-30 08:11:47.566 UTC [40107][checkpointer] LOG: shutting down 2024-03-30 08:11:47.566 UTC [40107][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:47.567 UTC [40107][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/40D4448, redo lsn=0/40D4448 2024-03-30 08:11:47.571 UTC [40106][postmaster] LOG: database system is shut down 2024-03-30 08:11:47.702 UTC [40171][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:47.702 UTC [40171][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:47.714 UTC [40171][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:47.735 UTC [40175][startup] LOG: database system was shut down at 2024-03-30 08:11:47 UTC 2024-03-30 08:11:47.738 UTC [40171][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:47.787 UTC [40180][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49361 2024-03-30 08:11:47.816 UTC [40180][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-30 08:11:47.816 UTC [40180][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-30 08:11:47.898 UTC [40180][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_qvmR/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-30 08:11:47.898 UTC [40180][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.112 user=ssltestuser database=certdb host=localhost port=49361 2024-03-30 08:11:47.921 UTC [40182][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49362 2024-03-30 08:11:47.937 UTC [40182][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-30 08:11:47.937 UTC [40182][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-30 08:11:48.068 UTC [40186][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49363 2024-03-30 08:11:48.140 UTC [40186][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-30 08:11:48.140 UTC [40186][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-30 08:11:48.238 UTC [40171][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:48.238 UTC [40171][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:48.242 UTC [40171][postmaster] LOG: background worker "logical replication launcher" (PID 40178) exited with exit code 1 2024-03-30 08:11:48.242 UTC [40173][checkpointer] LOG: shutting down 2024-03-30 08:11:48.243 UTC [40173][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:48.246 UTC [40173][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D44C0, redo lsn=0/40D44C0 2024-03-30 08:11:48.253 UTC [40171][postmaster] LOG: database system is shut down 2024-03-30 08:11:48.384 UTC [40191][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:48.384 UTC [40191][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:48.384 UTC [40191][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:48.393 UTC [40194][startup] LOG: database system was shut down at 2024-03-30 08:11:48 UTC 2024-03-30 08:11:48.405 UTC [40191][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:48.496 UTC [40199][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49364 2024-03-30 08:11:48.512 UTC [40199][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-30 08:11:48.512 UTC [40199][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-30 08:11:48.527 UTC [40191][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:48.527 UTC [40191][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:48.538 UTC [40191][postmaster] LOG: background worker "logical replication launcher" (PID 40197) exited with exit code 1 2024-03-30 08:11:48.538 UTC [40192][checkpointer] LOG: shutting down 2024-03-30 08:11:48.538 UTC [40192][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:48.539 UTC [40192][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/40D4538, redo lsn=0/40D4538 2024-03-30 08:11:48.541 UTC [40191][postmaster] LOG: database system is shut down 2024-03-30 08:11:48.657 UTC [40201][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:48.658 UTC [40201][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:48.658 UTC [40201][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:48.662 UTC [40204][startup] LOG: database system was shut down at 2024-03-30 08:11:48 UTC 2024-03-30 08:11:48.664 UTC [40201][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:48.754 UTC [40209][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49365 2024-03-30 08:11:48.765 UTC [40209][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-30 08:11:48.765 UTC [40209][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-30 08:11:48.788 UTC [40211][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49366 2024-03-30 08:11:48.799 UTC [40211][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-30 08:11:48.799 UTC [40211][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-30 08:11:48.812 UTC [40201][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:48.812 UTC [40201][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:48.814 UTC [40201][postmaster] LOG: background worker "logical replication launcher" (PID 40207) exited with exit code 1 2024-03-30 08:11:48.815 UTC [40202][checkpointer] LOG: shutting down 2024-03-30 08:11:48.816 UTC [40202][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:48.817 UTC [40202][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/40D45B0, redo lsn=0/40D45B0 2024-03-30 08:11:48.819 UTC [40201][postmaster] LOG: database system is shut down 2024-03-30 08:11:48.953 UTC [40213][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:48.954 UTC [40213][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:48.954 UTC [40213][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:48.966 UTC [40216][startup] LOG: database system was shut down at 2024-03-30 08:11:48 UTC 2024-03-30 08:11:48.971 UTC [40213][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:49.060 UTC [40221][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49367 2024-03-30 08:11:49.076 UTC [40221][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-30 08:11:49.076 UTC [40221][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-30 08:11:49.098 UTC [40223][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49368 2024-03-30 08:11:49.112 UTC [40223][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-30 08:11:49.112 UTC [40223][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-30 08:11:49.115 UTC [40223][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-30 08:11:49.116 UTC [40223][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.019 user=ssltestuser database=trustdb host=localhost port=49368 2024-03-30 08:11:49.124 UTC [40213][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:49.124 UTC [40213][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:49.126 UTC [40213][postmaster] LOG: background worker "logical replication launcher" (PID 40219) exited with exit code 1 2024-03-30 08:11:49.127 UTC [40214][checkpointer] LOG: shutting down 2024-03-30 08:11:49.127 UTC [40214][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:49.129 UTC [40214][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/40D4628, redo lsn=0/40D4628 2024-03-30 08:11:49.130 UTC [40213][postmaster] LOG: database system is shut down 2024-03-30 08:11:49.250 UTC [40225][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:49.251 UTC [40225][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:49.251 UTC [40225][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:49.255 UTC [40228][startup] LOG: database system was shut down at 2024-03-30 08:11:49 UTC 2024-03-30 08:11:49.257 UTC [40225][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:49.820 UTC [40233][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49369 2024-03-30 08:11:49.830 UTC [40233][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-30 08:11:49.830 UTC [40233][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-30 08:11:49.845 UTC [40235][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49370 2024-03-30 08:11:49.860 UTC [40235][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-30 08:11:49.860 UTC [40235][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-30 08:11:49.864 UTC [40235][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-30 08:11:49.864 UTC [40235][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.020 user=ssltestuser database=trustdb host=localhost port=49370 2024-03-30 08:11:49.871 UTC [40225][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:49.871 UTC [40225][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:49.872 UTC [40225][postmaster] LOG: background worker "logical replication launcher" (PID 40231) exited with exit code 1 2024-03-30 08:11:49.872 UTC [40226][checkpointer] LOG: shutting down 2024-03-30 08:11:49.872 UTC [40226][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:49.875 UTC [40226][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.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D46A0, redo lsn=0/40D46A0 2024-03-30 08:11:49.877 UTC [40225][postmaster] LOG: database system is shut down 2024-03-30 08:11:49.996 UTC [40237][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:49.997 UTC [40237][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:49.998 UTC [40237][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:50.002 UTC [40240][startup] LOG: database system was shut down at 2024-03-30 08:11:49 UTC 2024-03-30 08:11:50.005 UTC [40237][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:50.098 UTC [40245][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49371 2024-03-30 08:11:50.106 UTC [40245][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-30 08:11:50.106 UTC [40245][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-30 08:11:50.122 UTC [40247][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49372 2024-03-30 08:11:50.135 UTC [40247][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-30 08:11:50.135 UTC [40247][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-30 08:11:50.139 UTC [40247][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-30 08:11:50.140 UTC [40247][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49372 2024-03-30 08:11:50.147 UTC [40237][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:50.147 UTC [40237][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:50.148 UTC [40237][postmaster] LOG: background worker "logical replication launcher" (PID 40243) exited with exit code 1 2024-03-30 08:11:50.149 UTC [40238][checkpointer] LOG: shutting down 2024-03-30 08:11:50.149 UTC [40238][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:50.150 UTC [40238][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/40D4718, redo lsn=0/40D4718 2024-03-30 08:11:50.151 UTC [40237][postmaster] LOG: database system is shut down 2024-03-30 08:11:50.272 UTC [40249][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:50.273 UTC [40249][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:50.273 UTC [40249][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:50.276 UTC [40252][startup] LOG: database system was shut down at 2024-03-30 08:11:50 UTC 2024-03-30 08:11:50.278 UTC [40249][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:50.371 UTC [40257][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49373 2024-03-30 08:11:50.380 UTC [40257][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-30 08:11:50.380 UTC [40257][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-30 08:11:50.395 UTC [40259][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49374 2024-03-30 08:11:50.406 UTC [40259][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-30 08:11:50.406 UTC [40259][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-30 08:11:50.409 UTC [40259][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-30 08:11:50.409 UTC [40259][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=49374 2024-03-30 08:11:50.416 UTC [40249][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:50.416 UTC [40249][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:50.416 UTC [40249][postmaster] LOG: background worker "logical replication launcher" (PID 40255) exited with exit code 1 2024-03-30 08:11:50.417 UTC [40250][checkpointer] LOG: shutting down 2024-03-30 08:11:50.417 UTC [40250][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:50.418 UTC [40250][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/40D4790, redo lsn=0/40D4790 2024-03-30 08:11:50.419 UTC [40249][postmaster] LOG: database system is shut down 2024-03-30 08:11:50.542 UTC [40261][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:50.543 UTC [40261][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:50.543 UTC [40261][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:50.546 UTC [40264][startup] LOG: database system was shut down at 2024-03-30 08:11:50 UTC 2024-03-30 08:11:50.547 UTC [40261][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:50.640 UTC [40269][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49375 2024-03-30 08:11:50.648 UTC [40269][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-30 08:11:50.648 UTC [40269][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-30 08:11:50.661 UTC [40271][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49376 2024-03-30 08:11:50.671 UTC [40271][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-30 08:11:50.671 UTC [40271][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-30 08:11:50.674 UTC [40271][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-30 08:11:50.674 UTC [40271][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49376 2024-03-30 08:11:50.680 UTC [40261][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:50.680 UTC [40261][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:50.681 UTC [40261][postmaster] LOG: background worker "logical replication launcher" (PID 40267) exited with exit code 1 2024-03-30 08:11:50.681 UTC [40262][checkpointer] LOG: shutting down 2024-03-30 08:11:50.681 UTC [40262][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:50.681 UTC [40262][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/40D4808, redo lsn=0/40D4808 2024-03-30 08:11:50.683 UTC [40261][postmaster] LOG: database system is shut down 2024-03-30 08:11:50.811 UTC [40273][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:50.811 UTC [40273][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:50.812 UTC [40273][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:50.816 UTC [40276][startup] LOG: database system was shut down at 2024-03-30 08:11:50 UTC 2024-03-30 08:11:50.819 UTC [40273][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:50.913 UTC [40281][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49377 2024-03-30 08:11:50.921 UTC [40281][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-30 08:11:50.921 UTC [40281][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-30 08:11:50.936 UTC [40283][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49378 2024-03-30 08:11:50.948 UTC [40283][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-30 08:11:50.948 UTC [40283][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-30 08:11:50.952 UTC [40283][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-30 08:11:50.952 UTC [40283][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=49378 2024-03-30 08:11:50.959 UTC [40273][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:50.959 UTC [40273][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:50.960 UTC [40273][postmaster] LOG: background worker "logical replication launcher" (PID 40279) exited with exit code 1 2024-03-30 08:11:50.960 UTC [40274][checkpointer] LOG: shutting down 2024-03-30 08:11:50.960 UTC [40274][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:50.961 UTC [40274][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/40D4880, redo lsn=0/40D4880 2024-03-30 08:11:50.962 UTC [40273][postmaster] LOG: database system is shut down 2024-03-30 08:11:51.084 UTC [40285][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:51.084 UTC [40285][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:51.084 UTC [40285][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:51.087 UTC [40288][startup] LOG: database system was shut down at 2024-03-30 08:11:50 UTC 2024-03-30 08:11:51.089 UTC [40285][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:51.187 UTC [40293][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49379 2024-03-30 08:11:51.194 UTC [40293][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-30 08:11:51.195 UTC [40293][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-30 08:11:51.207 UTC [40295][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49380 2024-03-30 08:11:51.217 UTC [40295][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-30 08:11:51.217 UTC [40295][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-30 08:11:51.220 UTC [40295][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-30 08:11:51.220 UTC [40295][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=49380 2024-03-30 08:11:51.226 UTC [40285][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:51.226 UTC [40285][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:51.227 UTC [40285][postmaster] LOG: background worker "logical replication launcher" (PID 40291) exited with exit code 1 2024-03-30 08:11:51.227 UTC [40286][checkpointer] LOG: shutting down 2024-03-30 08:11:51.227 UTC [40286][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:51.228 UTC [40286][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/40D48F8, redo lsn=0/40D48F8 2024-03-30 08:11:51.229 UTC [40285][postmaster] LOG: database system is shut down 2024-03-30 08:11:51.348 UTC [40297][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:51.348 UTC [40297][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:51.349 UTC [40297][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:51.351 UTC [40300][startup] LOG: database system was shut down at 2024-03-30 08:11:51 UTC 2024-03-30 08:11:51.353 UTC [40297][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:51.445 UTC [40305][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49381 2024-03-30 08:11:51.452 UTC [40305][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-30 08:11:51.452 UTC [40305][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-30 08:11:51.464 UTC [40307][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49382 2024-03-30 08:11:51.473 UTC [40307][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-30 08:11:51.473 UTC [40307][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-30 08:11:51.475 UTC [40307][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-30 08:11:51.476 UTC [40307][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49382 2024-03-30 08:11:51.480 UTC [40297][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:51.481 UTC [40297][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:51.481 UTC [40297][postmaster] LOG: background worker "logical replication launcher" (PID 40303) exited with exit code 1 2024-03-30 08:11:51.481 UTC [40298][checkpointer] LOG: shutting down 2024-03-30 08:11:51.481 UTC [40298][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:51.484 UTC [40298][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.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/40D4970, redo lsn=0/40D4970 2024-03-30 08:11:51.485 UTC [40297][postmaster] LOG: database system is shut down 2024-03-30 08:11:51.606 UTC [40309][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-30 08:11:51.607 UTC [40309][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 50696 2024-03-30 08:11:51.607 UTC [40309][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/ZDP5IrgcBJ/.s.PGSQL.50696" 2024-03-30 08:11:51.610 UTC [40312][startup] LOG: database system was shut down at 2024-03-30 08:11:51 UTC 2024-03-30 08:11:51.612 UTC [40309][postmaster] LOG: database system is ready to accept connections 2024-03-30 08:11:51.712 UTC [40317][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49383 2024-03-30 08:11:51.719 UTC [40317][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-30 08:11:51.719 UTC [40317][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-30 08:11:51.732 UTC [40319][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49384 2024-03-30 08:11:51.741 UTC [40319][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-30 08:11:51.741 UTC [40319][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-30 08:11:51.744 UTC [40319][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-30 08:11:51.744 UTC [40319][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49384 2024-03-30 08:11:51.750 UTC [40309][postmaster] LOG: received fast shutdown request 2024-03-30 08:11:51.750 UTC [40309][postmaster] LOG: aborting any active transactions 2024-03-30 08:11:51.750 UTC [40309][postmaster] LOG: background worker "logical replication launcher" (PID 40315) exited with exit code 1 2024-03-30 08:11:51.751 UTC [40310][checkpointer] LOG: shutting down 2024-03-30 08:11:51.751 UTC [40310][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-30 08:11:51.751 UTC [40310][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/40D49E8, redo lsn=0/40D49E8 2024-03-30 08:11:51.752 UTC [40309][postmaster] LOG: database system is shut down 2024-03-30 08:11:51.875 UTC [40321][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-03-30 08:11:51.875 UTC [40321][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [08:11:51.971](0.226s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"