[13:21:03.782](0.030s) # setting up data directory # Checking port 59354 # Found port 59354 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=59354 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj Log file: /Users/admin/pgsql/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [13:21:03.803](0.021s) # 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 38300 [13:21:04.364](0.562s) 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 38738 [13:21:06.625](2.261s) # 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-16 13:21:04.265 UTC [38300][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:04.265 UTC [38300][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:04.278 UTC [38316][startup] LOG: database system was shut down at 2024-03-16 13:17:55 UTC 2024-03-16 13:21:04.284 UTC [38300][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:04.360 UTC [38343][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.360 UTC [38343][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-16 13:21:04.360 UTC [38343][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.363 UTC [38343][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-16 13:21:04.363 UTC [38343][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-03-16 13:21:04.384 UTC [38347][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.384 UTC [38347][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-16 13:21:04.385 UTC [38347][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.386 UTC [38347][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-16 13:21:04.388 UTC [38347][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-03-16 13:21:04.445 UTC [38368][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.445 UTC [38368][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-16 13:21:04.445 UTC [38368][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.447 UTC [38368][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-16 13:21:04.450 UTC [38368][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=admin database=postgres host=[local] 2024-03-16 13:21:04.492 UTC [38387][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.492 UTC [38387][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-16 13:21:04.492 UTC [38387][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.494 UTC [38387][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-16 13:21:04.494 UTC [38387][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=admin database=postgres host=[local] 2024-03-16 13:21:04.553 UTC [38405][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.554 UTC [38405][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-16 13:21:04.554 UTC [38405][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.554 UTC [38405][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-16 13:21:04.554 UTC [38405][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-16 13:21:04.603 UTC [38433][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.603 UTC [38433][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-16 13:21:04.603 UTC [38433][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.604 UTC [38433][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-16 13:21:04.655 UTC [38433][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.052 user=admin database=postgres host=[local] 2024-03-16 13:21:04.753 UTC [38471][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.754 UTC [38471][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-16 13:21:04.754 UTC [38471][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.754 UTC [38471][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-16 13:21:04.873 UTC [38471][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.119 user=admin database=postgres host=[local] 2024-03-16 13:21:04.889 UTC [38505][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.889 UTC [38505][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-16 13:21:04.889 UTC [38505][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.890 UTC [38505][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-16 13:21:05.023 UTC [38505][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.133 user=admin database=postgres host=[local] 2024-03-16 13:21:05.199 UTC [38567][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:05.199 UTC [38567][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-16 13:21:05.199 UTC [38567][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:05.200 UTC [38567][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-16 13:21:05.311 UTC [38567][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.112 user=admin database=postgres host=[local] 2024-03-16 13:21:05.340 UTC [38581][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:05.340 UTC [38581][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-16 13:21:05.340 UTC [38581][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:05.341 UTC [38581][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-16 13:21:05.410 UTC [38581][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.070 user=admin database=postgres host=[local] 2024-03-16 13:21:05.474 UTC [38605][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:05.474 UTC [38605][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-16 13:21:05.474 UTC [38605][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:05.474 UTC [38605][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-16 13:21:05.587 UTC [38605][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.113 user=admin database=postgres host=[local] 2024-03-16 13:21:05.755 UTC [38300][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:05.755 UTC [38300][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:05.756 UTC [38300][postmaster] LOG: background worker "logical replication launcher" (PID 38320) exited with exit code 1 2024-03-16 13:21:05.757 UTC [38314][checkpointer] LOG: shutting down 2024-03-16 13:21:05.757 UTC [38314][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:06.496 UTC [38314][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.739 s, sync=0.001 s, total=0.739 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4102DB0, redo lsn=0/4102DB0 2024-03-16 13:21:06.506 UTC [38300][postmaster] LOG: database system is shut down 2024-03-16 13:21:06.593 UTC [38738][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:06.599 UTC [38738][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:06.600 UTC [38738][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:06.603 UTC [38744][startup] LOG: database system was shut down at 2024-03-16 13:21:06 UTC 2024-03-16 13:21:06.605 UTC [38738][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:06.633 UTC [38738][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:06.633 UTC [38738][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:06.633 UTC [38738][postmaster] LOG: background worker "logical replication launcher" (PID 38750) exited with exit code 1 2024-03-16 13:21:06.633 UTC [38742][checkpointer] LOG: shutting down 2024-03-16 13:21:06.634 UTC [38742][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:06.719 UTC [38742][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.086 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4102E28, redo lsn=0/4102E28 2024-03-16 13:21:06.720 UTC [38738][postmaster] LOG: database system is shut down 2024-03-16 13:21:06.761 UTC [38757][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-16 13:21:06.761 UTC [38757][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [13:21:06.854](0.229s) 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 38769 [13:21:06.970](0.115s) ok 3 - restart succeeds with password-protected key file ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... stopped waiting pg_ctl: could not start server Examine the log output. # pg_ctl restart failed; logfile: 2024-03-16 13:21:04.265 UTC [38300][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:04.265 UTC [38300][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:04.278 UTC [38316][startup] LOG: database system was shut down at 2024-03-16 13:17:55 UTC 2024-03-16 13:21:04.284 UTC [38300][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:04.360 UTC [38343][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.360 UTC [38343][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-16 13:21:04.360 UTC [38343][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.363 UTC [38343][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-16 13:21:04.363 UTC [38343][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-03-16 13:21:04.384 UTC [38347][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.384 UTC [38347][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-16 13:21:04.385 UTC [38347][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.386 UTC [38347][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-16 13:21:04.388 UTC [38347][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-03-16 13:21:04.445 UTC [38368][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.445 UTC [38368][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-16 13:21:04.445 UTC [38368][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.447 UTC [38368][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-16 13:21:04.450 UTC [38368][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=admin database=postgres host=[local] 2024-03-16 13:21:04.492 UTC [38387][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.492 UTC [38387][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-16 13:21:04.492 UTC [38387][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.494 UTC [38387][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-16 13:21:04.494 UTC [38387][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=admin database=postgres host=[local] 2024-03-16 13:21:04.553 UTC [38405][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.554 UTC [38405][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-16 13:21:04.554 UTC [38405][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.554 UTC [38405][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-16 13:21:04.554 UTC [38405][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-16 13:21:04.603 UTC [38433][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.603 UTC [38433][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-16 13:21:04.603 UTC [38433][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.604 UTC [38433][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-16 13:21:04.655 UTC [38433][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.052 user=admin database=postgres host=[local] 2024-03-16 13:21:04.753 UTC [38471][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.754 UTC [38471][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-16 13:21:04.754 UTC [38471][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.754 UTC [38471][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-16 13:21:04.873 UTC [38471][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.119 user=admin database=postgres host=[local] 2024-03-16 13:21:04.889 UTC [38505][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.889 UTC [38505][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-16 13:21:04.889 UTC [38505][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.890 UTC [38505][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-16 13:21:05.023 UTC [38505][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.133 user=admin database=postgres host=[local] 2024-03-16 13:21:05.199 UTC [38567][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:05.199 UTC [38567][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-16 13:21:05.199 UTC [38567][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:05.200 UTC [38567][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-16 13:21:05.311 UTC [38567][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.112 user=admin database=postgres host=[local] 2024-03-16 13:21:05.340 UTC [38581][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:05.340 UTC [38581][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-16 13:21:05.340 UTC [38581][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:05.341 UTC [38581][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-16 13:21:05.410 UTC [38581][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.070 user=admin database=postgres host=[local] 2024-03-16 13:21:05.474 UTC [38605][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:05.474 UTC [38605][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-16 13:21:05.474 UTC [38605][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:05.474 UTC [38605][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-16 13:21:05.587 UTC [38605][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.113 user=admin database=postgres host=[local] 2024-03-16 13:21:05.755 UTC [38300][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:05.755 UTC [38300][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:05.756 UTC [38300][postmaster] LOG: background worker "logical replication launcher" (PID 38320) exited with exit code 1 2024-03-16 13:21:05.757 UTC [38314][checkpointer] LOG: shutting down 2024-03-16 13:21:05.757 UTC [38314][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:06.496 UTC [38314][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.739 s, sync=0.001 s, total=0.739 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4102DB0, redo lsn=0/4102DB0 2024-03-16 13:21:06.506 UTC [38300][postmaster] LOG: database system is shut down 2024-03-16 13:21:06.593 UTC [38738][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:06.599 UTC [38738][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:06.600 UTC [38738][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:06.603 UTC [38744][startup] LOG: database system was shut down at 2024-03-16 13:21:06 UTC 2024-03-16 13:21:06.605 UTC [38738][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:06.633 UTC [38738][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:06.633 UTC [38738][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:06.633 UTC [38738][postmaster] LOG: background worker "logical replication launcher" (PID 38750) exited with exit code 1 2024-03-16 13:21:06.633 UTC [38742][checkpointer] LOG: shutting down 2024-03-16 13:21:06.634 UTC [38742][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:06.719 UTC [38742][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.086 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4102E28, redo lsn=0/4102E28 2024-03-16 13:21:06.720 UTC [38738][postmaster] LOG: database system is shut down 2024-03-16 13:21:06.761 UTC [38757][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-16 13:21:06.761 UTC [38757][postmaster] LOG: database system is shut down 2024-03-16 13:21:06.883 UTC [38769][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:06.883 UTC [38769][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:06.892 UTC [38769][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:06.895 UTC [38779][startup] LOG: database system was shut down at 2024-03-16 13:21:06 UTC 2024-03-16 13:21:06.896 UTC [38769][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:06.974 UTC [38769][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:06.974 UTC [38769][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:06.975 UTC [38769][postmaster] LOG: background worker "logical replication launcher" (PID 38782) exited with exit code 1 2024-03-16 13:21:06.975 UTC [38777][checkpointer] LOG: shutting down 2024-03-16 13:21:06.975 UTC [38777][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:07.012 UTC [38777][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.038 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4102EA0, redo lsn=0/4102EA0 2024-03-16 13:21:07.014 UTC [38769][postmaster] LOG: database system is shut down 2024-03-16 13:21:07.095 UTC [38803][postmaster] FATAL: could not set SSL protocol version range 2024-03-16 13:21:07.095 UTC [38803][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-16 13:21:07.095 UTC [38803][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [13:21:07.181](0.211s) 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 38823 [13:21:07.291](0.110s) ok 5 - restart succeeds with correct SSL protocol bounds [13:21:07.291](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 38872 [13:21:07.533](0.241s) ok 6 - server doesn't accept non-SSL connections [13:21:07.533](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [13:21:07.554](0.021s) ok 8 - connect without server root cert sslmode=require [13:21:07.554](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [13:21:07.569](0.015s) ok 10 - connect without server root cert sslmode=verify-ca [13:21:07.569](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [13:21:07.583](0.015s) ok 12 - connect without server root cert sslmode=verify-full [13:21:07.583](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [13:21:07.601](0.018s) ok 14 - connect with wrong server root cert sslmode=require [13:21:07.601](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [13:21:07.618](0.017s) ok 16 - connect with wrong server root cert sslmode=verify-ca [13:21:07.618](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [13:21:07.635](0.017s) ok 18 - connect with wrong server root cert sslmode=verify-full [13:21:07.635](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [13:21:07.652](0.017s) ok 20 - connect with server CA cert, without root CA [13:21:07.652](0.000s) ok 21 - connect with server CA cert, without root CA: matches [13:21:07.671](0.019s) ok 22 - connect with correct server CA cert file sslmode=require [13:21:07.671](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [13:21:07.691](0.020s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [13:21:07.691](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [13:21:07.718](0.026s) ok 26 - connect with correct server CA cert file sslmode=verify-full [13:21:07.718](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [13:21:07.738](0.020s) ok 28 - cert root file that contains two certificates, order 1 [13:21:07.738](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [13:21:07.760](0.022s) ok 30 - cert root file that contains two certificates, order 2 [13:21:07.760](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [13:21:07.783](0.023s) ok 32 - connect with sslcertmode=disable [13:21:07.783](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [13:21:07.803](0.020s) ok 34 - connect with sslcertmode=allow [13:21:07.803](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [13:21:07.824](0.021s) ok 36 - connect with sslcertmode=require fails without a client certificate [13:21:07.824](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [13:21:07.845](0.021s) ok 38 - sslcrl option with invalid file name [13:21:07.845](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [13:21:07.865](0.020s) ok 40 - CRL belonging to a different CA [13:21:07.865](0.000s) ok 41 - CRL belonging to a different CA: matches [13:21:07.882](0.017s) ok 42 - directory CRL belonging to a different CA [13:21:07.882](0.000s) ok 43 - directory CRL belonging to a different CA: matches [13:21:07.901](0.019s) ok 44 - CRL with a non-revoked cert [13:21:07.901](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [13:21:07.918](0.017s) ok 46 - directory CRL with a non-revoked cert [13:21:07.918](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [13:21:07.935](0.017s) ok 48 - mismatch between host name and server certificate sslmode=require [13:21:07.935](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [13:21:07.954](0.019s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [13:21:07.954](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [13:21:07.970](0.016s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [13:21:07.970](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 38984 [13:21:08.216](0.246s) ok 54 - IP address in the Common Name [13:21:08.217](0.000s) ok 55 - IP address in the Common Name: no stderr [13:21:08.234](0.017s) ok 56 - mismatch between host name and server certificate IP address [13:21:08.234](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 39005 [13:21:08.492](0.258s) ok 58 - IP address in a dNSName [13:21:08.492](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 39015 [13:21:08.737](0.246s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [13:21:08.738](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [13:21:08.758](0.021s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [13:21:08.758](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [13:21:08.776](0.018s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [13:21:08.776](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [13:21:08.792](0.016s) ok 66 - host name not matching with X.509 Subject Alternative Names [13:21:08.792](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [13:21:08.808](0.016s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [13:21:08.808](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 39033 [13:21:09.065](0.257s) ok 70 - host name matching with a single X.509 Subject Alternative Name [13:21:09.066](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [13:21:09.084](0.018s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [13:21:09.084](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [13:21:09.099](0.016s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [13:21:09.100](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 39048 [13:21:09.370](0.271s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [13:21:09.370](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [13:21:09.392](0.022s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [13:21:09.392](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [13:21:09.409](0.017s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [13:21:09.409](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [13:21:09.426](0.017s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [13:21:09.426](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [13:21:09.443](0.017s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [13:21:09.443](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [13:21:09.460](0.017s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [13:21:09.460](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [13:21:09.476](0.016s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [13:21:09.476](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [13:21:09.492](0.016s) ok 90 - IPv6 host with CIDR mask does not match [13:21:09.492](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 39072 [13:21:09.750](0.258s) ok 92 - certificate with both a CN and SANs 1 [13:21:09.750](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [13:21:09.771](0.021s) ok 94 - certificate with both a CN and SANs 2 [13:21:09.771](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [13:21:09.788](0.017s) ok 96 - certificate with both a CN and SANs ignores CN [13:21:09.788](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 39087 [13:21:10.070](0.282s) ok 98 - certificate with both a CN and IP SANs matches CN [13:21:10.070](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [13:21:10.092](0.022s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [13:21:10.092](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [13:21:10.109](0.017s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [13:21:10.109](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 39101 [13:21:10.390](0.280s) ok 104 - certificate with both an IP CN and IP SANs 1 [13:21:10.390](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [13:21:10.412](0.022s) ok 106 - certificate with both an IP CN and IP SANs 2 [13:21:10.412](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [13:21:10.429](0.017s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [13:21:10.429](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 39115 [13:21:10.679](0.251s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [13:21:10.680](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [13:21:10.700](0.020s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [13:21:10.700](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [13:21:10.717](0.017s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [13:21:10.717](0.000s) ok 115 - certificate with both an IP CN and DNS SANs matches SAN 2: no stderr ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart waiting for server to shut down.... done server stopped waiting for server to start.... done server started # Postmaster PID for node "primary" is 39129 [13:21:10.979](0.262s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [13:21:10.979](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [13:21:10.999](0.020s) ok 118 - server certificate without CN or SANs sslmode=verify-full [13:21:10.999](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 39141 [13:21:11.272](0.273s) ok 120 - sslrootcert=system does not connect with private CA [13:21:11.272](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [13:21:11.284](0.011s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [13:21:11.284](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [13:21:11.307](0.023s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [13:21:11.307](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [13:21:11.324](0.017s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [13:21:11.324](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 39156 [13:21:11.597](0.273s) ok 128 - connects without client-side CRL [13:21:11.597](0.000s) ok 129 - connects without client-side CRL: no stderr [13:21:11.617](0.020s) ok 130 - does not connect with client-side CRL file [13:21:11.618](0.000s) ok 131 - does not connect with client-side CRL file: matches [13:21:11.635](0.017s) ok 132 - does not connect with client-side CRL directory [13:21:11.635](0.000s) ok 133 - does not connect with client-side CRL directory: matches # Running: psql -X -A -F , -P null=_null_ -d sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=invalid -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [13:21:11.651](0.017s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [13:21:11.652](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [13:21:11.652](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [13:21:11.668](0.017s) ok 137 - connection success with correct range of TLS protocol versions [13:21:11.668](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [13:21:11.676](0.008s) ok 139 - connection failure with incorrect range of TLS protocol versions [13:21:11.676](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [13:21:11.683](0.007s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [13:21:11.683](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [13:21:11.691](0.007s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [13:21:11.691](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [13:21:11.691](0.000s) # running server tests [13:21:11.707](0.016s) ok 145 - certificate authorization fails without client cert [13:21:11.707](0.000s) ok 146 - certificate authorization fails without client cert: matches [13:21:11.728](0.020s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [13:21:11.728](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [13:21:11.746](0.018s) ok 149 - certificate authorization succeeds with correct client cert in DER format [13:21:11.746](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [13:21:11.764](0.018s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [13:21:11.764](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [13:21:11.782](0.018s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [13:21:11.782](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [13:21:11.800](0.018s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [13:21:11.800](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [13:21:11.818](0.018s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [13:21:11.818](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [13:21:11.834](0.016s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [13:21:11.834](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [13:21:11.849](0.015s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [13:21:11.849](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [13:21:11.870](0.021s) ok 163 - certificate authorization succeeds with DN mapping [13:21:11.870](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [13:21:11.870](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [13:21:11.890](0.020s) ok 166 - certificate authorization succeeds with DN regex mapping [13:21:11.890](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [13:21:11.910](0.020s) ok 168 - certificate authorization succeeds with CN mapping [13:21:11.910](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [13:21:11.910](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [13:21:11.910](0.000s) not ok 171 # TODO & SKIP Need Pty support [13:21:11.910](0.000s) not ok 172 # TODO & SKIP Need Pty support [13:21:11.910](0.000s) not ok 173 # TODO & SKIP Need Pty support [13:21:11.910](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_CVZo/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [13:21:11.939](0.028s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [13:21:11.939](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [13:21:11.939](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [13:21:11.953](0.014s) ok 178 - certificate authorization fails because of file permissions [13:21:11.953](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [13:21:11.970](0.017s) ok 180 - certificate authorization fails with client cert belonging to another user [13:21:11.970](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [13:21:11.988](0.018s) ok 182 - certificate authorization fails with revoked client cert [13:21:11.988](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [13:21:11.989](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [13:21:12.008](0.020s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [13:21:12.008](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [13:21:12.008](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [13:21:12.026](0.017s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [13:21:12.026](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [13:21:12.026](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [13:21:12.043](0.018s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [13:21:12.044](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [13:21:12.044](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 39216 [13:21:12.327](0.283s) ok 194 - intermediate client certificate is provided by client [13:21:12.327](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [13:21:12.349](0.022s) ok 196 - intermediate client certificate is missing [13:21:12.349](0.000s) ok 197 - intermediate client certificate is missing: matches [13:21:12.368](0.019s) ok 198 - logged client certificate Subjects are truncated if they're too long [13:21:12.368](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 39230 [13:21:12.638](0.270s) ok 200 - intermediate client certificate is untrusted [13:21:12.638](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 39240 [13:21:12.910](0.272s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [13:21:12.910](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [13:21:12.933](0.023s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [13:21:12.933](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 39252 [13:21:13.190](0.256s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [13:21:13.190](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1' # at /Users/admin/pgsql/src/test/ssl/t/001_ssltests.pl line 923. [13:21:13.190](0.000s) # got: '2' # expected: '0' [13:21:13.190](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [13:21:13.190](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr' # at /Users/admin/pgsql/src/test/ssl/t/001_ssltests.pl line 923. [13:21:13.190](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 59354 failed: server closed the connection unexpectedly # This probably means the server terminated abnormally # before or while processing the request. # SSL SYSCALL error: Connection reset by peer' # expected: '' [13:21:13.212](0.022s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [13:21:13.212](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 39265 [13:21:13.475](0.263s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [13:21:13.500](0.024s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [13:21:13.500](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 39277 [13:21:13.740](0.240s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [13:21:13.765](0.025s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [13:21:13.765](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 39290 [13:21:14.014](0.249s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [13:21:14.036](0.022s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [13:21:14.036](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 39303 [13:21:14.264](0.228s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [13:21:14.264](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1' # at /Users/admin/pgsql/src/test/ssl/t/001_ssltests.pl line 1001. [13:21:14.264](0.000s) # got: '2' # expected: '0' [13:21:14.264](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [13:21:14.264](0.000s) # Failed test 'connect with valid stapled ocsp response when sslocspstapling=1: no stderr' # at /Users/admin/pgsql/src/test/ssl/t/001_ssltests.pl line 1001. [13:21:14.264](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 59354 failed: server closed the connection unexpectedly # This probably means the server terminated abnormally # before or while processing the request. # SSL SYSCALL error: Connection reset by peer' # expected: '' [13:21:14.285](0.020s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [13:21:14.285](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 39316 [13:21:14.522](0.237s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [13:21:14.543](0.021s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [13:21:14.543](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 39328 [13:21:14.777](0.234s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [13:21:14.796](0.019s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [13:21:14.796](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 39340 [13:21:15.043](0.247s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [13:21:15.065](0.022s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [13:21:15.065](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 39353 [13:21:15.320](0.255s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [13:21:15.342](0.022s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [13:21:15.342](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-16 13:21:04.265 UTC [38300][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:04.265 UTC [38300][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:04.278 UTC [38316][startup] LOG: database system was shut down at 2024-03-16 13:17:55 UTC 2024-03-16 13:21:04.284 UTC [38300][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:04.360 UTC [38343][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.360 UTC [38343][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-16 13:21:04.360 UTC [38343][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.363 UTC [38343][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-16 13:21:04.363 UTC [38343][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-03-16 13:21:04.384 UTC [38347][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.384 UTC [38347][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="admin" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:117) 2024-03-16 13:21:04.385 UTC [38347][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.386 UTC [38347][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-16 13:21:04.388 UTC [38347][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-03-16 13:21:04.445 UTC [38368][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.445 UTC [38368][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-16 13:21:04.445 UTC [38368][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.447 UTC [38368][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-16 13:21:04.450 UTC [38368][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.005 user=admin database=postgres host=[local] 2024-03-16 13:21:04.492 UTC [38387][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.492 UTC [38387][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-16 13:21:04.492 UTC [38387][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.494 UTC [38387][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-16 13:21:04.494 UTC [38387][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.002 user=admin database=postgres host=[local] 2024-03-16 13:21:04.553 UTC [38405][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.554 UTC [38405][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-16 13:21:04.554 UTC [38405][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.554 UTC [38405][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-16 13:21:04.554 UTC [38405][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-16 13:21:04.603 UTC [38433][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.603 UTC [38433][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-16 13:21:04.603 UTC [38433][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.604 UTC [38433][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-16 13:21:04.655 UTC [38433][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.052 user=admin database=postgres host=[local] 2024-03-16 13:21:04.753 UTC [38471][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.754 UTC [38471][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-16 13:21:04.754 UTC [38471][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.754 UTC [38471][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-16 13:21:04.873 UTC [38471][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.119 user=admin database=postgres host=[local] 2024-03-16 13:21:04.889 UTC [38505][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:04.889 UTC [38505][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-16 13:21:04.889 UTC [38505][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:04.890 UTC [38505][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-16 13:21:05.023 UTC [38505][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.133 user=admin database=postgres host=[local] 2024-03-16 13:21:05.199 UTC [38567][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:05.199 UTC [38567][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-16 13:21:05.199 UTC [38567][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:05.200 UTC [38567][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-16 13:21:05.311 UTC [38567][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.112 user=admin database=postgres host=[local] 2024-03-16 13:21:05.340 UTC [38581][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:05.340 UTC [38581][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-16 13:21:05.340 UTC [38581][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:05.341 UTC [38581][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-16 13:21:05.410 UTC [38581][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.070 user=admin database=postgres host=[local] 2024-03-16 13:21:05.474 UTC [38605][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-16 13:21:05.474 UTC [38605][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-16 13:21:05.474 UTC [38605][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-16 13:21:05.474 UTC [38605][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-16 13:21:05.587 UTC [38605][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.113 user=admin database=postgres host=[local] 2024-03-16 13:21:05.755 UTC [38300][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:05.755 UTC [38300][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:05.756 UTC [38300][postmaster] LOG: background worker "logical replication launcher" (PID 38320) exited with exit code 1 2024-03-16 13:21:05.757 UTC [38314][checkpointer] LOG: shutting down 2024-03-16 13:21:05.757 UTC [38314][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:06.496 UTC [38314][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.739 s, sync=0.001 s, total=0.739 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/4102DB0, redo lsn=0/4102DB0 2024-03-16 13:21:06.506 UTC [38300][postmaster] LOG: database system is shut down 2024-03-16 13:21:06.593 UTC [38738][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:06.599 UTC [38738][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:06.600 UTC [38738][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:06.603 UTC [38744][startup] LOG: database system was shut down at 2024-03-16 13:21:06 UTC 2024-03-16 13:21:06.605 UTC [38738][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:06.633 UTC [38738][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:06.633 UTC [38738][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:06.633 UTC [38738][postmaster] LOG: background worker "logical replication launcher" (PID 38750) exited with exit code 1 2024-03-16 13:21:06.633 UTC [38742][checkpointer] LOG: shutting down 2024-03-16 13:21:06.634 UTC [38742][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:06.719 UTC [38742][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.086 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4102E28, redo lsn=0/4102E28 2024-03-16 13:21:06.720 UTC [38738][postmaster] LOG: database system is shut down 2024-03-16 13:21:06.761 UTC [38757][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-16 13:21:06.761 UTC [38757][postmaster] LOG: database system is shut down 2024-03-16 13:21:06.883 UTC [38769][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:06.883 UTC [38769][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:06.892 UTC [38769][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:06.895 UTC [38779][startup] LOG: database system was shut down at 2024-03-16 13:21:06 UTC 2024-03-16 13:21:06.896 UTC [38769][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:06.974 UTC [38769][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:06.974 UTC [38769][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:06.975 UTC [38769][postmaster] LOG: background worker "logical replication launcher" (PID 38782) exited with exit code 1 2024-03-16 13:21:06.975 UTC [38777][checkpointer] LOG: shutting down 2024-03-16 13:21:06.975 UTC [38777][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:07.012 UTC [38777][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.038 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4102EA0, redo lsn=0/4102EA0 2024-03-16 13:21:07.014 UTC [38769][postmaster] LOG: database system is shut down 2024-03-16 13:21:07.095 UTC [38803][postmaster] FATAL: could not set SSL protocol version range 2024-03-16 13:21:07.095 UTC [38803][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-16 13:21:07.095 UTC [38803][postmaster] LOG: database system is shut down 2024-03-16 13:21:07.201 UTC [38823][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:07.201 UTC [38823][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:07.201 UTC [38823][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:07.204 UTC [38835][startup] LOG: database system was shut down at 2024-03-16 13:21:06 UTC 2024-03-16 13:21:07.205 UTC [38823][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:07.295 UTC [38823][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:07.295 UTC [38823][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:07.296 UTC [38823][postmaster] LOG: background worker "logical replication launcher" (PID 38838) exited with exit code 1 2024-03-16 13:21:07.296 UTC [38833][checkpointer] LOG: shutting down 2024-03-16 13:21:07.297 UTC [38833][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:07.297 UTC [38833][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/4102F18, redo lsn=0/4102F18 2024-03-16 13:21:07.299 UTC [38823][postmaster] LOG: database system is shut down 2024-03-16 13:21:07.421 UTC [38872][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:07.421 UTC [38872][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:07.422 UTC [38872][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:07.426 UTC [38881][startup] LOG: database system was shut down at 2024-03-16 13:21:07 UTC 2024-03-16 13:21:07.428 UTC [38872][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:07.527 UTC [38901][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49267 2024-03-16 13:21:07.531 UTC [38901][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-16 13:21:07.531 UTC [38901][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-03-16 13:21:07.542 UTC [38905][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49270 2024-03-16 13:21:07.550 UTC [38905][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-16 13:21:07.550 UTC [38905][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-16 13:21:07.553 UTC [38905][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-16 13:21:07.553 UTC [38905][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49270 2024-03-16 13:21:07.563 UTC [38910][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49272 2024-03-16 13:21:07.568 UTC [38910][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-16 13:21:07.577 UTC [38914][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49274 2024-03-16 13:21:07.582 UTC [38914][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-16 13:21:07.592 UTC [38918][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49275 2024-03-16 13:21:07.600 UTC [38918][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-16 13:21:07.610 UTC [38928][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49277 2024-03-16 13:21:07.617 UTC [38928][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-16 13:21:07.627 UTC [38932][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49279 2024-03-16 13:21:07.634 UTC [38932][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-16 13:21:07.644 UTC [38936][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49281 2024-03-16 13:21:07.651 UTC [38936][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-16 13:21:07.661 UTC [38938][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49282 2024-03-16 13:21:07.669 UTC [38938][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-16 13:21:07.669 UTC [38938][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-16 13:21:07.670 UTC [38938][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-16 13:21:07.670 UTC [38938][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49282 2024-03-16 13:21:07.681 UTC [38940][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49283 2024-03-16 13:21:07.689 UTC [38940][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-16 13:21:07.689 UTC [38940][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-16 13:21:07.690 UTC [38940][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-16 13:21:07.690 UTC [38940][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49283 2024-03-16 13:21:07.705 UTC [38944][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49285 2024-03-16 13:21:07.716 UTC [38944][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-16 13:21:07.716 UTC [38944][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-16 13:21:07.716 UTC [38944][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-16 13:21:07.717 UTC [38944][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49285 2024-03-16 13:21:07.727 UTC [38947][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49286 2024-03-16 13:21:07.736 UTC [38947][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-16 13:21:07.736 UTC [38947][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-16 13:21:07.736 UTC [38947][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-16 13:21:07.737 UTC [38947][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49286 2024-03-16 13:21:07.749 UTC [38949][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49287 2024-03-16 13:21:07.758 UTC [38949][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-16 13:21:07.758 UTC [38949][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-16 13:21:07.759 UTC [38949][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-16 13:21:07.759 UTC [38949][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49287 2024-03-16 13:21:07.775 UTC [38951][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49288 2024-03-16 13:21:07.782 UTC [38951][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-16 13:21:07.782 UTC [38951][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-16 13:21:07.782 UTC [38951][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-16 13:21:07.782 UTC [38951][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49288 2024-03-16 13:21:07.793 UTC [38953][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49289 2024-03-16 13:21:07.801 UTC [38953][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-16 13:21:07.801 UTC [38953][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-16 13:21:07.802 UTC [38953][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-16 13:21:07.802 UTC [38953][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49289 2024-03-16 13:21:07.814 UTC [38955][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49290 2024-03-16 13:21:07.822 UTC [38955][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-16 13:21:07.822 UTC [38955][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-16 13:21:07.823 UTC [38955][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49290 2024-03-16 13:21:07.835 UTC [38964][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49291 2024-03-16 13:21:07.843 UTC [38964][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-16 13:21:07.843 UTC [38964][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-16 13:21:07.843 UTC [38964][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-16 13:21:07.844 UTC [38964][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49291 2024-03-16 13:21:07.856 UTC [38966][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49292 2024-03-16 13:21:07.864 UTC [38966][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-16 13:21:07.874 UTC [38968][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49293 2024-03-16 13:21:07.881 UTC [38968][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-16 13:21:07.890 UTC [38970][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49294 2024-03-16 13:21:07.899 UTC [38970][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-16 13:21:07.899 UTC [38970][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-16 13:21:07.900 UTC [38970][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-16 13:21:07.900 UTC [38970][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49294 2024-03-16 13:21:07.909 UTC [38972][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49295 2024-03-16 13:21:07.916 UTC [38972][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-16 13:21:07.916 UTC [38972][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-16 13:21:07.917 UTC [38972][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-16 13:21:07.917 UTC [38972][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49295 2024-03-16 13:21:07.926 UTC [38974][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49296 2024-03-16 13:21:07.934 UTC [38974][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-16 13:21:07.934 UTC [38974][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-16 13:21:07.934 UTC [38974][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-16 13:21:07.934 UTC [38974][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49296 2024-03-16 13:21:07.944 UTC [38978][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49298 2024-03-16 13:21:07.953 UTC [38978][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-16 13:21:07.953 UTC [38978][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-16 13:21:07.953 UTC [38978][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-16 13:21:07.953 UTC [38978][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49298 2024-03-16 13:21:07.962 UTC [38981][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49299 2024-03-16 13:21:07.973 UTC [38872][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:07.973 UTC [38872][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:07.974 UTC [38872][postmaster] LOG: background worker "logical replication launcher" (PID 38887) exited with exit code 1 2024-03-16 13:21:07.974 UTC [38877][checkpointer] LOG: shutting down 2024-03-16 13:21:07.974 UTC [38877][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:07.975 UTC [38877][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4102F90, redo lsn=0/4102F90 2024-03-16 13:21:07.976 UTC [38872][postmaster] LOG: database system is shut down 2024-03-16 13:21:08.098 UTC [38984][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:08.098 UTC [38984][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:08.098 UTC [38984][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:08.101 UTC [38993][startup] LOG: database system was shut down at 2024-03-16 13:21:07 UTC 2024-03-16 13:21:08.102 UTC [38984][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:08.203 UTC [39000][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49300 2024-03-16 13:21:08.213 UTC [39000][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-16 13:21:08.213 UTC [39000][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-16 13:21:08.215 UTC [39000][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-16 13:21:08.216 UTC [39000][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49300 2024-03-16 13:21:08.226 UTC [39003][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49302 2024-03-16 13:21:08.237 UTC [38984][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:08.237 UTC [38984][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:08.238 UTC [38984][postmaster] LOG: background worker "logical replication launcher" (PID 38996) exited with exit code 1 2024-03-16 13:21:08.238 UTC [38991][checkpointer] LOG: shutting down 2024-03-16 13:21:08.238 UTC [38991][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:08.238 UTC [38991][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/4103008, redo lsn=0/4103008 2024-03-16 13:21:08.239 UTC [38984][postmaster] LOG: database system is shut down 2024-03-16 13:21:08.364 UTC [39005][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:08.365 UTC [39005][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:08.365 UTC [39005][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:08.368 UTC [39008][startup] LOG: database system was shut down at 2024-03-16 13:21:08 UTC 2024-03-16 13:21:08.369 UTC [39005][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:08.478 UTC [39013][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49303 2024-03-16 13:21:08.487 UTC [39013][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-16 13:21:08.488 UTC [39013][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-16 13:21:08.490 UTC [39013][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=192.0.2.1$$ 2024-03-16 13:21:08.491 UTC [39013][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.013 user=ssltestuser database=trustdb host=localhost port=49303 2024-03-16 13:21:08.495 UTC [39005][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:08.495 UTC [39005][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:08.496 UTC [39005][postmaster] LOG: background worker "logical replication launcher" (PID 39011) exited with exit code 1 2024-03-16 13:21:08.496 UTC [39006][checkpointer] LOG: shutting down 2024-03-16 13:21:08.496 UTC [39006][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:08.496 UTC [39006][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4103080, redo lsn=0/4103080 2024-03-16 13:21:08.497 UTC [39005][postmaster] LOG: database system is shut down 2024-03-16 13:21:08.627 UTC [39015][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:08.627 UTC [39015][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:08.627 UTC [39015][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:08.630 UTC [39018][startup] LOG: database system was shut down at 2024-03-16 13:21:08 UTC 2024-03-16 13:21:08.632 UTC [39015][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:08.720 UTC [39023][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49304 2024-03-16 13:21:08.733 UTC [39023][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-16 13:21:08.733 UTC [39023][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-16 13:21:08.736 UTC [39023][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-16 13:21:08.736 UTC [39023][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=49304 2024-03-16 13:21:08.748 UTC [39025][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49305 2024-03-16 13:21:08.757 UTC [39025][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-16 13:21:08.757 UTC [39025][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-16 13:21:08.757 UTC [39025][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-16 13:21:08.757 UTC [39025][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49305 2024-03-16 13:21:08.767 UTC [39027][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49306 2024-03-16 13:21:08.774 UTC [39027][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-16 13:21:08.774 UTC [39027][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-16 13:21:08.775 UTC [39027][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-16 13:21:08.775 UTC [39027][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49306 2024-03-16 13:21:08.784 UTC [39029][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49307 2024-03-16 13:21:08.800 UTC [39031][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49308 2024-03-16 13:21:08.811 UTC [39015][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:08.811 UTC [39015][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:08.812 UTC [39015][postmaster] LOG: background worker "logical replication launcher" (PID 39021) exited with exit code 1 2024-03-16 13:21:08.812 UTC [39016][checkpointer] LOG: shutting down 2024-03-16 13:21:08.812 UTC [39016][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:08.812 UTC [39016][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/41030F8, redo lsn=0/41030F8 2024-03-16 13:21:08.813 UTC [39015][postmaster] LOG: database system is shut down 2024-03-16 13:21:08.930 UTC [39033][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:08.931 UTC [39033][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:08.931 UTC [39033][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:08.933 UTC [39036][startup] LOG: database system was shut down at 2024-03-16 13:21:08 UTC 2024-03-16 13:21:08.934 UTC [39033][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:09.051 UTC [39041][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49309 2024-03-16 13:21:09.061 UTC [39041][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-16 13:21:09.061 UTC [39041][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-16 13:21:09.064 UTC [39041][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-16 13:21:09.064 UTC [39041][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.014 user=ssltestuser database=trustdb host=localhost port=49309 2024-03-16 13:21:09.075 UTC [39043][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49310 2024-03-16 13:21:09.092 UTC [39045][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49311 2024-03-16 13:21:09.106 UTC [39033][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:09.106 UTC [39033][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:09.107 UTC [39033][postmaster] LOG: background worker "logical replication launcher" (PID 39039) exited with exit code 1 2024-03-16 13:21:09.107 UTC [39034][checkpointer] LOG: shutting down 2024-03-16 13:21:09.107 UTC [39034][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:09.107 UTC [39034][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/4103170, redo lsn=0/4103170 2024-03-16 13:21:09.108 UTC [39033][postmaster] LOG: database system is shut down 2024-03-16 13:21:09.232 UTC [39048][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:09.233 UTC [39048][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:09.233 UTC [39048][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:09.236 UTC [39051][startup] LOG: database system was shut down at 2024-03-16 13:21:09 UTC 2024-03-16 13:21:09.237 UTC [39048][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:09.352 UTC [39056][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49312 2024-03-16 13:21:09.365 UTC [39056][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-16 13:21:09.365 UTC [39056][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-16 13:21:09.369 UTC [39056][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-16 13:21:09.369 UTC [39056][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49312 2024-03-16 13:21:09.382 UTC [39058][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49313 2024-03-16 13:21:09.391 UTC [39058][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-16 13:21:09.391 UTC [39058][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-16 13:21:09.391 UTC [39058][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-16 13:21:09.391 UTC [39058][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49313 2024-03-16 13:21:09.401 UTC [39060][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49314 2024-03-16 13:21:09.417 UTC [39062][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49315 2024-03-16 13:21:09.425 UTC [39062][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-16 13:21:09.425 UTC [39062][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-16 13:21:09.425 UTC [39062][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-16 13:21:09.425 UTC [39062][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49315 2024-03-16 13:21:09.435 UTC [39064][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49316 2024-03-16 13:21:09.442 UTC [39064][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-16 13:21:09.442 UTC [39064][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-16 13:21:09.442 UTC [39064][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-16 13:21:09.442 UTC [39064][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49316 2024-03-16 13:21:09.451 UTC [39066][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49317 2024-03-16 13:21:09.459 UTC [39066][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-16 13:21:09.459 UTC [39066][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-16 13:21:09.459 UTC [39066][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-16 13:21:09.459 UTC [39066][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49317 2024-03-16 13:21:09.468 UTC [39068][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49318 2024-03-16 13:21:09.484 UTC [39070][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49319 2024-03-16 13:21:09.495 UTC [39048][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:09.495 UTC [39048][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:09.495 UTC [39048][postmaster] LOG: background worker "logical replication launcher" (PID 39054) exited with exit code 1 2024-03-16 13:21:09.495 UTC [39049][checkpointer] LOG: shutting down 2024-03-16 13:21:09.495 UTC [39049][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:09.496 UTC [39049][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/41031E8, redo lsn=0/41031E8 2024-03-16 13:21:09.497 UTC [39048][postmaster] LOG: database system is shut down 2024-03-16 13:21:09.622 UTC [39072][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:09.623 UTC [39072][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:09.623 UTC [39072][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:09.625 UTC [39075][startup] LOG: database system was shut down at 2024-03-16 13:21:09 UTC 2024-03-16 13:21:09.627 UTC [39072][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:09.732 UTC [39080][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49320 2024-03-16 13:21:09.745 UTC [39080][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-16 13:21:09.745 UTC [39080][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-16 13:21:09.748 UTC [39080][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-16 13:21:09.749 UTC [39080][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=49320 2024-03-16 13:21:09.761 UTC [39082][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49321 2024-03-16 13:21:09.769 UTC [39082][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-16 13:21:09.769 UTC [39082][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-16 13:21:09.770 UTC [39082][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-16 13:21:09.770 UTC [39082][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49321 2024-03-16 13:21:09.780 UTC [39084][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49322 2024-03-16 13:21:09.794 UTC [39072][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:09.794 UTC [39072][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:09.795 UTC [39072][postmaster] LOG: background worker "logical replication launcher" (PID 39078) exited with exit code 1 2024-03-16 13:21:09.795 UTC [39073][checkpointer] LOG: shutting down 2024-03-16 13:21:09.795 UTC [39073][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:09.796 UTC [39073][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/4103260, redo lsn=0/4103260 2024-03-16 13:21:09.797 UTC [39072][postmaster] LOG: database system is shut down 2024-03-16 13:21:09.937 UTC [39087][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:09.938 UTC [39087][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:09.938 UTC [39087][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:09.941 UTC [39090][startup] LOG: database system was shut down at 2024-03-16 13:21:09 UTC 2024-03-16 13:21:09.943 UTC [39087][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:10.051 UTC [39095][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49323 2024-03-16 13:21:10.065 UTC [39095][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-16 13:21:10.065 UTC [39095][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-16 13:21:10.068 UTC [39095][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-16 13:21:10.068 UTC [39095][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=49323 2024-03-16 13:21:10.081 UTC [39097][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49324 2024-03-16 13:21:10.090 UTC [39097][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-16 13:21:10.090 UTC [39097][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-16 13:21:10.091 UTC [39097][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-16 13:21:10.091 UTC [39097][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49324 2024-03-16 13:21:10.100 UTC [39099][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49325 2024-03-16 13:21:10.108 UTC [39099][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-16 13:21:10.108 UTC [39099][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-16 13:21:10.108 UTC [39099][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-16 13:21:10.108 UTC [39099][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49325 2024-03-16 13:21:10.112 UTC [39087][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:10.112 UTC [39087][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:10.113 UTC [39087][postmaster] LOG: background worker "logical replication launcher" (PID 39093) exited with exit code 1 2024-03-16 13:21:10.113 UTC [39088][checkpointer] LOG: shutting down 2024-03-16 13:21:10.113 UTC [39088][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:10.114 UTC [39088][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/41032D8, redo lsn=0/41032D8 2024-03-16 13:21:10.114 UTC [39087][postmaster] LOG: database system is shut down 2024-03-16 13:21:10.257 UTC [39101][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:10.258 UTC [39101][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:10.258 UTC [39101][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:10.262 UTC [39104][startup] LOG: database system was shut down at 2024-03-16 13:21:10 UTC 2024-03-16 13:21:10.264 UTC [39101][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:10.371 UTC [39109][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49326 2024-03-16 13:21:10.385 UTC [39109][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-16 13:21:10.385 UTC [39109][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-16 13:21:10.388 UTC [39109][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-16 13:21:10.388 UTC [39109][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49326 2024-03-16 13:21:10.401 UTC [39111][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49327 2024-03-16 13:21:10.410 UTC [39111][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-16 13:21:10.410 UTC [39111][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-16 13:21:10.410 UTC [39111][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-16 13:21:10.411 UTC [39111][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49327 2024-03-16 13:21:10.420 UTC [39113][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49328 2024-03-16 13:21:10.432 UTC [39101][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:10.432 UTC [39101][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:10.432 UTC [39101][postmaster] LOG: background worker "logical replication launcher" (PID 39107) exited with exit code 1 2024-03-16 13:21:10.432 UTC [39102][checkpointer] LOG: shutting down 2024-03-16 13:21:10.432 UTC [39102][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:10.433 UTC [39102][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4103350, redo lsn=0/4103350 2024-03-16 13:21:10.434 UTC [39101][postmaster] LOG: database system is shut down 2024-03-16 13:21:10.566 UTC [39115][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:10.566 UTC [39115][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:10.566 UTC [39115][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:10.570 UTC [39118][startup] LOG: database system was shut down at 2024-03-16 13:21:10 UTC 2024-03-16 13:21:10.571 UTC [39115][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:10.664 UTC [39123][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49329 2024-03-16 13:21:10.675 UTC [39123][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-16 13:21:10.675 UTC [39123][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-16 13:21:10.678 UTC [39123][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-16 13:21:10.678 UTC [39123][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=49329 2024-03-16 13:21:10.690 UTC [39125][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49330 2024-03-16 13:21:10.698 UTC [39125][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-16 13:21:10.698 UTC [39125][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-16 13:21:10.699 UTC [39125][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-16 13:21:10.699 UTC [39125][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49330 2024-03-16 13:21:10.708 UTC [39127][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49331 2024-03-16 13:21:10.716 UTC [39127][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-16 13:21:10.716 UTC [39127][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-16 13:21:10.716 UTC [39127][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-16 13:21:10.716 UTC [39127][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49331 2024-03-16 13:21:10.720 UTC [39115][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:10.720 UTC [39115][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:10.721 UTC [39115][postmaster] LOG: background worker "logical replication launcher" (PID 39121) exited with exit code 1 2024-03-16 13:21:10.721 UTC [39116][checkpointer] LOG: shutting down 2024-03-16 13:21:10.721 UTC [39116][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:10.721 UTC [39116][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/41033C8, redo lsn=0/41033C8 2024-03-16 13:21:10.722 UTC [39115][postmaster] LOG: database system is shut down 2024-03-16 13:21:10.848 UTC [39129][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:10.848 UTC [39129][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:10.848 UTC [39129][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:10.851 UTC [39132][startup] LOG: database system was shut down at 2024-03-16 13:21:10 UTC 2024-03-16 13:21:10.855 UTC [39129][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:10.963 UTC [39137][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49332 2024-03-16 13:21:10.975 UTC [39137][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-16 13:21:10.975 UTC [39137][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-16 13:21:10.978 UTC [39137][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-16 13:21:10.978 UTC [39137][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=49332 2024-03-16 13:21:10.990 UTC [39139][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49333 2024-03-16 13:21:11.003 UTC [39129][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:11.003 UTC [39129][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:11.004 UTC [39129][postmaster] LOG: background worker "logical replication launcher" (PID 39135) exited with exit code 1 2024-03-16 13:21:11.004 UTC [39130][checkpointer] LOG: shutting down 2024-03-16 13:21:11.004 UTC [39130][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:11.004 UTC [39130][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/4103440, redo lsn=0/4103440 2024-03-16 13:21:11.005 UTC [39129][postmaster] LOG: database system is shut down 2024-03-16 13:21:11.138 UTC [39141][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:11.139 UTC [39141][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:11.139 UTC [39141][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:11.142 UTC [39144][startup] LOG: database system was shut down at 2024-03-16 13:21:11 UTC 2024-03-16 13:21:11.143 UTC [39141][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:11.257 UTC [39149][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49334 2024-03-16 13:21:11.271 UTC [39149][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-16 13:21:11.294 UTC [39152][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49335 2024-03-16 13:21:11.303 UTC [39152][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-16 13:21:11.303 UTC [39152][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-16 13:21:11.306 UTC [39152][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-16 13:21:11.306 UTC [39152][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49335 2024-03-16 13:21:11.316 UTC [39154][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49336 2024-03-16 13:21:11.327 UTC [39141][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:11.327 UTC [39141][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:11.328 UTC [39141][postmaster] LOG: background worker "logical replication launcher" (PID 39147) exited with exit code 1 2024-03-16 13:21:11.328 UTC [39142][checkpointer] LOG: shutting down 2024-03-16 13:21:11.328 UTC [39142][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:11.328 UTC [39142][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/41034B8, redo lsn=0/41034B8 2024-03-16 13:21:11.329 UTC [39141][postmaster] LOG: database system is shut down 2024-03-16 13:21:11.469 UTC [39156][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:11.470 UTC [39156][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:11.470 UTC [39156][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:11.473 UTC [39159][startup] LOG: database system was shut down at 2024-03-16 13:21:11 UTC 2024-03-16 13:21:11.475 UTC [39156][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:11.580 UTC [39164][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49337 2024-03-16 13:21:11.592 UTC [39164][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-16 13:21:11.592 UTC [39164][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-16 13:21:11.596 UTC [39164][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-16 13:21:11.596 UTC [39164][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=49337 2024-03-16 13:21:11.608 UTC [39166][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49338 2024-03-16 13:21:11.617 UTC [39166][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: ssl/tls alert certificate revoked 2024-03-16 13:21:11.626 UTC [39168][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49339 2024-03-16 13:21:11.634 UTC [39168][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: ssl/tls alert certificate revoked 2024-03-16 13:21:11.643 UTC [39170][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49340 2024-03-16 13:21:11.650 UTC [39170][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-16 13:21:11.650 UTC [39170][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-16 13:21:11.650 UTC [39170][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-16 13:21:11.651 UTC [39170][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49340 2024-03-16 13:21:11.660 UTC [39172][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49341 2024-03-16 13:21:11.667 UTC [39172][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-16 13:21:11.667 UTC [39172][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-16 13:21:11.667 UTC [39172][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-16 13:21:11.668 UTC [39172][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49341 2024-03-16 13:21:11.699 UTC [39177][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49342 2024-03-16 13:21:11.706 UTC [39177][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-03-16 13:21:11.715 UTC [39179][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49343 2024-03-16 13:21:11.724 UTC [39179][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-16 13:21:11.724 UTC [39179][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-16 13:21:11.726 UTC [39179][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_CVZo/client.key$$ 2024-03-16 13:21:11.727 UTC [39179][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=49343 2024-03-16 13:21:11.736 UTC [39181][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49344 2024-03-16 13:21:11.744 UTC [39181][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-16 13:21:11.744 UTC [39181][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-16 13:21:11.745 UTC [39181][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_CVZo/client-der.key$$ 2024-03-16 13:21:11.745 UTC [39181][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49344 2024-03-16 13:21:11.754 UTC [39183][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49345 2024-03-16 13:21:11.763 UTC [39183][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-16 13:21:11.763 UTC [39183][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-16 13:21:11.763 UTC [39183][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_CVZo/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-03-16 13:21:11.763 UTC [39183][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49345 2024-03-16 13:21:11.772 UTC [39185][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49346 2024-03-16 13:21:11.781 UTC [39185][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-16 13:21:11.781 UTC [39185][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-16 13:21:11.781 UTC [39185][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_CVZo/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-03-16 13:21:11.781 UTC [39185][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49346 2024-03-16 13:21:11.790 UTC [39187][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49347 2024-03-16 13:21:11.798 UTC [39187][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-16 13:21:11.799 UTC [39187][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-16 13:21:11.799 UTC [39187][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_CVZo/client.key$$ 2024-03-16 13:21:11.799 UTC [39187][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49347 2024-03-16 13:21:11.808 UTC [39189][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49348 2024-03-16 13:21:11.816 UTC [39189][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-16 13:21:11.816 UTC [39189][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-16 13:21:11.817 UTC [39189][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_CVZo/client.key$$ 2024-03-16 13:21:11.817 UTC [39189][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49348 2024-03-16 13:21:11.826 UTC [39191][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49349 2024-03-16 13:21:11.833 UTC [39191][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-03-16 13:21:11.842 UTC [39193][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49350 2024-03-16 13:21:11.848 UTC [39193][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-16 13:21:11.857 UTC [39195][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49351 2024-03-16 13:21:11.867 UTC [39195][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-16 13:21:11.867 UTC [39195][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-16 13:21:11.869 UTC [39195][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_CVZo/client-dn.key$$ 2024-03-16 13:21:11.869 UTC [39195][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=certdb_dn host=localhost port=49351 2024-03-16 13:21:11.878 UTC [39197][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49352 2024-03-16 13:21:11.887 UTC [39197][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-16 13:21:11.887 UTC [39197][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-16 13:21:11.889 UTC [39197][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_CVZo/client-dn.key$$ 2024-03-16 13:21:11.889 UTC [39197][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_dn_re host=localhost port=49352 2024-03-16 13:21:11.898 UTC [39199][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49353 2024-03-16 13:21:11.907 UTC [39199][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-16 13:21:11.907 UTC [39199][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-16 13:21:11.909 UTC [39199][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_CVZo/client-dn.key$$ 2024-03-16 13:21:11.909 UTC [39199][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb_cn host=localhost port=49353 2024-03-16 13:21:11.928 UTC [39202][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49354 2024-03-16 13:21:11.937 UTC [39202][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-16 13:21:11.937 UTC [39202][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-16 13:21:11.937 UTC [39202][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-16 13:21:11.938 UTC [39202][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49354 2024-03-16 13:21:11.947 UTC [39204][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49355 2024-03-16 13:21:11.952 UTC [39204][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-16 13:21:11.961 UTC [39206][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49356 2024-03-16 13:21:11.969 UTC [39206][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-16 13:21:11.969 UTC [39206][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-16 13:21:11.969 UTC [39206][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-03-16 13:21:11.969 UTC [39206][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-16 13:21:11.978 UTC [39208][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49357 2024-03-16 13:21:11.987 UTC [39208][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-16 13:21:11.987 UTC [39208][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-16 13:21:11.996 UTC [39210][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49358 2024-03-16 13:21:12.005 UTC [39210][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-16 13:21:12.005 UTC [39210][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-16 13:21:12.007 UTC [39210][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_CVZo/client.key$$ 2024-03-16 13:21:12.007 UTC [39210][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=verifydb host=localhost port=49358 2024-03-16 13:21:12.016 UTC [39212][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49359 2024-03-16 13:21:12.025 UTC [39212][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-16 13:21:12.025 UTC [39212][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-03-16 13:21:12.025 UTC [39212][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-03-16 13:21:12.025 UTC [39212][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-16 13:21:12.034 UTC [39214][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49360 2024-03-16 13:21:12.042 UTC [39214][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-16 13:21:12.042 UTC [39214][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-16 13:21:12.043 UTC [39214][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_CVZo/client.key$$ 2024-03-16 13:21:12.043 UTC [39214][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=yetanotheruser database=verifydb host=localhost port=49360 2024-03-16 13:21:12.047 UTC [39156][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:12.047 UTC [39156][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:12.047 UTC [39156][postmaster] LOG: background worker "logical replication launcher" (PID 39162) exited with exit code 1 2024-03-16 13:21:12.047 UTC [39157][checkpointer] LOG: shutting down 2024-03-16 13:21:12.047 UTC [39157][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:12.048 UTC [39157][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/4103530, redo lsn=0/4103530 2024-03-16 13:21:12.049 UTC [39156][postmaster] LOG: database system is shut down 2024-03-16 13:21:12.186 UTC [39216][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:12.187 UTC [39216][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:12.187 UTC [39216][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:12.190 UTC [39219][startup] LOG: database system was shut down at 2024-03-16 13:21:12 UTC 2024-03-16 13:21:12.192 UTC [39216][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:12.304 UTC [39224][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49361 2024-03-16 13:21:12.322 UTC [39224][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-16 13:21:12.322 UTC [39224][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-16 13:21:12.326 UTC [39224][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_CVZo/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-16 13:21:12.326 UTC [39224][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.022 user=ssltestuser database=certdb host=localhost port=49361 2024-03-16 13:21:12.338 UTC [39226][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49362 2024-03-16 13:21:12.348 UTC [39226][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-16 13:21:12.348 UTC [39226][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-16 13:21:12.358 UTC [39228][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49363 2024-03-16 13:21:12.367 UTC [39228][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-16 13:21:12.367 UTC [39228][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-16 13:21:12.371 UTC [39216][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:12.371 UTC [39216][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:12.371 UTC [39216][postmaster] LOG: background worker "logical replication launcher" (PID 39222) exited with exit code 1 2024-03-16 13:21:12.372 UTC [39217][checkpointer] LOG: shutting down 2024-03-16 13:21:12.372 UTC [39217][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:12.372 UTC [39217][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/41035A8, redo lsn=0/41035A8 2024-03-16 13:21:12.373 UTC [39216][postmaster] LOG: database system is shut down 2024-03-16 13:21:12.511 UTC [39230][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:12.511 UTC [39230][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:12.512 UTC [39230][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:12.515 UTC [39233][startup] LOG: database system was shut down at 2024-03-16 13:21:12 UTC 2024-03-16 13:21:12.517 UTC [39230][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:12.621 UTC [39238][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49364 2024-03-16 13:21:12.637 UTC [39238][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-16 13:21:12.637 UTC [39238][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-16 13:21:12.643 UTC [39230][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:12.643 UTC [39230][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:12.643 UTC [39230][postmaster] LOG: background worker "logical replication launcher" (PID 39236) exited with exit code 1 2024-03-16 13:21:12.643 UTC [39231][checkpointer] LOG: shutting down 2024-03-16 13:21:12.643 UTC [39231][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:12.644 UTC [39231][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4103620, redo lsn=0/4103620 2024-03-16 13:21:12.646 UTC [39230][postmaster] LOG: database system is shut down 2024-03-16 13:21:12.798 UTC [39240][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:12.798 UTC [39240][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:12.799 UTC [39240][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:12.803 UTC [39243][startup] LOG: database system was shut down at 2024-03-16 13:21:12 UTC 2024-03-16 13:21:12.806 UTC [39240][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:12.893 UTC [39248][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49365 2024-03-16 13:21:12.908 UTC [39248][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-16 13:21:12.908 UTC [39248][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-16 13:21:12.921 UTC [39250][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49366 2024-03-16 13:21:12.932 UTC [39250][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-16 13:21:12.932 UTC [39250][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-16 13:21:12.937 UTC [39240][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:12.937 UTC [39240][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:12.938 UTC [39240][postmaster] LOG: background worker "logical replication launcher" (PID 39246) exited with exit code 1 2024-03-16 13:21:12.938 UTC [39241][checkpointer] LOG: shutting down 2024-03-16 13:21:12.938 UTC [39241][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:12.939 UTC [39241][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/4103698, redo lsn=0/4103698 2024-03-16 13:21:12.940 UTC [39240][postmaster] LOG: database system is shut down 2024-03-16 13:21:13.083 UTC [39252][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:13.084 UTC [39252][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:13.084 UTC [39252][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:13.087 UTC [39255][startup] LOG: database system was shut down at 2024-03-16 13:21:12 UTC 2024-03-16 13:21:13.089 UTC [39252][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:13.180 UTC [39261][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49367 2024-03-16 13:21:13.188 UTC [39261][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-16 13:21:13.188 UTC [39261][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-16 13:21:13.200 UTC [39263][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49368 2024-03-16 13:21:13.208 UTC [39263][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-16 13:21:13.208 UTC [39263][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-16 13:21:13.211 UTC [39263][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-16 13:21:13.211 UTC [39263][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49368 2024-03-16 13:21:13.215 UTC [39252][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:13.215 UTC [39252][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:13.216 UTC [39252][postmaster] LOG: background worker "logical replication launcher" (PID 39258) exited with exit code 1 2024-03-16 13:21:13.216 UTC [39253][checkpointer] LOG: shutting down 2024-03-16 13:21:13.216 UTC [39253][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:13.217 UTC [39253][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/4103710, redo lsn=0/4103710 2024-03-16 13:21:13.218 UTC [39252][postmaster] LOG: database system is shut down 2024-03-16 13:21:13.352 UTC [39265][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:13.352 UTC [39265][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:13.353 UTC [39265][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:13.355 UTC [39268][startup] LOG: database system was shut down at 2024-03-16 13:21:13 UTC 2024-03-16 13:21:13.357 UTC [39265][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:13.463 UTC [39273][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49369 2024-03-16 13:21:13.474 UTC [39273][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-16 13:21:13.474 UTC [39273][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-16 13:21:13.487 UTC [39275][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49370 2024-03-16 13:21:13.496 UTC [39275][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-16 13:21:13.496 UTC [39275][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-16 13:21:13.498 UTC [39275][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-16 13:21:13.499 UTC [39275][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49370 2024-03-16 13:21:13.503 UTC [39265][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:13.503 UTC [39265][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:13.504 UTC [39265][postmaster] LOG: background worker "logical replication launcher" (PID 39271) exited with exit code 1 2024-03-16 13:21:13.504 UTC [39266][checkpointer] LOG: shutting down 2024-03-16 13:21:13.504 UTC [39266][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:13.505 UTC [39266][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/4103788, redo lsn=0/4103788 2024-03-16 13:21:13.506 UTC [39265][postmaster] LOG: database system is shut down 2024-03-16 13:21:13.634 UTC [39277][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:13.634 UTC [39277][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:13.634 UTC [39277][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:13.637 UTC [39281][startup] LOG: database system was shut down at 2024-03-16 13:21:13 UTC 2024-03-16 13:21:13.639 UTC [39277][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:13.729 UTC [39286][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49371 2024-03-16 13:21:13.738 UTC [39286][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-16 13:21:13.738 UTC [39286][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-16 13:21:13.752 UTC [39288][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49372 2024-03-16 13:21:13.761 UTC [39288][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-16 13:21:13.761 UTC [39288][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-16 13:21:13.763 UTC [39288][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-16 13:21:13.764 UTC [39288][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49372 2024-03-16 13:21:13.768 UTC [39277][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:13.768 UTC [39277][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:13.769 UTC [39277][postmaster] LOG: background worker "logical replication launcher" (PID 39284) exited with exit code 1 2024-03-16 13:21:13.769 UTC [39279][checkpointer] LOG: shutting down 2024-03-16 13:21:13.769 UTC [39279][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:13.770 UTC [39279][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/4103800, redo lsn=0/4103800 2024-03-16 13:21:13.771 UTC [39277][postmaster] LOG: database system is shut down 2024-03-16 13:21:13.895 UTC [39290][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:13.896 UTC [39290][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:13.896 UTC [39290][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:13.898 UTC [39293][startup] LOG: database system was shut down at 2024-03-16 13:21:13 UTC 2024-03-16 13:21:13.900 UTC [39290][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:14.004 UTC [39298][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49373 2024-03-16 13:21:14.012 UTC [39298][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-16 13:21:14.012 UTC [39298][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-16 13:21:14.024 UTC [39301][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49374 2024-03-16 13:21:14.032 UTC [39301][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-16 13:21:14.032 UTC [39301][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-16 13:21:14.034 UTC [39301][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-16 13:21:14.035 UTC [39301][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49374 2024-03-16 13:21:14.039 UTC [39290][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:14.039 UTC [39290][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:14.040 UTC [39290][postmaster] LOG: background worker "logical replication launcher" (PID 39296) exited with exit code 1 2024-03-16 13:21:14.040 UTC [39291][checkpointer] LOG: shutting down 2024-03-16 13:21:14.040 UTC [39291][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:14.040 UTC [39291][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4103878, redo lsn=0/4103878 2024-03-16 13:21:14.041 UTC [39290][postmaster] LOG: database system is shut down 2024-03-16 13:21:14.158 UTC [39303][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:14.159 UTC [39303][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:14.159 UTC [39303][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:14.161 UTC [39306][startup] LOG: database system was shut down at 2024-03-16 13:21:14 UTC 2024-03-16 13:21:14.162 UTC [39303][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:14.257 UTC [39311][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49375 2024-03-16 13:21:14.263 UTC [39311][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-16 13:21:14.263 UTC [39311][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-16 13:21:14.273 UTC [39313][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49376 2024-03-16 13:21:14.281 UTC [39313][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-16 13:21:14.281 UTC [39313][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-16 13:21:14.283 UTC [39313][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-16 13:21:14.284 UTC [39313][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49376 2024-03-16 13:21:14.288 UTC [39303][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:14.288 UTC [39303][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:14.289 UTC [39303][postmaster] LOG: background worker "logical replication launcher" (PID 39309) exited with exit code 1 2024-03-16 13:21:14.289 UTC [39304][checkpointer] LOG: shutting down 2024-03-16 13:21:14.289 UTC [39304][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:14.289 UTC [39304][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/41038F0, redo lsn=0/41038F0 2024-03-16 13:21:14.290 UTC [39303][postmaster] LOG: database system is shut down 2024-03-16 13:21:14.411 UTC [39316][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:14.411 UTC [39316][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:14.411 UTC [39316][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:14.413 UTC [39319][startup] LOG: database system was shut down at 2024-03-16 13:21:14 UTC 2024-03-16 13:21:14.415 UTC [39316][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:14.515 UTC [39324][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49377 2024-03-16 13:21:14.520 UTC [39324][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-16 13:21:14.520 UTC [39324][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-16 13:21:14.532 UTC [39326][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49378 2024-03-16 13:21:14.539 UTC [39326][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-16 13:21:14.539 UTC [39326][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-16 13:21:14.542 UTC [39326][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-16 13:21:14.542 UTC [39326][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49378 2024-03-16 13:21:14.546 UTC [39316][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:14.546 UTC [39316][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:14.547 UTC [39316][postmaster] LOG: background worker "logical replication launcher" (PID 39322) exited with exit code 1 2024-03-16 13:21:14.547 UTC [39317][checkpointer] LOG: shutting down 2024-03-16 13:21:14.547 UTC [39317][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:14.548 UTC [39317][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4103968, redo lsn=0/4103968 2024-03-16 13:21:14.549 UTC [39316][postmaster] LOG: database system is shut down 2024-03-16 13:21:14.671 UTC [39328][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:14.671 UTC [39328][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:14.671 UTC [39328][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:14.673 UTC [39331][startup] LOG: database system was shut down at 2024-03-16 13:21:14 UTC 2024-03-16 13:21:14.675 UTC [39328][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:14.770 UTC [39336][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49379 2024-03-16 13:21:14.776 UTC [39336][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-16 13:21:14.776 UTC [39336][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-16 13:21:14.785 UTC [39338][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49380 2024-03-16 13:21:14.792 UTC [39338][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-16 13:21:14.792 UTC [39338][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-16 13:21:14.795 UTC [39338][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-16 13:21:14.795 UTC [39338][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=trustdb host=localhost port=49380 2024-03-16 13:21:14.799 UTC [39328][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:14.799 UTC [39328][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:14.800 UTC [39328][postmaster] LOG: background worker "logical replication launcher" (PID 39334) exited with exit code 1 2024-03-16 13:21:14.800 UTC [39329][checkpointer] LOG: shutting down 2024-03-16 13:21:14.800 UTC [39329][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:14.801 UTC [39329][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/41039E0, redo lsn=0/41039E0 2024-03-16 13:21:14.801 UTC [39328][postmaster] LOG: database system is shut down 2024-03-16 13:21:14.932 UTC [39340][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:14.932 UTC [39340][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:14.932 UTC [39340][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:14.935 UTC [39344][startup] LOG: database system was shut down at 2024-03-16 13:21:14 UTC 2024-03-16 13:21:14.936 UTC [39340][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:15.035 UTC [39349][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49381 2024-03-16 13:21:15.041 UTC [39349][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-16 13:21:15.041 UTC [39349][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-16 13:21:15.052 UTC [39351][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49382 2024-03-16 13:21:15.061 UTC [39351][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-16 13:21:15.061 UTC [39351][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-16 13:21:15.063 UTC [39351][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-16 13:21:15.064 UTC [39351][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49382 2024-03-16 13:21:15.069 UTC [39340][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:15.069 UTC [39340][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:15.069 UTC [39340][postmaster] LOG: background worker "logical replication launcher" (PID 39347) exited with exit code 1 2024-03-16 13:21:15.069 UTC [39342][checkpointer] LOG: shutting down 2024-03-16 13:21:15.070 UTC [39342][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:15.070 UTC [39342][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/4103A58, redo lsn=0/4103A58 2024-03-16 13:21:15.071 UTC [39340][postmaster] LOG: database system is shut down 2024-03-16 13:21:15.207 UTC [39353][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-16 13:21:15.207 UTC [39353][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 59354 2024-03-16 13:21:15.207 UTC [39353][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/6LOZtdW2Tj/.s.PGSQL.59354" 2024-03-16 13:21:15.210 UTC [39357][startup] LOG: database system was shut down at 2024-03-16 13:21:15 UTC 2024-03-16 13:21:15.211 UTC [39353][postmaster] LOG: database system is ready to accept connections 2024-03-16 13:21:15.311 UTC [39362][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49383 2024-03-16 13:21:15.318 UTC [39362][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-16 13:21:15.318 UTC [39362][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-16 13:21:15.329 UTC [39364][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49384 2024-03-16 13:21:15.337 UTC [39364][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-16 13:21:15.337 UTC [39364][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-16 13:21:15.340 UTC [39364][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-16 13:21:15.340 UTC [39364][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49384 2024-03-16 13:21:15.345 UTC [39353][postmaster] LOG: received fast shutdown request 2024-03-16 13:21:15.345 UTC [39353][postmaster] LOG: aborting any active transactions 2024-03-16 13:21:15.346 UTC [39353][postmaster] LOG: background worker "logical replication launcher" (PID 39360) exited with exit code 1 2024-03-16 13:21:15.346 UTC [39355][checkpointer] LOG: shutting down 2024-03-16 13:21:15.347 UTC [39355][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-16 13:21:15.347 UTC [39355][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/4103AD0, redo lsn=0/4103AD0 2024-03-16 13:21:15.349 UTC [39353][postmaster] LOG: database system is shut down 2024-03-16 13:21:15.460 UTC [39367][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-03-16 13:21:15.461 UTC [39367][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [13:21:15.557](0.216s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"