[11:52:35.226](0.015s) # setting up data directory
# Checking port 65265
# Found port 65265
Name: primary
Data directory: /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/backup
Archive directory: /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/archives
Connection string: port=65265 host=/tmp/EIcZZrwHXx
Log file: /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/log/002_scram_primary.log
[11:52:35.232](0.005s) # initializing database system by copying initdb template
# Running: cp -RPp /tmp/cirrus-ci-build/build/tmp_install/initdb-template /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata
# Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata
### Starting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/log/002_scram_primary.log -o --cluster-name=primary start
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 47904
d41d8cd98f00b204e9800998ecf8427e
### Restarting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/ssl/002_scram/log/002_scram_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-09 11:52:35.322 UTC [47904][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit
2024-03-09 11:52:35.322 UTC [47904][postmaster] LOG:  listening on Unix socket "/tmp/EIcZZrwHXx/.s.PGSQL.65265"
2024-03-09 11:52:35.324 UTC [47913][startup] LOG:  database system was shut down at 2024-03-09 11:50:23 UTC
2024-03-09 11:52:35.326 UTC [47904][postmaster] LOG:  database system is ready to accept connections
2024-03-09 11:52:35.413 UTC [47948][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-09 11:52:35.414 UTC [47948][client backend] [[unknown]][0/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata/pg_hba.conf:117)
2024-03-09 11:52:35.414 UTC [47948][client backend] [[unknown]][0/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=002_scram.pl
2024-03-09 11:52:35.418 UTC [47948][client backend] [002_scram.pl][0/2:0] LOG:  statement: select md5('')
2024-03-09 11:52:35.419 UTC [47948][client backend] [002_scram.pl][:0] LOG:  disconnection: session time: 0:00:00.006 user=postgres database=postgres host=[local]
2024-03-09 11:52:35.463 UTC [47966][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-09 11:52:35.463 UTC [47966][client backend] [[unknown]][1/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata/pg_hba.conf:117)
2024-03-09 11:52:35.463 UTC [47966][client backend] [[unknown]][1/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=002_scram.pl
2024-03-09 11:52:35.464 UTC [47966][client backend] [002_scram.pl][1/2:0] LOG:  statement: CREATE USER ssltestuser
2024-03-09 11:52:35.465 UTC [47966][client backend] [002_scram.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-09 11:52:35.519 UTC [47984][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-09 11:52:35.519 UTC [47984][client backend] [[unknown]][2/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata/pg_hba.conf:117)
2024-03-09 11:52:35.519 UTC [47984][client backend] [[unknown]][2/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=002_scram.pl
2024-03-09 11:52:35.520 UTC [47984][client backend] [002_scram.pl][2/2:0] LOG:  statement: CREATE USER md5testuser
2024-03-09 11:52:35.521 UTC [47984][client backend] [002_scram.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-09 11:52:35.558 UTC [47995][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-09 11:52:35.558 UTC [47995][client backend] [[unknown]][3/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata/pg_hba.conf:117)
2024-03-09 11:52:35.558 UTC [47995][client backend] [[unknown]][3/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=002_scram.pl
2024-03-09 11:52:35.559 UTC [47995][client backend] [002_scram.pl][3/2:0] LOG:  statement: CREATE USER anotheruser
2024-03-09 11:52:35.559 UTC [47995][client backend] [002_scram.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-09 11:52:35.600 UTC [48001][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-09 11:52:35.600 UTC [48001][client backend] [[unknown]][4/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata/pg_hba.conf:117)
2024-03-09 11:52:35.600 UTC [48001][client backend] [[unknown]][4/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=002_scram.pl
2024-03-09 11:52:35.601 UTC [48001][client backend] [002_scram.pl][4/2:0] LOG:  statement: CREATE USER yetanotheruser
2024-03-09 11:52:35.602 UTC [48001][client backend] [002_scram.pl][:0] LOG:  disconnection: session time: 0:00:00.001 user=postgres database=postgres host=[local]
2024-03-09 11:52:35.643 UTC [48014][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-09 11:52:35.643 UTC [48014][client backend] [[unknown]][5/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata/pg_hba.conf:117)
2024-03-09 11:52:35.643 UTC [48014][client backend] [[unknown]][5/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=002_scram.pl
2024-03-09 11:52:35.644 UTC [48014][client backend] [002_scram.pl][5/2:0] LOG:  statement: CREATE DATABASE trustdb
2024-03-09 11:52:35.689 UTC [48014][client backend] [002_scram.pl][:0] LOG:  disconnection: session time: 0:00:00.046 user=postgres database=postgres host=[local]
2024-03-09 11:52:35.772 UTC [48057][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-09 11:52:35.773 UTC [48057][client backend] [[unknown]][6/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata/pg_hba.conf:117)
2024-03-09 11:52:35.773 UTC [48057][client backend] [[unknown]][6/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=002_scram.pl
2024-03-09 11:52:35.773 UTC [48057][client backend] [002_scram.pl][6/2:0] LOG:  statement: CREATE DATABASE certdb
2024-03-09 11:52:35.831 UTC [48057][client backend] [002_scram.pl][:0] LOG:  disconnection: session time: 0:00:00.058 user=postgres database=postgres host=[local]
2024-03-09 11:52:35.895 UTC [48090][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-09 11:52:35.895 UTC [48090][client backend] [[unknown]][7/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata/pg_hba.conf:117)
2024-03-09 11:52:35.895 UTC [48090][client backend] [[unknown]][7/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=002_scram.pl
2024-03-09 11:52:35.896 UTC [48090][client backend] [002_scram.pl][7/2:0] LOG:  statement: CREATE DATABASE certdb_dn
2024-03-09 11:52:35.933 UTC [48090][client backend] [002_scram.pl][:0] LOG:  disconnection: session time: 0:00:00.038 user=postgres database=postgres host=[local]
2024-03-09 11:52:36.018 UTC [48123][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-09 11:52:36.019 UTC [48123][client backend] [[unknown]][8/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata/pg_hba.conf:117)
2024-03-09 11:52:36.019 UTC [48123][client backend] [[unknown]][8/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=002_scram.pl
2024-03-09 11:52:36.020 UTC [48123][client backend] [002_scram.pl][8/2:0] LOG:  statement: CREATE DATABASE certdb_dn_re
2024-03-09 11:52:36.056 UTC [48123][client backend] [002_scram.pl][:0] LOG:  disconnection: session time: 0:00:00.037 user=postgres database=postgres host=[local]
2024-03-09 11:52:36.141 UTC [48158][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-09 11:52:36.142 UTC [48158][client backend] [[unknown]][9/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata/pg_hba.conf:117)
2024-03-09 11:52:36.142 UTC [48158][client backend] [[unknown]][9/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=002_scram.pl
2024-03-09 11:52:36.143 UTC [48158][client backend] [002_scram.pl][9/2:0] LOG:  statement: CREATE DATABASE certdb_cn
2024-03-09 11:52:36.166 UTC [48158][client backend] [002_scram.pl][:0] LOG:  disconnection: session time: 0:00:00.024 user=postgres database=postgres host=[local]
2024-03-09 11:52:36.185 UTC [48171][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-09 11:52:36.185 UTC [48171][client backend] [[unknown]][10/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata/pg_hba.conf:117)
2024-03-09 11:52:36.185 UTC [48171][client backend] [[unknown]][10/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=002_scram.pl
2024-03-09 11:52:36.186 UTC [48171][client backend] [002_scram.pl][10/2:0] LOG:  statement: CREATE DATABASE verifydb
2024-03-09 11:52:36.222 UTC [48171][client backend] [002_scram.pl][:0] LOG:  disconnection: session time: 0:00:00.037 user=postgres database=postgres host=[local]
2024-03-09 11:52:36.307 UTC [48201][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-09 11:52:36.308 UTC [48201][client backend] [[unknown]][11/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata/pg_hba.conf:117)
2024-03-09 11:52:36.308 UTC [48201][client backend] [[unknown]][11/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=002_scram.pl
2024-03-09 11:52:36.308 UTC [48201][client backend] [002_scram.pl][11/2:0] LOG:  statement: SET password_encryption='scram-sha-256';
2024-03-09 11:52:36.308 UTC [48201][client backend] [002_scram.pl][11/3:0] LOG:  statement: ALTER USER ssltestuser PASSWORD 'pass';
2024-03-09 11:52:36.311 UTC [48201][client backend] [002_scram.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-09 11:52:36.355 UTC [48217][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-09 11:52:36.356 UTC [48217][client backend] [[unknown]][12/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata/pg_hba.conf:117)
2024-03-09 11:52:36.356 UTC [48217][client backend] [[unknown]][12/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=002_scram.pl
2024-03-09 11:52:36.357 UTC [48217][client backend] [002_scram.pl][12/2:0] LOG:  statement: SET password_encryption='md5';
2024-03-09 11:52:36.357 UTC [48217][client backend] [002_scram.pl][12/3:0] LOG:  statement: ALTER USER md5testuser PASSWORD 'pass';
2024-03-09 11:52:36.358 UTC [48217][client backend] [002_scram.pl][:0] LOG:  disconnection: session time: 0:00:00.002 user=postgres database=postgres host=[local]
2024-03-09 11:52:36.395 UTC [48234][not initialized] [[unknown]][:0] LOG:  connection received: host=[local]
2024-03-09 11:52:36.395 UTC [48234][client backend] [[unknown]][13/1:0] LOG:  connection authenticated: user="postgres" method=trust (/tmp/cirrus-ci-build/build/testrun/ssl/002_scram/data/t_002_scram_primary_data/pgdata/pg_hba.conf:117)
2024-03-09 11:52:36.395 UTC [48234][client backend] [[unknown]][13/1:0] LOG:  connection authorized: user=postgres database=postgres application_name=002_scram.pl
2024-03-09 11:52:36.396 UTC [48234][client backend] [002_scram.pl][13/2:0] LOG:  statement: SET password_encryption='scram-sha-256';
2024-03-09 11:52:36.396 UTC [48234][client backend] [002_scram.pl][13/3:0] LOG:  statement: ALTER USER anotheruser PASSWORD 'pass';
2024-03-09 11:52:36.399 UTC [48234][client backend] [002_scram.pl][:0] LOG:  disconnection: session time: 0:00:00.004 user=postgres database=postgres host=[local]
2024-03-09 11:52:36.436 UTC [47904][postmaster] LOG:  received fast shutdown request
2024-03-09 11:52:36.436 UTC [47904][postmaster] LOG:  aborting any active transactions
2024-03-09 11:52:36.437 UTC [47904][postmaster] LOG:  background worker "logical replication launcher" (PID 47916) exited with exit code 1
2024-03-09 11:52:36.439 UTC [47911][checkpointer] LOG:  shutting down
2024-03-09 11:52:36.439 UTC [47911][checkpointer] LOG:  checkpoint starting: shutdown immediate
2024-03-09 11:52:36.472 UTC [47911][checkpointer] LOG:  checkpoint complete: wrote 5650 buffers (34.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.032 s, sync=0.001 s, total=0.033 s; sync files=0, longest=0.000 s, average=0.000 s; distance=45479 kB, estimate=45479 kB; lsn=0/41964F8, redo lsn=0/41964F8
2024-03-09 11:52:36.481 UTC [47904][postmaster] LOG:  database system is shut down
2024-03-09 11:52:36.551 UTC [48267][postmaster] LOG:  starting PostgreSQL 17devel on x86_64-linux, compiled by gcc-10.2.1, 64-bit
2024-03-09 11:52:36.551 UTC [48267][postmaster] LOG:  could not bind IPv4 address "127.0.0.1": Address already in use
2024-03-09 11:52:36.551 UTC [48267][postmaster] HINT:  Is another postmaster already running on port 65265? If not, wait a few seconds and retry.
2024-03-09 11:52:36.551 UTC [48267][postmaster] WARNING:  could not create listen socket for "127.0.0.1"
2024-03-09 11:52:36.551 UTC [48267][postmaster] FATAL:  could not create any TCP/IP sockets
2024-03-09 11:52:36.552 UTC [48267][postmaster] LOG:  database system is shut down
# No postmaster PID for node "primary"
[11:52:36.637](1.405s) Bail out!  pg_ctl restart failed
# No postmaster PID for node "primary"