[04:48:31.453](0.037s) # setting up data directory # Checking port 63713 # Found port 63713 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=63713 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b Log file: /Users/admin/pgsql/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log [04:48:31.468](0.016s) # 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 38098 [04:48:32.389](0.921s) 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 38781 [04:48:38.704](6.315s) # 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-08 04:48:32.013 UTC [38098][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:32.013 UTC [38098][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:32.276 UTC [38148][startup] LOG: database system was shut down at 2024-03-08 04:43:55 UTC 2024-03-08 04:48:32.282 UTC [38098][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:32.385 UTC [38194][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:32.385 UTC [38194][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-08 04:48:32.385 UTC [38194][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:32.388 UTC [38194][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-08 04:48:32.388 UTC [38194][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-03-08 04:48:32.399 UTC [38199][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:32.400 UTC [38199][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-08 04:48:32.400 UTC [38199][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:32.400 UTC [38199][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-08 04:48:32.401 UTC [38199][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-08 04:48:32.444 UTC [38214][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:32.444 UTC [38214][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-08 04:48:32.444 UTC [38214][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:32.444 UTC [38214][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-08 04:48:32.445 UTC [38214][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.000 user=admin database=postgres host=[local] 2024-03-08 04:48:32.495 UTC [38228][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:32.495 UTC [38228][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-08 04:48:32.495 UTC [38228][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:32.496 UTC [38228][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-08 04:48:32.496 UTC [38228][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-08 04:48:32.545 UTC [38246][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:32.545 UTC [38246][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-08 04:48:32.545 UTC [38246][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:32.546 UTC [38246][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-08 04:48:32.546 UTC [38246][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-08 04:48:32.598 UTC [38261][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:32.599 UTC [38261][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-08 04:48:32.599 UTC [38261][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:32.599 UTC [38261][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-08 04:48:32.638 UTC [38261][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.040 user=admin database=postgres host=[local] 2024-03-08 04:48:32.723 UTC [38286][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:32.723 UTC [38286][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-08 04:48:32.723 UTC [38286][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:32.724 UTC [38286][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-08 04:48:33.436 UTC [38286][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.713 user=admin database=postgres host=[local] 2024-03-08 04:48:33.879 UTC [38326][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:33.880 UTC [38326][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-08 04:48:33.880 UTC [38326][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:33.880 UTC [38326][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-08 04:48:33.919 UTC [38326][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.039 user=admin database=postgres host=[local] 2024-03-08 04:48:34.024 UTC [38335][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:34.024 UTC [38335][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-08 04:48:34.024 UTC [38335][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:34.024 UTC [38335][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-08 04:48:34.469 UTC [38335][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.445 user=admin database=postgres host=[local] 2024-03-08 04:48:34.663 UTC [38349][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:34.664 UTC [38349][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-08 04:48:34.664 UTC [38349][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:34.664 UTC [38349][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-08 04:48:34.750 UTC [38349][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.086 user=admin database=postgres host=[local] 2024-03-08 04:48:34.801 UTC [38359][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:34.801 UTC [38359][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-08 04:48:34.801 UTC [38359][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:34.802 UTC [38359][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-08 04:48:35.195 UTC [38359][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.394 user=admin database=postgres host=[local] 2024-03-08 04:48:35.852 UTC [38098][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:35.852 UTC [38098][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:35.854 UTC [38098][postmaster] LOG: background worker "logical replication launcher" (PID 38159) exited with exit code 1 2024-03-08 04:48:35.854 UTC [38146][checkpointer] LOG: shutting down 2024-03-08 04:48:35.854 UTC [38146][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:38.489 UTC [38146][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=2.635 s, sync=0.001 s, total=2.636 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/41049C8, redo lsn=0/41049C8 2024-03-08 04:48:38.498 UTC [38098][postmaster] LOG: database system is shut down 2024-03-08 04:48:38.582 UTC [38781][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:38.583 UTC [38781][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:38.585 UTC [38781][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:38.589 UTC [38785][startup] LOG: database system was shut down at 2024-03-08 04:48:38 UTC 2024-03-08 04:48:38.590 UTC [38781][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:38.708 UTC [38781][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:38.708 UTC [38781][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:38.709 UTC [38781][postmaster] LOG: background worker "logical replication launcher" (PID 38788) exited with exit code 1 2024-03-08 04:48:38.709 UTC [38783][checkpointer] LOG: shutting down 2024-03-08 04:48:38.709 UTC [38783][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:38.725 UTC [38783][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.017 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4104A40, redo lsn=0/4104A40 2024-03-08 04:48:38.884 UTC [38781][postmaster] LOG: database system is shut down 2024-03-08 04:48:38.947 UTC [38811][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-08 04:48:38.947 UTC [38811][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [04:48:39.041](0.337s) 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 38822 [04:48:39.148](0.107s) 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-08 04:48:32.013 UTC [38098][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:32.013 UTC [38098][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:32.276 UTC [38148][startup] LOG: database system was shut down at 2024-03-08 04:43:55 UTC 2024-03-08 04:48:32.282 UTC [38098][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:32.385 UTC [38194][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:32.385 UTC [38194][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-08 04:48:32.385 UTC [38194][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:32.388 UTC [38194][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-08 04:48:32.388 UTC [38194][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-03-08 04:48:32.399 UTC [38199][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:32.400 UTC [38199][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-08 04:48:32.400 UTC [38199][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:32.400 UTC [38199][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-08 04:48:32.401 UTC [38199][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-08 04:48:32.444 UTC [38214][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:32.444 UTC [38214][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-08 04:48:32.444 UTC [38214][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:32.444 UTC [38214][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-08 04:48:32.445 UTC [38214][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.000 user=admin database=postgres host=[local] 2024-03-08 04:48:32.495 UTC [38228][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:32.495 UTC [38228][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-08 04:48:32.495 UTC [38228][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:32.496 UTC [38228][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-08 04:48:32.496 UTC [38228][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-08 04:48:32.545 UTC [38246][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:32.545 UTC [38246][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-08 04:48:32.545 UTC [38246][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:32.546 UTC [38246][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-08 04:48:32.546 UTC [38246][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-08 04:48:32.598 UTC [38261][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:32.599 UTC [38261][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-08 04:48:32.599 UTC [38261][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:32.599 UTC [38261][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-08 04:48:32.638 UTC [38261][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.040 user=admin database=postgres host=[local] 2024-03-08 04:48:32.723 UTC [38286][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:32.723 UTC [38286][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-08 04:48:32.723 UTC [38286][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:32.724 UTC [38286][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-08 04:48:33.436 UTC [38286][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.713 user=admin database=postgres host=[local] 2024-03-08 04:48:33.879 UTC [38326][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:33.880 UTC [38326][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-08 04:48:33.880 UTC [38326][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:33.880 UTC [38326][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-08 04:48:33.919 UTC [38326][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.039 user=admin database=postgres host=[local] 2024-03-08 04:48:34.024 UTC [38335][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:34.024 UTC [38335][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-08 04:48:34.024 UTC [38335][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:34.024 UTC [38335][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-08 04:48:34.469 UTC [38335][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.445 user=admin database=postgres host=[local] 2024-03-08 04:48:34.663 UTC [38349][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:34.664 UTC [38349][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-08 04:48:34.664 UTC [38349][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:34.664 UTC [38349][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-08 04:48:34.750 UTC [38349][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.086 user=admin database=postgres host=[local] 2024-03-08 04:48:34.801 UTC [38359][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:34.801 UTC [38359][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-08 04:48:34.801 UTC [38359][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:34.802 UTC [38359][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-08 04:48:35.195 UTC [38359][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.394 user=admin database=postgres host=[local] 2024-03-08 04:48:35.852 UTC [38098][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:35.852 UTC [38098][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:35.854 UTC [38098][postmaster] LOG: background worker "logical replication launcher" (PID 38159) exited with exit code 1 2024-03-08 04:48:35.854 UTC [38146][checkpointer] LOG: shutting down 2024-03-08 04:48:35.854 UTC [38146][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:38.489 UTC [38146][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=2.635 s, sync=0.001 s, total=2.636 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/41049C8, redo lsn=0/41049C8 2024-03-08 04:48:38.498 UTC [38098][postmaster] LOG: database system is shut down 2024-03-08 04:48:38.582 UTC [38781][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:38.583 UTC [38781][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:38.585 UTC [38781][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:38.589 UTC [38785][startup] LOG: database system was shut down at 2024-03-08 04:48:38 UTC 2024-03-08 04:48:38.590 UTC [38781][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:38.708 UTC [38781][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:38.708 UTC [38781][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:38.709 UTC [38781][postmaster] LOG: background worker "logical replication launcher" (PID 38788) exited with exit code 1 2024-03-08 04:48:38.709 UTC [38783][checkpointer] LOG: shutting down 2024-03-08 04:48:38.709 UTC [38783][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:38.725 UTC [38783][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.017 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4104A40, redo lsn=0/4104A40 2024-03-08 04:48:38.884 UTC [38781][postmaster] LOG: database system is shut down 2024-03-08 04:48:38.947 UTC [38811][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-08 04:48:38.947 UTC [38811][postmaster] LOG: database system is shut down 2024-03-08 04:48:39.063 UTC [38822][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:39.064 UTC [38822][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:39.064 UTC [38822][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:39.066 UTC [38830][startup] LOG: database system was shut down at 2024-03-08 04:48:38 UTC 2024-03-08 04:48:39.067 UTC [38822][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:39.151 UTC [38822][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:39.151 UTC [38822][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:39.152 UTC [38822][postmaster] LOG: background worker "logical replication launcher" (PID 38833) exited with exit code 1 2024-03-08 04:48:39.153 UTC [38828][checkpointer] LOG: shutting down 2024-03-08 04:48:39.153 UTC [38828][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:39.153 UTC [38828][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/4104AB8, redo lsn=0/4104AB8 2024-03-08 04:48:39.154 UTC [38822][postmaster] LOG: database system is shut down 2024-03-08 04:48:39.277 UTC [38877][postmaster] FATAL: could not set SSL protocol version range 2024-03-08 04:48:39.277 UTC [38877][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-08 04:48:39.277 UTC [38877][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [04:48:39.373](0.225s) ok 4 - restart fails with incorrect SSL protocol bounds ### Restarting node "primary" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata -l /Users/admin/pgsql/build/testrun/ssl/001_ssltests/log/001_ssltests_primary.log restart pg_ctl: PID file "/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/postmaster.pid" does not exist Is server running? trying to start server anyway waiting for server to start.... done server started # Postmaster PID for node "primary" is 38900 [04:48:39.481](0.108s) ok 5 - restart succeeds with correct SSL protocol bounds [04:48:39.481](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 38924 [04:48:39.715](0.234s) ok 6 - server doesn't accept non-SSL connections [04:48:39.716](0.000s) ok 7 - server doesn't accept non-SSL connections: matches [04:48:39.736](0.021s) ok 8 - connect without server root cert sslmode=require [04:48:39.737](0.000s) ok 9 - connect without server root cert sslmode=require: no stderr [04:48:39.751](0.015s) ok 10 - connect without server root cert sslmode=verify-ca [04:48:39.751](0.000s) ok 11 - connect without server root cert sslmode=verify-ca: matches [04:48:39.765](0.014s) ok 12 - connect without server root cert sslmode=verify-full [04:48:39.765](0.000s) ok 13 - connect without server root cert sslmode=verify-full: matches [04:48:39.783](0.018s) ok 14 - connect with wrong server root cert sslmode=require [04:48:39.783](0.000s) ok 15 - connect with wrong server root cert sslmode=require: matches [04:48:39.798](0.015s) ok 16 - connect with wrong server root cert sslmode=verify-ca [04:48:39.798](0.000s) ok 17 - connect with wrong server root cert sslmode=verify-ca: matches [04:48:39.814](0.015s) ok 18 - connect with wrong server root cert sslmode=verify-full [04:48:39.814](0.000s) ok 19 - connect with wrong server root cert sslmode=verify-full: matches [04:48:39.829](0.015s) ok 20 - connect with server CA cert, without root CA [04:48:39.829](0.000s) ok 21 - connect with server CA cert, without root CA: matches [04:48:39.846](0.017s) ok 22 - connect with correct server CA cert file sslmode=require [04:48:39.846](0.000s) ok 23 - connect with correct server CA cert file sslmode=require: no stderr [04:48:39.864](0.018s) ok 24 - connect with correct server CA cert file sslmode=verify-ca [04:48:39.864](0.000s) ok 25 - connect with correct server CA cert file sslmode=verify-ca: no stderr [04:48:39.881](0.017s) ok 26 - connect with correct server CA cert file sslmode=verify-full [04:48:39.881](0.000s) ok 27 - connect with correct server CA cert file sslmode=verify-full: no stderr [04:48:39.898](0.017s) ok 28 - cert root file that contains two certificates, order 1 [04:48:39.898](0.000s) ok 29 - cert root file that contains two certificates, order 1: no stderr [04:48:39.915](0.017s) ok 30 - cert root file that contains two certificates, order 2 [04:48:39.915](0.000s) ok 31 - cert root file that contains two certificates, order 2: no stderr [04:48:39.932](0.016s) ok 32 - connect with sslcertmode=disable [04:48:39.932](0.000s) ok 33 - connect with sslcertmode=disable: no stderr [04:48:39.948](0.017s) ok 34 - connect with sslcertmode=allow [04:48:39.949](0.000s) ok 35 - connect with sslcertmode=allow: no stderr [04:48:39.966](0.017s) ok 36 - connect with sslcertmode=require fails without a client certificate [04:48:39.966](0.000s) ok 37 - connect with sslcertmode=require fails without a client certificate: matches [04:48:39.983](0.017s) ok 38 - sslcrl option with invalid file name [04:48:39.983](0.000s) ok 39 - sslcrl option with invalid file name: no stderr [04:48:39.999](0.016s) ok 40 - CRL belonging to a different CA [04:48:39.999](0.000s) ok 41 - CRL belonging to a different CA: matches [04:48:40.014](0.015s) ok 42 - directory CRL belonging to a different CA [04:48:40.014](0.000s) ok 43 - directory CRL belonging to a different CA: matches [04:48:40.031](0.017s) ok 44 - CRL with a non-revoked cert [04:48:40.031](0.000s) ok 45 - CRL with a non-revoked cert: no stderr [04:48:40.048](0.016s) ok 46 - directory CRL with a non-revoked cert [04:48:40.048](0.000s) ok 47 - directory CRL with a non-revoked cert: no stderr [04:48:40.064](0.016s) ok 48 - mismatch between host name and server certificate sslmode=require [04:48:40.064](0.000s) ok 49 - mismatch between host name and server certificate sslmode=require: no stderr [04:48:40.081](0.017s) ok 50 - mismatch between host name and server certificate sslmode=verify-ca [04:48:40.081](0.000s) ok 51 - mismatch between host name and server certificate sslmode=verify-ca: no stderr [04:48:40.099](0.018s) ok 52 - mismatch between host name and server certificate sslmode=verify-full [04:48:40.100](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 39000 [04:48:40.381](0.281s) ok 54 - IP address in the Common Name [04:48:40.381](0.000s) ok 55 - IP address in the Common Name: no stderr [04:48:40.401](0.020s) ok 56 - mismatch between host name and server certificate IP address [04:48:40.401](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 39012 [04:48:40.656](0.255s) ok 58 - IP address in a dNSName [04:48:40.657](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 39022 [04:48:40.921](0.264s) ok 60 - host name matching with X.509 Subject Alternative Names 1 [04:48:40.921](0.000s) ok 61 - host name matching with X.509 Subject Alternative Names 1: no stderr [04:48:40.942](0.021s) ok 62 - host name matching with X.509 Subject Alternative Names 2 [04:48:40.942](0.000s) ok 63 - host name matching with X.509 Subject Alternative Names 2: no stderr [04:48:40.959](0.017s) ok 64 - host name matching with X.509 Subject Alternative Names wildcard [04:48:40.959](0.000s) ok 65 - host name matching with X.509 Subject Alternative Names wildcard: no stderr [04:48:40.975](0.016s) ok 66 - host name not matching with X.509 Subject Alternative Names [04:48:40.975](0.000s) ok 67 - host name not matching with X.509 Subject Alternative Names: matches [04:48:40.990](0.015s) ok 68 - host name not matching with X.509 Subject Alternative Names wildcard [04:48:40.990](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 39040 [04:48:41.267](0.277s) ok 70 - host name matching with a single X.509 Subject Alternative Name [04:48:41.267](0.000s) ok 71 - host name matching with a single X.509 Subject Alternative Name: no stderr [04:48:41.287](0.020s) ok 72 - host name not matching with a single X.509 Subject Alternative Name [04:48:41.287](0.000s) ok 73 - host name not matching with a single X.509 Subject Alternative Name: matches [04:48:41.303](0.016s) ok 74 - host name not matching with a single X.509 Subject Alternative Name wildcard [04:48:41.303](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 39055 [04:48:41.583](0.279s) ok 76 - host matching an IPv4 address (Subject Alternative Name 1) [04:48:41.583](0.000s) ok 77 - host matching an IPv4 address (Subject Alternative Name 1): no stderr [04:48:41.603](0.020s) ok 78 - host matching an IPv4 address in alternate form (Subject Alternative Name 1) [04:48:41.603](0.000s) ok 79 - host matching an IPv4 address in alternate form (Subject Alternative Name 1): no stderr [04:48:41.619](0.016s) ok 80 - host not matching an IPv4 address (Subject Alternative Name 1) [04:48:41.619](0.000s) ok 81 - host not matching an IPv4 address (Subject Alternative Name 1): matches [04:48:41.636](0.016s) ok 82 - host matching an IPv6 address (Subject Alternative Name 2) [04:48:41.636](0.000s) ok 83 - host matching an IPv6 address (Subject Alternative Name 2): no stderr [04:48:41.652](0.016s) ok 84 - host matching an IPv6 address in alternate form (Subject Alternative Name 2) [04:48:41.652](0.000s) ok 85 - host matching an IPv6 address in alternate form (Subject Alternative Name 2): no stderr [04:48:41.668](0.016s) ok 86 - host matching an IPv6 address in mixed form (Subject Alternative Name 2) [04:48:41.668](0.000s) ok 87 - host matching an IPv6 address in mixed form (Subject Alternative Name 2): no stderr [04:48:41.685](0.016s) ok 88 - host not matching an IPv6 address (Subject Alternative Name 2) [04:48:41.685](0.000s) ok 89 - host not matching an IPv6 address (Subject Alternative Name 2): matches [04:48:41.700](0.016s) ok 90 - IPv6 host with CIDR mask does not match [04:48:41.700](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 39079 [04:48:41.966](0.266s) ok 92 - certificate with both a CN and SANs 1 [04:48:41.966](0.000s) ok 93 - certificate with both a CN and SANs 1: no stderr [04:48:41.987](0.021s) ok 94 - certificate with both a CN and SANs 2 [04:48:41.987](0.000s) ok 95 - certificate with both a CN and SANs 2: no stderr [04:48:42.003](0.016s) ok 96 - certificate with both a CN and SANs ignores CN [04:48:42.003](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 39094 [04:48:42.279](0.277s) ok 98 - certificate with both a CN and IP SANs matches CN [04:48:42.280](0.000s) ok 99 - certificate with both a CN and IP SANs matches CN: no stderr [04:48:42.301](0.021s) ok 100 - certificate with both a CN and IP SANs matches SAN 1 [04:48:42.301](0.000s) ok 101 - certificate with both a CN and IP SANs matches SAN 1: no stderr [04:48:42.318](0.017s) ok 102 - certificate with both a CN and IP SANs matches SAN 2 [04:48:42.318](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 39108 [04:48:42.598](0.280s) ok 104 - certificate with both an IP CN and IP SANs 1 [04:48:42.598](0.000s) ok 105 - certificate with both an IP CN and IP SANs 1: no stderr [04:48:42.620](0.021s) ok 106 - certificate with both an IP CN and IP SANs 2 [04:48:42.620](0.000s) ok 107 - certificate with both an IP CN and IP SANs 2: no stderr [04:48:42.636](0.017s) ok 108 - certificate with both an IP CN and IP SANs ignores CN [04:48:42.636](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 39122 [04:48:42.895](0.258s) ok 110 - certificate with both an IP CN and DNS SANs matches CN [04:48:42.895](0.000s) ok 111 - certificate with both an IP CN and DNS SANs matches CN: no stderr [04:48:42.913](0.018s) ok 112 - certificate with both an IP CN and DNS SANs matches SAN 1 [04:48:42.913](0.000s) ok 113 - certificate with both an IP CN and DNS SANs matches SAN 1: no stderr [04:48:42.930](0.017s) ok 114 - certificate with both an IP CN and DNS SANs matches SAN 2 [04:48:42.930](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 39136 [04:48:43.190](0.260s) ok 116 - server certificate without CN or SANs sslmode=verify-ca [04:48:43.190](0.000s) ok 117 - server certificate without CN or SANs sslmode=verify-ca: no stderr [04:48:43.209](0.019s) ok 118 - server certificate without CN or SANs sslmode=verify-full [04:48:43.209](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 39148 [04:48:43.477](0.268s) ok 120 - sslrootcert=system does not connect with private CA [04:48:43.477](0.000s) ok 121 - sslrootcert=system does not connect with private CA: matches [04:48:43.490](0.013s) ok 122 - sslrootcert=system only accepts sslmode=verify-full [04:48:43.490](0.000s) ok 123 - sslrootcert=system only accepts sslmode=verify-full: matches [04:48:43.515](0.025s) ok 124 - sslrootcert=system connects with overridden SSL_CERT_FILE [04:48:43.515](0.000s) ok 125 - sslrootcert=system connects with overridden SSL_CERT_FILE: no stderr [04:48:43.531](0.017s) ok 126 - sslrootcert=system defaults to sslmode=verify-full [04:48:43.531](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 39163 [04:48:43.801](0.269s) ok 128 - connects without client-side CRL [04:48:43.801](0.000s) ok 129 - connects without client-side CRL: no stderr [04:48:43.821](0.020s) ok 130 - does not connect with client-side CRL file [04:48:43.821](0.000s) ok 131 - does not connect with client-side CRL file: matches [04:48:43.839](0.018s) ok 132 - does not connect with client-side CRL directory [04:48:43.839](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() [04:48:43.855](0.017s) ok 134 - pg_stat_ssl view without client certificate: exit code 0 [04:48:43.855](0.000s) ok 135 - pg_stat_ssl view without client certificate: no stderr [04:48:43.855](0.000s) ok 136 - pg_stat_ssl view without client certificate: matches [04:48:43.872](0.016s) ok 137 - connection success with correct range of TLS protocol versions [04:48:43.872](0.000s) ok 138 - connection success with correct range of TLS protocol versions: no stderr [04:48:43.879](0.007s) ok 139 - connection failure with incorrect range of TLS protocol versions [04:48:43.879](0.000s) ok 140 - connection failure with incorrect range of TLS protocol versions: matches [04:48:43.887](0.007s) ok 141 - connection failure with an incorrect SSL protocol minimum bound [04:48:43.887](0.000s) ok 142 - connection failure with an incorrect SSL protocol minimum bound: matches [04:48:43.894](0.007s) ok 143 - connection failure with an incorrect SSL protocol maximum bound [04:48:43.894](0.000s) ok 144 - connection failure with an incorrect SSL protocol maximum bound: matches [04:48:43.894](0.000s) # running server tests [04:48:43.910](0.016s) ok 145 - certificate authorization fails without client cert [04:48:43.910](0.000s) ok 146 - certificate authorization fails without client cert: matches [04:48:43.930](0.020s) ok 147 - certificate authorization succeeds with correct client cert in PEM format [04:48:43.930](0.000s) ok 148 - certificate authorization succeeds with correct client cert in PEM format: no stderr [04:48:43.947](0.017s) ok 149 - certificate authorization succeeds with correct client cert in DER format [04:48:43.947](0.000s) ok 150 - certificate authorization succeeds with correct client cert in DER format: no stderr [04:48:43.965](0.018s) ok 151 - certificate authorization succeeds with correct client cert in encrypted PEM format [04:48:43.965](0.000s) ok 152 - certificate authorization succeeds with correct client cert in encrypted PEM format: no stderr [04:48:43.983](0.018s) ok 153 - certificate authorization succeeds with correct client cert in encrypted DER format [04:48:43.983](0.000s) ok 154 - certificate authorization succeeds with correct client cert in encrypted DER format: no stderr [04:48:44.000](0.017s) ok 155 - certificate authorization succeeds with correct client cert and sslcertmode=require [04:48:44.000](0.000s) ok 156 - certificate authorization succeeds with correct client cert and sslcertmode=require: no stderr [04:48:44.018](0.018s) ok 157 - certificate authorization succeeds with correct client cert and sslcertmode=allow [04:48:44.018](0.000s) ok 158 - certificate authorization succeeds with correct client cert and sslcertmode=allow: no stderr [04:48:44.034](0.016s) ok 159 - certificate authorization fails with correct client cert and sslcertmode=disable [04:48:44.034](0.000s) ok 160 - certificate authorization fails with correct client cert and sslcertmode=disable: matches [04:48:44.048](0.015s) ok 161 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format [04:48:44.048](0.000s) ok 162 - certificate authorization fails with correct client cert and wrong password in encrypted PEM format: matches [04:48:44.069](0.020s) ok 163 - certificate authorization succeeds with DN mapping [04:48:44.069](0.000s) ok 164 - certificate authorization succeeds with DN mapping: no stderr [04:48:44.069](0.000s) ok 165 - certificate authorization succeeds with DN mapping: log matches [04:48:44.089](0.020s) ok 166 - certificate authorization succeeds with DN regex mapping [04:48:44.089](0.000s) ok 167 - certificate authorization succeeds with DN regex mapping: no stderr [04:48:44.108](0.019s) ok 168 - certificate authorization succeeds with CN mapping [04:48:44.108](0.000s) ok 169 - certificate authorization succeeds with CN mapping: no stderr [04:48:44.108](0.000s) ok 170 - certificate authorization succeeds with CN mapping: log matches [04:48:44.108](0.000s) not ok 171 # TODO & SKIP Need Pty support [04:48:44.108](0.000s) not ok 172 # TODO & SKIP Need Pty support [04:48:44.108](0.000s) not ok 173 # TODO & SKIP Need Pty support [04:48:44.109](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_OuWP/client.key -c SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() [04:48:44.137](0.028s) ok 175 - pg_stat_ssl with client certificate: exit code 0 [04:48:44.137](0.000s) ok 176 - pg_stat_ssl with client certificate: no stderr [04:48:44.137](0.000s) ok 177 - pg_stat_ssl with client certificate: matches [04:48:44.151](0.014s) ok 178 - certificate authorization fails because of file permissions [04:48:44.151](0.000s) ok 179 - certificate authorization fails because of file permissions: matches [04:48:44.168](0.017s) ok 180 - certificate authorization fails with client cert belonging to another user [04:48:44.168](0.000s) ok 181 - certificate authorization fails with client cert belonging to another user: matches [04:48:44.187](0.019s) ok 182 - certificate authorization fails with revoked client cert [04:48:44.187](0.000s) ok 183 - certificate authorization fails with revoked client cert: matches [04:48:44.187](0.000s) ok 184 - certificate authorization fails with revoked client cert: log does not match [04:48:44.206](0.020s) ok 185 - auth_option clientcert=verify-full succeeds with matching username and Common Name [04:48:44.207](0.000s) ok 186 - auth_option clientcert=verify-full succeeds with matching username and Common Name: no stderr [04:48:44.207](0.000s) ok 187 - auth_option clientcert=verify-full succeeds with matching username and Common Name: log matches [04:48:44.224](0.017s) ok 188 - auth_option clientcert=verify-full fails with mismatching username and Common Name [04:48:44.224](0.000s) ok 189 - auth_option clientcert=verify-full fails with mismatching username and Common Name: matches [04:48:44.224](0.000s) ok 190 - auth_option clientcert=verify-full fails with mismatching username and Common Name: log does not match [04:48:44.242](0.018s) ok 191 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name [04:48:44.242](0.000s) ok 192 - auth_option clientcert=verify-ca succeeds with mismatching username and Common Name: no stderr [04:48:44.242](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 39223 [04:48:44.501](0.259s) ok 194 - intermediate client certificate is provided by client [04:48:44.501](0.000s) ok 195 - intermediate client certificate is provided by client: no stderr [04:48:44.521](0.020s) ok 196 - intermediate client certificate is missing [04:48:44.521](0.000s) ok 197 - intermediate client certificate is missing: matches [04:48:44.539](0.018s) ok 198 - logged client certificate Subjects are truncated if they're too long [04:48:44.539](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 39237 [04:48:44.794](0.255s) ok 200 - intermediate client certificate is untrusted [04:48:44.794](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 39247 [04:48:45.078](0.284s) ok 202 - certificate authorization fails with revoked client cert with server-side CRL directory [04:48:45.078](0.000s) ok 203 - certificate authorization fails with revoked client cert with server-side CRL directory: matches [04:48:45.102](0.024s) ok 204 - certificate authorization fails with revoked UTF-8 client cert with server-side CRL directory [04:48:45.102](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 39259 [04:48:45.375](0.273s) not ok 206 - connect with valid stapled ocsp response when sslocspstapling=1 [04:48:45.375](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. [04:48:45.375](0.000s) # got: '2' # expected: '0' [04:48:45.376](0.000s) not ok 207 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [04:48:45.376](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. [04:48:45.376](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 63713 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: '' [04:48:45.401](0.026s) ok 208 - connect without requesting ocsp response when sslocspstapling=0 [04:48:45.401](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 39271 [04:48:45.681](0.279s) ok 210 - failed with a revoked ocsp response when sslocspstapling=1 [04:48:45.706](0.026s) ok 211 - connect without requesting ocsp response when sslocspstapling=0 [04:48:45.706](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 39283 [04:48:45.982](0.276s) ok 213 - failed with a revoked ocsp response when sslocspstapling=1 [04:48:46.007](0.025s) ok 214 - connect without requesting ocsp response when sslocspstapling=0 [04:48:46.007](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 39295 [04:48:46.266](0.259s) ok 216 - failed with an expired ocsp response when sslocspstapling=1 [04:48:46.290](0.023s) ok 217 - connect without requesting ocsp response when sslocspstapling=0 [04:48:46.290](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 39307 [04:48:46.551](0.261s) not ok 219 - connect with valid stapled ocsp response when sslocspstapling=1 [04:48:46.551](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. [04:48:46.551](0.000s) # got: '2' # expected: '0' [04:48:46.551](0.000s) not ok 220 - connect with valid stapled ocsp response when sslocspstapling=1: no stderr [04:48:46.551](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. [04:48:46.552](0.000s) # got: 'psql: error: connection to server at "127.0.0.1", port 63713 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: '' [04:48:46.576](0.024s) ok 221 - connect without requesting ocsp response when sslocspstapling=0 [04:48:46.576](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 39319 [04:48:46.846](0.270s) ok 223 - failed with a revoked ocsp response when sslocspstapling=1 [04:48:46.871](0.025s) ok 224 - connect without requesting ocsp response when sslocspstapling=0 [04:48:46.871](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 39331 [04:48:47.135](0.264s) ok 226 - failed with a revoked ocsp response when sslocspstapling=1 [04:48:47.160](0.024s) ok 227 - connect without requesting ocsp response when sslocspstapling=0 [04:48:47.160](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 39344 [04:48:47.415](0.255s) ok 229 - failed with an expired ocsp response when sslocspstapling=1 [04:48:47.440](0.025s) ok 230 - connect without requesting ocsp response when sslocspstapling=0 [04:48:47.440](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 39356 [04:48:47.708](0.268s) ok 232 - failed with an expired ocsp response when sslocspstapling=1 [04:48:47.732](0.025s) ok 233 - connect without requesting ocsp response when sslocspstapling=0 [04:48:47.733](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-08 04:48:32.013 UTC [38098][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:32.013 UTC [38098][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:32.276 UTC [38148][startup] LOG: database system was shut down at 2024-03-08 04:43:55 UTC 2024-03-08 04:48:32.282 UTC [38098][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:32.385 UTC [38194][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:32.385 UTC [38194][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-08 04:48:32.385 UTC [38194][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:32.388 UTC [38194][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SHOW ssl_library 2024-03-08 04:48:32.388 UTC [38194][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.003 user=admin database=postgres host=[local] 2024-03-08 04:48:32.399 UTC [38199][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:32.400 UTC [38199][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-08 04:48:32.400 UTC [38199][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:32.400 UTC [38199][client backend] [001_ssltests.pl][1/2:0] LOG: statement: CREATE USER ssltestuser 2024-03-08 04:48:32.401 UTC [38199][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-08 04:48:32.444 UTC [38214][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:32.444 UTC [38214][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-08 04:48:32.444 UTC [38214][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:32.444 UTC [38214][client backend] [001_ssltests.pl][2/2:0] LOG: statement: CREATE USER md5testuser 2024-03-08 04:48:32.445 UTC [38214][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.000 user=admin database=postgres host=[local] 2024-03-08 04:48:32.495 UTC [38228][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:32.495 UTC [38228][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-08 04:48:32.495 UTC [38228][client backend] [[unknown]][3/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:32.496 UTC [38228][client backend] [001_ssltests.pl][3/2:0] LOG: statement: CREATE USER anotheruser 2024-03-08 04:48:32.496 UTC [38228][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-08 04:48:32.545 UTC [38246][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:32.545 UTC [38246][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-08 04:48:32.545 UTC [38246][client backend] [[unknown]][4/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:32.546 UTC [38246][client backend] [001_ssltests.pl][4/2:0] LOG: statement: CREATE USER yetanotheruser 2024-03-08 04:48:32.546 UTC [38246][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.001 user=admin database=postgres host=[local] 2024-03-08 04:48:32.598 UTC [38261][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:32.599 UTC [38261][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-08 04:48:32.599 UTC [38261][client backend] [[unknown]][5/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:32.599 UTC [38261][client backend] [001_ssltests.pl][5/2:0] LOG: statement: CREATE DATABASE trustdb 2024-03-08 04:48:32.638 UTC [38261][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.040 user=admin database=postgres host=[local] 2024-03-08 04:48:32.723 UTC [38286][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:32.723 UTC [38286][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-08 04:48:32.723 UTC [38286][client backend] [[unknown]][6/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:32.724 UTC [38286][client backend] [001_ssltests.pl][6/2:0] LOG: statement: CREATE DATABASE certdb 2024-03-08 04:48:33.436 UTC [38286][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.713 user=admin database=postgres host=[local] 2024-03-08 04:48:33.879 UTC [38326][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:33.880 UTC [38326][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-08 04:48:33.880 UTC [38326][client backend] [[unknown]][7/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:33.880 UTC [38326][client backend] [001_ssltests.pl][7/2:0] LOG: statement: CREATE DATABASE certdb_dn 2024-03-08 04:48:33.919 UTC [38326][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.039 user=admin database=postgres host=[local] 2024-03-08 04:48:34.024 UTC [38335][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:34.024 UTC [38335][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-08 04:48:34.024 UTC [38335][client backend] [[unknown]][8/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:34.024 UTC [38335][client backend] [001_ssltests.pl][8/2:0] LOG: statement: CREATE DATABASE certdb_dn_re 2024-03-08 04:48:34.469 UTC [38335][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.445 user=admin database=postgres host=[local] 2024-03-08 04:48:34.663 UTC [38349][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:34.664 UTC [38349][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-08 04:48:34.664 UTC [38349][client backend] [[unknown]][9/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:34.664 UTC [38349][client backend] [001_ssltests.pl][9/2:0] LOG: statement: CREATE DATABASE certdb_cn 2024-03-08 04:48:34.750 UTC [38349][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.086 user=admin database=postgres host=[local] 2024-03-08 04:48:34.801 UTC [38359][not initialized] [[unknown]][:0] LOG: connection received: host=[local] 2024-03-08 04:48:34.801 UTC [38359][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-08 04:48:34.801 UTC [38359][client backend] [[unknown]][10/1:0] LOG: connection authorized: user=admin database=postgres application_name=001_ssltests.pl 2024-03-08 04:48:34.802 UTC [38359][client backend] [001_ssltests.pl][10/2:0] LOG: statement: CREATE DATABASE verifydb 2024-03-08 04:48:35.195 UTC [38359][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.394 user=admin database=postgres host=[local] 2024-03-08 04:48:35.852 UTC [38098][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:35.852 UTC [38098][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:35.854 UTC [38098][postmaster] LOG: background worker "logical replication launcher" (PID 38159) exited with exit code 1 2024-03-08 04:48:35.854 UTC [38146][checkpointer] LOG: shutting down 2024-03-08 04:48:35.854 UTC [38146][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:38.489 UTC [38146][checkpointer] LOG: checkpoint complete: wrote 5590 buffers (34.1%); 0 WAL file(s) added, 0 removed, 3 recycled; write=2.635 s, sync=0.001 s, total=2.636 s; sync files=0, longest=0.000 s, average=0.000 s; distance=44997 kB, estimate=44997 kB; lsn=0/41049C8, redo lsn=0/41049C8 2024-03-08 04:48:38.498 UTC [38098][postmaster] LOG: database system is shut down 2024-03-08 04:48:38.582 UTC [38781][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:38.583 UTC [38781][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:38.585 UTC [38781][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:38.589 UTC [38785][startup] LOG: database system was shut down at 2024-03-08 04:48:38 UTC 2024-03-08 04:48:38.590 UTC [38781][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:38.708 UTC [38781][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:38.708 UTC [38781][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:38.709 UTC [38781][postmaster] LOG: background worker "logical replication launcher" (PID 38788) exited with exit code 1 2024-03-08 04:48:38.709 UTC [38783][checkpointer] LOG: shutting down 2024-03-08 04:48:38.709 UTC [38783][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:38.725 UTC [38783][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.017 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4104A40, redo lsn=0/4104A40 2024-03-08 04:48:38.884 UTC [38781][postmaster] LOG: database system is shut down 2024-03-08 04:48:38.947 UTC [38811][postmaster] FATAL: could not load private key file "server-password.key": bad decrypt 2024-03-08 04:48:38.947 UTC [38811][postmaster] LOG: database system is shut down 2024-03-08 04:48:39.063 UTC [38822][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:39.064 UTC [38822][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:39.064 UTC [38822][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:39.066 UTC [38830][startup] LOG: database system was shut down at 2024-03-08 04:48:38 UTC 2024-03-08 04:48:39.067 UTC [38822][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:39.151 UTC [38822][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:39.151 UTC [38822][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:39.152 UTC [38822][postmaster] LOG: background worker "logical replication launcher" (PID 38833) exited with exit code 1 2024-03-08 04:48:39.153 UTC [38828][checkpointer] LOG: shutting down 2024-03-08 04:48:39.153 UTC [38828][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:39.153 UTC [38828][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/4104AB8, redo lsn=0/4104AB8 2024-03-08 04:48:39.154 UTC [38822][postmaster] LOG: database system is shut down 2024-03-08 04:48:39.277 UTC [38877][postmaster] FATAL: could not set SSL protocol version range 2024-03-08 04:48:39.277 UTC [38877][postmaster] DETAIL: ssl_min_protocol_version cannot be higher than ssl_max_protocol_version 2024-03-08 04:48:39.277 UTC [38877][postmaster] LOG: database system is shut down 2024-03-08 04:48:39.393 UTC [38900][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:39.394 UTC [38900][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:39.394 UTC [38900][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:39.396 UTC [38912][startup] LOG: database system was shut down at 2024-03-08 04:48:39 UTC 2024-03-08 04:48:39.397 UTC [38900][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:39.484 UTC [38900][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:39.484 UTC [38900][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:39.485 UTC [38900][postmaster] LOG: background worker "logical replication launcher" (PID 38915) exited with exit code 1 2024-03-08 04:48:39.485 UTC [38910][checkpointer] LOG: shutting down 2024-03-08 04:48:39.485 UTC [38910][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:39.486 UTC [38910][checkpointer] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4104B30, redo lsn=0/4104B30 2024-03-08 04:48:39.487 UTC [38900][postmaster] LOG: database system is shut down 2024-03-08 04:48:39.615 UTC [38924][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:39.615 UTC [38924][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:39.616 UTC [38924][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:39.622 UTC [38931][startup] LOG: database system was shut down at 2024-03-08 04:48:39 UTC 2024-03-08 04:48:39.626 UTC [38924][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:39.710 UTC [38939][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49275 2024-03-08 04:48:39.715 UTC [38939][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-08 04:48:39.715 UTC [38939][client backend] [[unknown]][0/1:0] DETAIL: Client IP address resolved to "localhost", forward lookup not checked. 2024-03-08 04:48:39.724 UTC [38943][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49277 2024-03-08 04:48:39.732 UTC [38943][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-08 04:48:39.732 UTC [38943][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-08 04:48:39.735 UTC [38943][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-08 04:48:39.735 UTC [38943][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49277 2024-03-08 04:48:39.745 UTC [38946][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49278 2024-03-08 04:48:39.751 UTC [38946][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-08 04:48:39.759 UTC [38948][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49279 2024-03-08 04:48:39.764 UTC [38948][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-08 04:48:39.773 UTC [38950][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49280 2024-03-08 04:48:39.782 UTC [38950][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-08 04:48:39.791 UTC [38952][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49281 2024-03-08 04:48:39.798 UTC [38952][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-08 04:48:39.806 UTC [38954][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49282 2024-03-08 04:48:39.813 UTC [38954][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-08 04:48:39.821 UTC [38956][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49283 2024-03-08 04:48:39.828 UTC [38956][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-08 04:48:39.837 UTC [38958][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49284 2024-03-08 04:48:39.844 UTC [38958][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-08 04:48:39.844 UTC [38958][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-08 04:48:39.845 UTC [38958][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-08 04:48:39.845 UTC [38958][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49284 2024-03-08 04:48:39.854 UTC [38961][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49285 2024-03-08 04:48:39.862 UTC [38961][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-08 04:48:39.862 UTC [38961][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-08 04:48:39.863 UTC [38961][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-08 04:48:39.863 UTC [38961][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49285 2024-03-08 04:48:39.872 UTC [38969][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49286 2024-03-08 04:48:39.879 UTC [38969][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-08 04:48:39.879 UTC [38969][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-08 04:48:39.880 UTC [38969][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-08 04:48:39.880 UTC [38969][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49286 2024-03-08 04:48:39.889 UTC [38971][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49287 2024-03-08 04:48:39.896 UTC [38971][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-08 04:48:39.896 UTC [38971][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-08 04:48:39.897 UTC [38971][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-08 04:48:39.897 UTC [38971][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49287 2024-03-08 04:48:39.906 UTC [38973][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49288 2024-03-08 04:48:39.914 UTC [38973][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-08 04:48:39.914 UTC [38973][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-08 04:48:39.914 UTC [38973][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-08 04:48:39.914 UTC [38973][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49288 2024-03-08 04:48:39.923 UTC [38975][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49289 2024-03-08 04:48:39.930 UTC [38975][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-08 04:48:39.930 UTC [38975][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-08 04:48:39.931 UTC [38975][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-08 04:48:39.931 UTC [38975][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49289 2024-03-08 04:48:39.940 UTC [38977][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49290 2024-03-08 04:48:39.947 UTC [38977][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-08 04:48:39.947 UTC [38977][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-08 04:48:39.947 UTC [38977][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-08 04:48:39.948 UTC [38977][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49290 2024-03-08 04:48:39.957 UTC [38981][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49291 2024-03-08 04:48:39.965 UTC [38981][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-08 04:48:39.965 UTC [38981][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-08 04:48:39.965 UTC [38981][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49291 2024-03-08 04:48:39.974 UTC [38984][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49293 2024-03-08 04:48:39.981 UTC [38984][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-08 04:48:39.981 UTC [38984][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-08 04:48:39.982 UTC [38984][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-08 04:48:39.982 UTC [38984][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49293 2024-03-08 04:48:39.991 UTC [38986][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49294 2024-03-08 04:48:39.998 UTC [38986][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-08 04:48:40.007 UTC [38988][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49295 2024-03-08 04:48:40.013 UTC [38988][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-08 04:48:40.022 UTC [38990][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49296 2024-03-08 04:48:40.030 UTC [38990][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-08 04:48:40.030 UTC [38990][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-08 04:48:40.030 UTC [38990][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-08 04:48:40.030 UTC [38990][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49296 2024-03-08 04:48:40.039 UTC [38992][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49297 2024-03-08 04:48:40.046 UTC [38992][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-08 04:48:40.046 UTC [38992][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-08 04:48:40.047 UTC [38992][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-08 04:48:40.047 UTC [38992][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49297 2024-03-08 04:48:40.056 UTC [38994][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49298 2024-03-08 04:48:40.063 UTC [38994][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-08 04:48:40.063 UTC [38994][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-08 04:48:40.063 UTC [38994][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-08 04:48:40.063 UTC [38994][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49298 2024-03-08 04:48:40.072 UTC [38996][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49299 2024-03-08 04:48:40.079 UTC [38996][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-08 04:48:40.079 UTC [38996][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-08 04:48:40.080 UTC [38996][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-08 04:48:40.080 UTC [38996][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49299 2024-03-08 04:48:40.091 UTC [38998][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49300 2024-03-08 04:48:40.103 UTC [38924][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:40.103 UTC [38924][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:40.104 UTC [38924][postmaster] LOG: background worker "logical replication launcher" (PID 38937) exited with exit code 1 2024-03-08 04:48:40.104 UTC [38927][checkpointer] LOG: shutting down 2024-03-08 04:48:40.104 UTC [38927][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:40.106 UTC [38927][checkpointer] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.000 s, total=0.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=0 kB; lsn=0/4104BA8, redo lsn=0/4104BA8 2024-03-08 04:48:40.107 UTC [38924][postmaster] LOG: database system is shut down 2024-03-08 04:48:40.247 UTC [39000][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:40.248 UTC [39000][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:40.248 UTC [39000][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:40.251 UTC [39003][startup] LOG: database system was shut down at 2024-03-08 04:48:40 UTC 2024-03-08 04:48:40.252 UTC [39000][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:40.363 UTC [39008][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49301 2024-03-08 04:48:40.376 UTC [39008][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-08 04:48:40.376 UTC [39008][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-08 04:48:40.379 UTC [39008][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-08 04:48:40.380 UTC [39008][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=49301 2024-03-08 04:48:40.392 UTC [39010][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49302 2024-03-08 04:48:40.405 UTC [39000][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:40.405 UTC [39000][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:40.405 UTC [39000][postmaster] LOG: background worker "logical replication launcher" (PID 39006) exited with exit code 1 2024-03-08 04:48:40.405 UTC [39001][checkpointer] LOG: shutting down 2024-03-08 04:48:40.405 UTC [39001][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:40.406 UTC [39001][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/4104C20, redo lsn=0/4104C20 2024-03-08 04:48:40.407 UTC [39000][postmaster] LOG: database system is shut down 2024-03-08 04:48:40.545 UTC [39012][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:40.545 UTC [39012][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:40.546 UTC [39012][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:40.549 UTC [39015][startup] LOG: database system was shut down at 2024-03-08 04:48:40 UTC 2024-03-08 04:48:40.551 UTC [39012][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:40.639 UTC [39020][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49303 2024-03-08 04:48:40.652 UTC [39020][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-08 04:48:40.652 UTC [39020][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-08 04:48:40.655 UTC [39020][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-08 04:48:40.655 UTC [39020][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=49303 2024-03-08 04:48:40.660 UTC [39012][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:40.660 UTC [39012][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:40.661 UTC [39012][postmaster] LOG: background worker "logical replication launcher" (PID 39018) exited with exit code 1 2024-03-08 04:48:40.661 UTC [39013][checkpointer] LOG: shutting down 2024-03-08 04:48:40.661 UTC [39013][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:40.662 UTC [39013][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/4104C98, redo lsn=0/4104C98 2024-03-08 04:48:40.663 UTC [39012][postmaster] LOG: database system is shut down 2024-03-08 04:48:40.797 UTC [39022][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:40.797 UTC [39022][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:40.798 UTC [39022][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:40.801 UTC [39025][startup] LOG: database system was shut down at 2024-03-08 04:48:40 UTC 2024-03-08 04:48:40.803 UTC [39022][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:40.901 UTC [39030][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49304 2024-03-08 04:48:40.915 UTC [39030][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-08 04:48:40.915 UTC [39030][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-08 04:48:40.919 UTC [39030][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns1.alt-name.pg-ssltest.test$$ 2024-03-08 04:48:40.919 UTC [39030][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.019 user=ssltestuser database=trustdb host=localhost port=49304 2024-03-08 04:48:40.932 UTC [39032][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49305 2024-03-08 04:48:40.940 UTC [39032][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-08 04:48:40.940 UTC [39032][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-08 04:48:40.941 UTC [39032][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=dns2.alt-name.pg-ssltest.test$$ 2024-03-08 04:48:40.941 UTC [39032][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49305 2024-03-08 04:48:40.951 UTC [39034][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49306 2024-03-08 04:48:40.958 UTC [39034][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-08 04:48:40.958 UTC [39034][client backend] [[unknown]][2/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-08 04:48:40.958 UTC [39034][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb sslrootcert=ssl/root+server_ca.crt hostaddr=127.0.0.1 sslmode=verify-full host=foo.wildcard.pg-ssltest.test$$ 2024-03-08 04:48:40.958 UTC [39034][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=49306 2024-03-08 04:48:40.967 UTC [39036][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49307 2024-03-08 04:48:40.983 UTC [39038][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49308 2024-03-08 04:48:40.993 UTC [39022][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:40.993 UTC [39022][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:40.994 UTC [39022][postmaster] LOG: background worker "logical replication launcher" (PID 39028) exited with exit code 1 2024-03-08 04:48:40.994 UTC [39023][checkpointer] LOG: shutting down 2024-03-08 04:48:40.994 UTC [39023][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:40.994 UTC [39023][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/4104D10, redo lsn=0/4104D10 2024-03-08 04:48:40.995 UTC [39022][postmaster] LOG: database system is shut down 2024-03-08 04:48:41.131 UTC [39040][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:41.132 UTC [39040][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:41.132 UTC [39040][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:41.135 UTC [39043][startup] LOG: database system was shut down at 2024-03-08 04:48:40 UTC 2024-03-08 04:48:41.136 UTC [39040][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:41.247 UTC [39048][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49309 2024-03-08 04:48:41.261 UTC [39048][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-08 04:48:41.261 UTC [39048][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-08 04:48:41.265 UTC [39048][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-08 04:48:41.265 UTC [39048][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49309 2024-03-08 04:48:41.278 UTC [39050][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49310 2024-03-08 04:48:41.296 UTC [39052][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49311 2024-03-08 04:48:41.309 UTC [39040][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:41.310 UTC [39040][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:41.310 UTC [39040][postmaster] LOG: background worker "logical replication launcher" (PID 39046) exited with exit code 1 2024-03-08 04:48:41.310 UTC [39041][checkpointer] LOG: shutting down 2024-03-08 04:48:41.310 UTC [39041][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:41.311 UTC [39041][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/4104D88, redo lsn=0/4104D88 2024-03-08 04:48:41.312 UTC [39040][postmaster] LOG: database system is shut down 2024-03-08 04:48:41.447 UTC [39055][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:41.448 UTC [39055][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:41.448 UTC [39055][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:41.451 UTC [39058][startup] LOG: database system was shut down at 2024-03-08 04:48:41 UTC 2024-03-08 04:48:41.453 UTC [39055][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:41.564 UTC [39063][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49312 2024-03-08 04:48:41.577 UTC [39063][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-08 04:48:41.577 UTC [39063][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-08 04:48:41.581 UTC [39063][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-08 04:48:41.581 UTC [39063][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.018 user=ssltestuser database=trustdb host=localhost port=49312 2024-03-08 04:48:41.593 UTC [39065][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49313 2024-03-08 04:48:41.601 UTC [39065][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-08 04:48:41.601 UTC [39065][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-08 04:48:41.602 UTC [39065][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-08 04:48:41.602 UTC [39065][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49313 2024-03-08 04:48:41.612 UTC [39067][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49314 2024-03-08 04:48:41.627 UTC [39069][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49315 2024-03-08 04:48:41.634 UTC [39069][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-08 04:48:41.634 UTC [39069][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-08 04:48:41.635 UTC [39069][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-08 04:48:41.635 UTC [39069][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49315 2024-03-08 04:48:41.643 UTC [39071][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49316 2024-03-08 04:48:41.651 UTC [39071][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-08 04:48:41.651 UTC [39071][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-08 04:48:41.651 UTC [39071][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-08 04:48:41.651 UTC [39071][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49316 2024-03-08 04:48:41.660 UTC [39073][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49317 2024-03-08 04:48:41.667 UTC [39073][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-08 04:48:41.667 UTC [39073][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-08 04:48:41.667 UTC [39073][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-08 04:48:41.668 UTC [39073][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49317 2024-03-08 04:48:41.677 UTC [39075][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49318 2024-03-08 04:48:41.693 UTC [39077][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49319 2024-03-08 04:48:41.703 UTC [39055][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:41.703 UTC [39055][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:41.704 UTC [39055][postmaster] LOG: background worker "logical replication launcher" (PID 39061) exited with exit code 1 2024-03-08 04:48:41.704 UTC [39056][checkpointer] LOG: shutting down 2024-03-08 04:48:41.704 UTC [39056][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:41.705 UTC [39056][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/4104E00, redo lsn=0/4104E00 2024-03-08 04:48:41.706 UTC [39055][postmaster] LOG: database system is shut down 2024-03-08 04:48:41.847 UTC [39079][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:41.848 UTC [39079][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:41.848 UTC [39079][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:41.851 UTC [39082][startup] LOG: database system was shut down at 2024-03-08 04:48:41 UTC 2024-03-08 04:48:41.852 UTC [39079][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:41.949 UTC [39087][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49320 2024-03-08 04:48:41.961 UTC [39087][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-08 04:48:41.961 UTC [39087][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-08 04:48:41.964 UTC [39087][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-08 04:48:41.965 UTC [39087][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=49320 2024-03-08 04:48:41.977 UTC [39089][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49321 2024-03-08 04:48:41.985 UTC [39089][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-08 04:48:41.985 UTC [39089][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-08 04:48:41.986 UTC [39089][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-08 04:48:41.986 UTC [39089][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49321 2024-03-08 04:48:41.995 UTC [39091][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49322 2024-03-08 04:48:42.009 UTC [39079][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:42.009 UTC [39079][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:42.010 UTC [39079][postmaster] LOG: background worker "logical replication launcher" (PID 39085) exited with exit code 1 2024-03-08 04:48:42.010 UTC [39080][checkpointer] LOG: shutting down 2024-03-08 04:48:42.010 UTC [39080][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:42.010 UTC [39080][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/4104E78, redo lsn=0/4104E78 2024-03-08 04:48:42.011 UTC [39079][postmaster] LOG: database system is shut down 2024-03-08 04:48:42.143 UTC [39094][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:42.144 UTC [39094][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:42.144 UTC [39094][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:42.147 UTC [39097][startup] LOG: database system was shut down at 2024-03-08 04:48:42 UTC 2024-03-08 04:48:42.148 UTC [39094][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:42.261 UTC [39102][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49323 2024-03-08 04:48:42.274 UTC [39102][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-08 04:48:42.274 UTC [39102][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-08 04:48:42.278 UTC [39102][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-08 04:48:42.278 UTC [39102][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.017 user=ssltestuser database=trustdb host=localhost port=49323 2024-03-08 04:48:42.290 UTC [39104][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49324 2024-03-08 04:48:42.299 UTC [39104][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-08 04:48:42.299 UTC [39104][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-08 04:48:42.300 UTC [39104][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-08 04:48:42.300 UTC [39104][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49324 2024-03-08 04:48:42.309 UTC [39106][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49325 2024-03-08 04:48:42.316 UTC [39106][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-08 04:48:42.316 UTC [39106][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-08 04:48:42.317 UTC [39106][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-08 04:48:42.317 UTC [39106][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49325 2024-03-08 04:48:42.321 UTC [39094][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:42.321 UTC [39094][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:42.321 UTC [39094][postmaster] LOG: background worker "logical replication launcher" (PID 39100) exited with exit code 1 2024-03-08 04:48:42.321 UTC [39095][checkpointer] LOG: shutting down 2024-03-08 04:48:42.321 UTC [39095][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:42.322 UTC [39095][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/4104EF0, redo lsn=0/4104EF0 2024-03-08 04:48:42.323 UTC [39094][postmaster] LOG: database system is shut down 2024-03-08 04:48:42.465 UTC [39108][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:42.466 UTC [39108][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:42.466 UTC [39108][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:42.469 UTC [39111][startup] LOG: database system was shut down at 2024-03-08 04:48:42 UTC 2024-03-08 04:48:42.471 UTC [39108][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:42.579 UTC [39116][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49326 2024-03-08 04:48:42.593 UTC [39116][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-08 04:48:42.593 UTC [39116][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-08 04:48:42.597 UTC [39116][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-08 04:48:42.597 UTC [39116][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.019 user=ssltestuser database=trustdb host=localhost port=49326 2024-03-08 04:48:42.610 UTC [39118][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49327 2024-03-08 04:48:42.618 UTC [39118][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-08 04:48:42.618 UTC [39118][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-08 04:48:42.619 UTC [39118][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-08 04:48:42.619 UTC [39118][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=trustdb host=localhost port=49327 2024-03-08 04:48:42.629 UTC [39120][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49328 2024-03-08 04:48:42.639 UTC [39108][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:42.639 UTC [39108][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:42.640 UTC [39108][postmaster] LOG: background worker "logical replication launcher" (PID 39114) exited with exit code 1 2024-03-08 04:48:42.640 UTC [39109][checkpointer] LOG: shutting down 2024-03-08 04:48:42.640 UTC [39109][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:42.641 UTC [39109][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/4104F68, redo lsn=0/4104F68 2024-03-08 04:48:42.641 UTC [39108][postmaster] LOG: database system is shut down 2024-03-08 04:48:42.775 UTC [39122][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:42.775 UTC [39122][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:42.775 UTC [39122][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:42.778 UTC [39125][startup] LOG: database system was shut down at 2024-03-08 04:48:42 UTC 2024-03-08 04:48:42.780 UTC [39122][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:42.881 UTC [39130][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49329 2024-03-08 04:48:42.891 UTC [39130][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-08 04:48:42.891 UTC [39130][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-08 04:48:42.893 UTC [39130][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-08 04:48:42.894 UTC [39130][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49329 2024-03-08 04:48:42.904 UTC [39132][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49330 2024-03-08 04:48:42.911 UTC [39132][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-08 04:48:42.911 UTC [39132][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-08 04:48:42.912 UTC [39132][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-08 04:48:42.912 UTC [39132][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49330 2024-03-08 04:48:42.921 UTC [39134][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49331 2024-03-08 04:48:42.928 UTC [39134][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-08 04:48:42.928 UTC [39134][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-08 04:48:42.929 UTC [39134][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-08 04:48:42.929 UTC [39134][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49331 2024-03-08 04:48:42.933 UTC [39122][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:42.933 UTC [39122][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:42.933 UTC [39122][postmaster] LOG: background worker "logical replication launcher" (PID 39128) exited with exit code 1 2024-03-08 04:48:42.933 UTC [39123][checkpointer] LOG: shutting down 2024-03-08 04:48:42.933 UTC [39123][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:42.934 UTC [39123][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/4104FE0, redo lsn=0/4104FE0 2024-03-08 04:48:42.935 UTC [39122][postmaster] LOG: database system is shut down 2024-03-08 04:48:43.072 UTC [39136][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:43.072 UTC [39136][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:43.073 UTC [39136][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:43.075 UTC [39139][startup] LOG: database system was shut down at 2024-03-08 04:48:42 UTC 2024-03-08 04:48:43.077 UTC [39136][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:43.174 UTC [39144][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49332 2024-03-08 04:48:43.185 UTC [39144][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-08 04:48:43.185 UTC [39144][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-08 04:48:43.189 UTC [39144][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-08 04:48:43.189 UTC [39144][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.015 user=ssltestuser database=trustdb host=localhost port=49332 2024-03-08 04:48:43.200 UTC [39146][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49333 2024-03-08 04:48:43.212 UTC [39136][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:43.212 UTC [39136][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:43.213 UTC [39136][postmaster] LOG: background worker "logical replication launcher" (PID 39142) exited with exit code 1 2024-03-08 04:48:43.213 UTC [39137][checkpointer] LOG: shutting down 2024-03-08 04:48:43.213 UTC [39137][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:43.213 UTC [39137][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/4105058, redo lsn=0/4105058 2024-03-08 04:48:43.214 UTC [39136][postmaster] LOG: database system is shut down 2024-03-08 04:48:43.352 UTC [39148][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:43.353 UTC [39148][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:43.353 UTC [39148][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:43.356 UTC [39151][startup] LOG: database system was shut down at 2024-03-08 04:48:43 UTC 2024-03-08 04:48:43.357 UTC [39148][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:43.462 UTC [39156][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49334 2024-03-08 04:48:43.475 UTC [39156][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: tlsv1 alert unknown ca 2024-03-08 04:48:43.502 UTC [39159][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49335 2024-03-08 04:48:43.511 UTC [39159][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-08 04:48:43.511 UTC [39159][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-08 04:48:43.513 UTC [39159][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-08 04:48:43.514 UTC [39159][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49335 2024-03-08 04:48:43.523 UTC [39161][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49336 2024-03-08 04:48:43.534 UTC [39148][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:43.534 UTC [39148][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:43.535 UTC [39148][postmaster] LOG: background worker "logical replication launcher" (PID 39154) exited with exit code 1 2024-03-08 04:48:43.535 UTC [39149][checkpointer] LOG: shutting down 2024-03-08 04:48:43.535 UTC [39149][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:43.536 UTC [39149][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/41050D0, redo lsn=0/41050D0 2024-03-08 04:48:43.537 UTC [39148][postmaster] LOG: database system is shut down 2024-03-08 04:48:43.671 UTC [39163][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:43.671 UTC [39163][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:43.672 UTC [39163][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:43.674 UTC [39166][startup] LOG: database system was shut down at 2024-03-08 04:48:43 UTC 2024-03-08 04:48:43.676 UTC [39163][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:43.784 UTC [39171][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49337 2024-03-08 04:48:43.796 UTC [39171][client backend] [[unknown]][0/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-08 04:48:43.796 UTC [39171][client backend] [[unknown]][0/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-08 04:48:43.799 UTC [39171][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb hostaddr=127.0.0.1 host=common-name.pg-ssltest.test sslrootcert=ssl/root+server_ca.crt sslmode=verify-ca$$ 2024-03-08 04:48:43.800 UTC [39171][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.016 user=ssltestuser database=trustdb host=localhost port=49337 2024-03-08 04:48:43.812 UTC [39173][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49338 2024-03-08 04:48:43.820 UTC [39173][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: ssl/tls alert certificate revoked 2024-03-08 04:48:43.829 UTC [39175][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49339 2024-03-08 04:48:43.838 UTC [39175][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: ssl/tls alert certificate revoked 2024-03-08 04:48:43.846 UTC [39177][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49340 2024-03-08 04:48:43.853 UTC [39177][client backend] [[unknown]][1/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-08 04:48:43.853 UTC [39177][client backend] [[unknown]][1/1:0] LOG: connection authorized: user=ssltestuser database=trustdb application_name=001_ssltests.pl SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, bits=256) 2024-03-08 04:48:43.854 UTC [39177][client backend] [001_ssltests.pl][1/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-08 04:48:43.855 UTC [39177][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.008 user=ssltestuser database=trustdb host=localhost port=49340 2024-03-08 04:48:43.863 UTC [39179][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49341 2024-03-08 04:48:43.870 UTC [39179][client backend] [[unknown]][2/1:0] LOG: connection authenticated: user="ssltestuser" method=trust (/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/t_001_ssltests_primary_data/pgdata/pg_hba.conf:3) 2024-03-08 04:48:43.870 UTC [39179][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-08 04:48:43.871 UTC [39179][client backend] [001_ssltests.pl][2/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=trustdb 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-08 04:48:43.871 UTC [39179][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.007 user=ssltestuser database=trustdb host=localhost port=49341 2024-03-08 04:48:43.902 UTC [39184][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49342 2024-03-08 04:48:43.909 UTC [39184][client backend] [[unknown]][3/1:0] FATAL: connection requires a valid client certificate 2024-03-08 04:48:43.918 UTC [39186][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49343 2024-03-08 04:48:43.926 UTC [39186][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-08 04:48:43.926 UTC [39186][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-08 04:48:43.928 UTC [39186][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_OuWP/client.key$$ 2024-03-08 04:48:43.929 UTC [39186][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb host=localhost port=49343 2024-03-08 04:48:43.937 UTC [39188][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49344 2024-03-08 04:48:43.946 UTC [39188][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-08 04:48:43.946 UTC [39188][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-08 04:48:43.946 UTC [39188][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_OuWP/client-der.key$$ 2024-03-08 04:48:43.946 UTC [39188][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49344 2024-03-08 04:48:43.955 UTC [39190][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49345 2024-03-08 04:48:43.964 UTC [39190][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-08 04:48:43.964 UTC [39190][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-08 04:48:43.964 UTC [39190][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_OuWP/client-encrypted-pem.key sslpassword='dUmmyP^#+'$$ 2024-03-08 04:48:43.964 UTC [39190][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49345 2024-03-08 04:48:43.973 UTC [39192][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49346 2024-03-08 04:48:43.981 UTC [39192][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-08 04:48:43.981 UTC [39192][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-08 04:48:43.982 UTC [39192][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_OuWP/client-encrypted-der.key sslpassword='dUmmyP^#+'$$ 2024-03-08 04:48:43.982 UTC [39192][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49346 2024-03-08 04:48:43.990 UTC [39194][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49347 2024-03-08 04:48:43.999 UTC [39194][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-08 04:48:43.999 UTC [39194][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-08 04:48:43.999 UTC [39194][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_OuWP/client.key$$ 2024-03-08 04:48:43.999 UTC [39194][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49347 2024-03-08 04:48:44.008 UTC [39196][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49348 2024-03-08 04:48:44.016 UTC [39196][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-08 04:48:44.016 UTC [39196][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-08 04:48:44.017 UTC [39196][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_OuWP/client.key$$ 2024-03-08 04:48:44.017 UTC [39196][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=ssltestuser database=certdb host=localhost port=49348 2024-03-08 04:48:44.026 UTC [39198][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49349 2024-03-08 04:48:44.033 UTC [39198][client backend] [[unknown]][10/1:0] FATAL: connection requires a valid client certificate 2024-03-08 04:48:44.042 UTC [39200][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49350 2024-03-08 04:48:44.048 UTC [39200][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-08 04:48:44.056 UTC [39202][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49351 2024-03-08 04:48:44.065 UTC [39202][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-08 04:48:44.065 UTC [39202][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-08 04:48:44.068 UTC [39202][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_OuWP/client-dn.key$$ 2024-03-08 04:48:44.068 UTC [39202][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=certdb_dn host=localhost port=49351 2024-03-08 04:48:44.077 UTC [39204][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49352 2024-03-08 04:48:44.085 UTC [39204][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-08 04:48:44.085 UTC [39204][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-08 04:48:44.088 UTC [39204][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_OuWP/client-dn.key$$ 2024-03-08 04:48:44.088 UTC [39204][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-08 04:48:44.097 UTC [39206][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49353 2024-03-08 04:48:44.105 UTC [39206][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-08 04:48:44.105 UTC [39206][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-08 04:48:44.107 UTC [39206][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_OuWP/client-dn.key$$ 2024-03-08 04:48:44.107 UTC [39206][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-08 04:48:44.126 UTC [39209][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49354 2024-03-08 04:48:44.135 UTC [39209][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-08 04:48:44.135 UTC [39209][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-08 04:48:44.135 UTC [39209][client backend] [001_ssltests.pl][14/2:0] LOG: statement: SELECT * FROM pg_stat_ssl WHERE pid = pg_backend_pid() 2024-03-08 04:48:44.136 UTC [39209][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.010 user=ssltestuser database=certdb host=localhost port=49354 2024-03-08 04:48:44.145 UTC [39211][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49355 2024-03-08 04:48:44.150 UTC [39211][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: EOF detected 2024-03-08 04:48:44.159 UTC [39213][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49356 2024-03-08 04:48:44.167 UTC [39213][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-08 04:48:44.167 UTC [39213][client backend] [[unknown]][15/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-08 04:48:44.167 UTC [39213][client backend] [[unknown]][15/1:0] FATAL: certificate authentication failed for user "anotheruser" 2024-03-08 04:48:44.167 UTC [39213][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-08 04:48:44.176 UTC [39215][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49357 2024-03-08 04:48:44.186 UTC [39215][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-08 04:48:44.186 UTC [39215][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-08 04:48:44.195 UTC [39217][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49358 2024-03-08 04:48:44.203 UTC [39217][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-08 04:48:44.203 UTC [39217][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-08 04:48:44.205 UTC [39217][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_OuWP/client.key$$ 2024-03-08 04:48:44.206 UTC [39217][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=verifydb host=localhost port=49358 2024-03-08 04:48:44.215 UTC [39219][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49359 2024-03-08 04:48:44.223 UTC [39219][client backend] [[unknown]][17/1:0] LOG: provided user name (anotheruser) and authenticated user name (ssltestuser) do not match 2024-03-08 04:48:44.223 UTC [39219][client backend] [[unknown]][17/1:0] LOG: certificate validation (clientcert=verify-full) failed for user "anotheruser": CN mismatch 2024-03-08 04:48:44.223 UTC [39219][client backend] [[unknown]][17/1:0] FATAL: "trust" authentication failed for user "anotheruser" 2024-03-08 04:48:44.223 UTC [39219][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-08 04:48:44.232 UTC [39221][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49360 2024-03-08 04:48:44.240 UTC [39221][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-08 04:48:44.240 UTC [39221][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-08 04:48:44.241 UTC [39221][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_OuWP/client.key$$ 2024-03-08 04:48:44.241 UTC [39221][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.009 user=yetanotheruser database=verifydb host=localhost port=49360 2024-03-08 04:48:44.245 UTC [39163][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:44.245 UTC [39163][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:44.246 UTC [39163][postmaster] LOG: background worker "logical replication launcher" (PID 39169) exited with exit code 1 2024-03-08 04:48:44.246 UTC [39164][checkpointer] LOG: shutting down 2024-03-08 04:48:44.246 UTC [39164][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:44.246 UTC [39164][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/4105148, redo lsn=0/4105148 2024-03-08 04:48:44.247 UTC [39163][postmaster] LOG: database system is shut down 2024-03-08 04:48:44.376 UTC [39223][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:44.377 UTC [39223][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:44.377 UTC [39223][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:44.380 UTC [39226][startup] LOG: database system was shut down at 2024-03-08 04:48:44 UTC 2024-03-08 04:48:44.381 UTC [39223][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:44.481 UTC [39231][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49361 2024-03-08 04:48:44.496 UTC [39231][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-08 04:48:44.496 UTC [39231][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-08 04:48:44.499 UTC [39231][client backend] [001_ssltests.pl][0/2:0] LOG: statement: SELECT $$connected with sslkey=invalid sslcert=invalid sslrootcert=invalid sslcrl=invalid sslcrldir=invalid user=ssltestuser dbname=certdb sslkey=/Users/admin/pgsql/build/testrun/ssl/001_ssltests/data/tmp_test_OuWP/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-08 04:48:44.500 UTC [39231][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.019 user=ssltestuser database=certdb host=localhost port=49361 2024-03-08 04:48:44.512 UTC [39233][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49362 2024-03-08 04:48:44.520 UTC [39233][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-08 04:48:44.520 UTC [39233][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-08 04:48:44.530 UTC [39235][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49363 2024-03-08 04:48:44.538 UTC [39235][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-08 04:48:44.538 UTC [39235][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-08 04:48:44.542 UTC [39223][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:44.542 UTC [39223][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:44.543 UTC [39223][postmaster] LOG: background worker "logical replication launcher" (PID 39229) exited with exit code 1 2024-03-08 04:48:44.543 UTC [39224][checkpointer] LOG: shutting down 2024-03-08 04:48:44.543 UTC [39224][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:44.543 UTC [39224][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/41051C0, redo lsn=0/41051C0 2024-03-08 04:48:44.544 UTC [39223][postmaster] LOG: database system is shut down 2024-03-08 04:48:44.681 UTC [39237][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:44.681 UTC [39237][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:44.681 UTC [39237][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:44.684 UTC [39240][startup] LOG: database system was shut down at 2024-03-08 04:48:44 UTC 2024-03-08 04:48:44.686 UTC [39237][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:44.779 UTC [39245][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49364 2024-03-08 04:48:44.793 UTC [39245][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-08 04:48:44.793 UTC [39245][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-08 04:48:44.798 UTC [39237][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:44.798 UTC [39237][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:44.798 UTC [39237][postmaster] LOG: background worker "logical replication launcher" (PID 39243) exited with exit code 1 2024-03-08 04:48:44.798 UTC [39238][checkpointer] LOG: shutting down 2024-03-08 04:48:44.799 UTC [39238][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:44.799 UTC [39238][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/4105238, redo lsn=0/4105238 2024-03-08 04:48:44.800 UTC [39237][postmaster] LOG: database system is shut down 2024-03-08 04:48:44.954 UTC [39247][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:44.955 UTC [39247][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:44.956 UTC [39247][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:44.959 UTC [39250][startup] LOG: database system was shut down at 2024-03-08 04:48:44 UTC 2024-03-08 04:48:44.961 UTC [39247][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:45.059 UTC [39255][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49365 2024-03-08 04:48:45.076 UTC [39255][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-08 04:48:45.076 UTC [39255][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-08 04:48:45.091 UTC [39257][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49366 2024-03-08 04:48:45.101 UTC [39257][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: certificate verify failed 2024-03-08 04:48:45.101 UTC [39257][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-08 04:48:45.106 UTC [39247][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:45.106 UTC [39247][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:45.108 UTC [39247][postmaster] LOG: background worker "logical replication launcher" (PID 39253) exited with exit code 1 2024-03-08 04:48:45.108 UTC [39248][checkpointer] LOG: shutting down 2024-03-08 04:48:45.108 UTC [39248][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:45.109 UTC [39248][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/41052B0, redo lsn=0/41052B0 2024-03-08 04:48:45.110 UTC [39247][postmaster] LOG: database system is shut down 2024-03-08 04:48:45.245 UTC [39259][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:45.245 UTC [39259][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:45.245 UTC [39259][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:45.249 UTC [39262][startup] LOG: database system was shut down at 2024-03-08 04:48:45 UTC 2024-03-08 04:48:45.250 UTC [39259][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:45.362 UTC [39267][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49367 2024-03-08 04:48:45.373 UTC [39267][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-08 04:48:45.373 UTC [39267][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-08 04:48:45.388 UTC [39269][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49368 2024-03-08 04:48:45.397 UTC [39269][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-08 04:48:45.397 UTC [39269][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-08 04:48:45.400 UTC [39269][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-08 04:48:45.400 UTC [39269][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49368 2024-03-08 04:48:45.405 UTC [39259][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:45.405 UTC [39259][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:45.405 UTC [39259][postmaster] LOG: background worker "logical replication launcher" (PID 39265) exited with exit code 1 2024-03-08 04:48:45.405 UTC [39260][checkpointer] LOG: shutting down 2024-03-08 04:48:45.406 UTC [39260][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:45.406 UTC [39260][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/4105328, redo lsn=0/4105328 2024-03-08 04:48:45.407 UTC [39259][postmaster] LOG: database system is shut down 2024-03-08 04:48:45.555 UTC [39271][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:45.555 UTC [39271][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:45.556 UTC [39271][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:45.559 UTC [39274][startup] LOG: database system was shut down at 2024-03-08 04:48:45 UTC 2024-03-08 04:48:45.561 UTC [39271][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:45.668 UTC [39279][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49369 2024-03-08 04:48:45.679 UTC [39279][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-08 04:48:45.679 UTC [39279][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-08 04:48:45.693 UTC [39281][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49370 2024-03-08 04:48:45.702 UTC [39281][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-08 04:48:45.702 UTC [39281][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-08 04:48:45.705 UTC [39281][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-08 04:48:45.705 UTC [39281][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49370 2024-03-08 04:48:45.710 UTC [39271][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:45.710 UTC [39271][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:45.710 UTC [39271][postmaster] LOG: background worker "logical replication launcher" (PID 39277) exited with exit code 1 2024-03-08 04:48:45.710 UTC [39272][checkpointer] LOG: shutting down 2024-03-08 04:48:45.710 UTC [39272][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:45.711 UTC [39272][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/41053A0, redo lsn=0/41053A0 2024-03-08 04:48:45.712 UTC [39271][postmaster] LOG: database system is shut down 2024-03-08 04:48:45.857 UTC [39283][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:45.858 UTC [39283][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:45.858 UTC [39283][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:45.861 UTC [39286][startup] LOG: database system was shut down at 2024-03-08 04:48:45 UTC 2024-03-08 04:48:45.863 UTC [39283][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:45.969 UTC [39291][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49371 2024-03-08 04:48:45.980 UTC [39291][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-08 04:48:45.980 UTC [39291][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-08 04:48:45.994 UTC [39293][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49372 2024-03-08 04:48:46.003 UTC [39293][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-08 04:48:46.003 UTC [39293][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-08 04:48:46.006 UTC [39293][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-08 04:48:46.006 UTC [39293][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49372 2024-03-08 04:48:46.011 UTC [39283][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:46.011 UTC [39283][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:46.011 UTC [39283][postmaster] LOG: background worker "logical replication launcher" (PID 39289) exited with exit code 1 2024-03-08 04:48:46.011 UTC [39284][checkpointer] LOG: shutting down 2024-03-08 04:48:46.011 UTC [39284][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:46.012 UTC [39284][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/4105418, redo lsn=0/4105418 2024-03-08 04:48:46.013 UTC [39283][postmaster] LOG: database system is shut down 2024-03-08 04:48:46.152 UTC [39295][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:46.152 UTC [39295][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:46.152 UTC [39295][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:46.155 UTC [39298][startup] LOG: database system was shut down at 2024-03-08 04:48:46 UTC 2024-03-08 04:48:46.156 UTC [39295][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:46.256 UTC [39303][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49373 2024-03-08 04:48:46.265 UTC [39303][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-08 04:48:46.265 UTC [39303][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-08 04:48:46.277 UTC [39305][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49374 2024-03-08 04:48:46.286 UTC [39305][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-08 04:48:46.286 UTC [39305][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-08 04:48:46.288 UTC [39305][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-08 04:48:46.289 UTC [39305][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49374 2024-03-08 04:48:46.293 UTC [39295][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:46.293 UTC [39295][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:46.294 UTC [39295][postmaster] LOG: background worker "logical replication launcher" (PID 39301) exited with exit code 1 2024-03-08 04:48:46.294 UTC [39296][checkpointer] LOG: shutting down 2024-03-08 04:48:46.294 UTC [39296][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:46.294 UTC [39296][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/4105490, redo lsn=0/4105490 2024-03-08 04:48:46.295 UTC [39295][postmaster] LOG: database system is shut down 2024-03-08 04:48:46.438 UTC [39307][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:46.438 UTC [39307][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:46.438 UTC [39307][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:46.442 UTC [39310][startup] LOG: database system was shut down at 2024-03-08 04:48:46 UTC 2024-03-08 04:48:46.444 UTC [39307][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:46.540 UTC [39315][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49375 2024-03-08 04:48:46.549 UTC [39315][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-08 04:48:46.549 UTC [39315][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-08 04:48:46.563 UTC [39317][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49376 2024-03-08 04:48:46.572 UTC [39317][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-08 04:48:46.572 UTC [39317][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-08 04:48:46.575 UTC [39317][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-08 04:48:46.575 UTC [39317][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49376 2024-03-08 04:48:46.579 UTC [39307][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:46.579 UTC [39307][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:46.580 UTC [39307][postmaster] LOG: background worker "logical replication launcher" (PID 39313) exited with exit code 1 2024-03-08 04:48:46.580 UTC [39308][checkpointer] LOG: shutting down 2024-03-08 04:48:46.580 UTC [39308][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:46.580 UTC [39308][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/4105508, redo lsn=0/4105508 2024-03-08 04:48:46.581 UTC [39307][postmaster] LOG: database system is shut down 2024-03-08 04:48:46.728 UTC [39319][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:46.729 UTC [39319][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:46.729 UTC [39319][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:46.732 UTC [39322][startup] LOG: database system was shut down at 2024-03-08 04:48:46 UTC 2024-03-08 04:48:46.734 UTC [39319][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:46.833 UTC [39327][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49377 2024-03-08 04:48:46.844 UTC [39327][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-08 04:48:46.844 UTC [39327][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-08 04:48:46.858 UTC [39329][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49378 2024-03-08 04:48:46.867 UTC [39329][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-08 04:48:46.867 UTC [39329][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-08 04:48:46.870 UTC [39329][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-08 04:48:46.870 UTC [39329][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49378 2024-03-08 04:48:46.874 UTC [39319][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:46.874 UTC [39319][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:46.875 UTC [39319][postmaster] LOG: background worker "logical replication launcher" (PID 39325) exited with exit code 1 2024-03-08 04:48:46.875 UTC [39320][checkpointer] LOG: shutting down 2024-03-08 04:48:46.875 UTC [39320][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:46.876 UTC [39320][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/4105580, redo lsn=0/4105580 2024-03-08 04:48:46.877 UTC [39319][postmaster] LOG: database system is shut down 2024-03-08 04:48:47.023 UTC [39331][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:47.023 UTC [39331][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:47.024 UTC [39331][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:47.027 UTC [39334][startup] LOG: database system was shut down at 2024-03-08 04:48:46 UTC 2024-03-08 04:48:47.029 UTC [39331][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:47.122 UTC [39339][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49379 2024-03-08 04:48:47.133 UTC [39339][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-08 04:48:47.133 UTC [39339][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-08 04:48:47.147 UTC [39341][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49380 2024-03-08 04:48:47.156 UTC [39341][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-08 04:48:47.156 UTC [39341][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-08 04:48:47.158 UTC [39341][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-08 04:48:47.159 UTC [39341][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.011 user=ssltestuser database=trustdb host=localhost port=49380 2024-03-08 04:48:47.163 UTC [39331][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:47.163 UTC [39331][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:47.164 UTC [39331][postmaster] LOG: background worker "logical replication launcher" (PID 39337) exited with exit code 1 2024-03-08 04:48:47.164 UTC [39332][checkpointer] LOG: shutting down 2024-03-08 04:48:47.164 UTC [39332][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:47.164 UTC [39332][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/41055F8, redo lsn=0/41055F8 2024-03-08 04:48:47.165 UTC [39331][postmaster] LOG: database system is shut down 2024-03-08 04:48:47.300 UTC [39344][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:47.301 UTC [39344][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:47.301 UTC [39344][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:47.304 UTC [39347][startup] LOG: database system was shut down at 2024-03-08 04:48:47 UTC 2024-03-08 04:48:47.306 UTC [39344][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:47.403 UTC [39352][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49381 2024-03-08 04:48:47.413 UTC [39352][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-08 04:48:47.413 UTC [39352][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-08 04:48:47.427 UTC [39354][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49382 2024-03-08 04:48:47.436 UTC [39354][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-08 04:48:47.436 UTC [39354][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-08 04:48:47.438 UTC [39354][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-08 04:48:47.439 UTC [39354][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49382 2024-03-08 04:48:47.443 UTC [39344][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:47.443 UTC [39344][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:47.444 UTC [39344][postmaster] LOG: background worker "logical replication launcher" (PID 39350) exited with exit code 1 2024-03-08 04:48:47.444 UTC [39345][checkpointer] LOG: shutting down 2024-03-08 04:48:47.444 UTC [39345][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:47.444 UTC [39345][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/4105670, redo lsn=0/4105670 2024-03-08 04:48:47.445 UTC [39344][postmaster] LOG: database system is shut down 2024-03-08 04:48:47.585 UTC [39356][postmaster] LOG: starting PostgreSQL 17devel on aarch64-darwin, compiled by clang-14.0.3, 64-bit 2024-03-08 04:48:47.585 UTC [39356][postmaster] LOG: listening on IPv4 address "127.0.0.1", port 63713 2024-03-08 04:48:47.586 UTC [39356][postmaster] LOG: listening on Unix socket "/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/LVF4IIe16b/.s.PGSQL.63713" 2024-03-08 04:48:47.589 UTC [39359][startup] LOG: database system was shut down at 2024-03-08 04:48:47 UTC 2024-03-08 04:48:47.590 UTC [39356][postmaster] LOG: database system is ready to accept connections 2024-03-08 04:48:47.695 UTC [39364][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49383 2024-03-08 04:48:47.706 UTC [39364][not initialized] [[unknown]][:0] WARNING: could not read ssl_ocsp_file 2024-03-08 04:48:47.706 UTC [39364][not initialized] [[unknown]][:0] LOG: could not accept SSL connection: Resource temporarily unavailable 2024-03-08 04:48:47.720 UTC [39366][not initialized] [[unknown]][:0] LOG: connection received: host=localhost port=49384 2024-03-08 04:48:47.729 UTC [39366][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-08 04:48:47.729 UTC [39366][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-08 04:48:47.731 UTC [39366][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-08 04:48:47.732 UTC [39366][client backend] [001_ssltests.pl][:0] LOG: disconnection: session time: 0:00:00.012 user=ssltestuser database=trustdb host=localhost port=49384 2024-03-08 04:48:47.736 UTC [39356][postmaster] LOG: received fast shutdown request 2024-03-08 04:48:47.736 UTC [39356][postmaster] LOG: aborting any active transactions 2024-03-08 04:48:47.736 UTC [39356][postmaster] LOG: background worker "logical replication launcher" (PID 39362) exited with exit code 1 2024-03-08 04:48:47.737 UTC [39357][checkpointer] LOG: shutting down 2024-03-08 04:48:47.737 UTC [39357][checkpointer] LOG: checkpoint starting: shutdown immediate 2024-03-08 04:48:47.737 UTC [39357][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/41056E8, redo lsn=0/41056E8 2024-03-08 04:48:47.738 UTC [39356][postmaster] LOG: database system is shut down 2024-03-08 04:48:47.881 UTC [39368][postmaster] FATAL: could not load server certificate file "server-ip-cn-only+server_ca.crt": No such file or directory 2024-03-08 04:48:47.881 UTC [39368][postmaster] LOG: database system is shut down # No postmaster PID for node "primary" [04:48:47.976](0.244s) Bail out! pg_ctl restart failed # No postmaster PID for node "primary"