# Checking port 63273
# Found port 63273
Name: london
Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/backup
Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/archives
Connection string: port=63273 host=/tmp/YrNrUZ7e92
Log file: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log
[18:47:27.655](0.078s) # 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/recovery/009_twophase/data/t_009_twophase_london_data/pgdata
# Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata
### Starting node "london"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log -o --cluster-name=london start
pg_ctl: another server might be running; trying to start server anyway
waiting for server to start.... done
server started
# Postmaster PID for node "london" is 21384
# Taking pg_basebackup london_backup from node "london"
# Running: pg_basebackup -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/backup/london_backup -h /tmp/YrNrUZ7e92 -p 63273 --checkpoint fast --no-sync
# Backup finished
# Checking port 63274
# Found port 63274
Name: paris
Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_paris_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_paris_data/backup
Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_paris_data/archives
Connection string: port=63274 host=/tmp/YrNrUZ7e92
Log file: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_paris.log
# Initializing node "paris" from backup "london_backup" of node "london"
### Enabling streaming replication for node "paris"
### Starting node "paris"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_paris_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_paris.log -o --cluster-name=paris start
waiting for server to start.... done
server started
# Postmaster PID for node "paris" is 21503
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63273" failed: FATAL:  database "postgres" does not exist
[18:47:28.137](0.482s) not ok 1 - reload node london with synchronous_standby_names = 'paris'
[18:47:28.137](0.000s) #   Failed test 'reload node london with synchronous_standby_names = 'paris''
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 51.
[18:47:28.137](0.000s) #          got: ''
#     expected: 't'
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63274" failed: FATAL:  database "postgres" does not exist
[18:47:28.166](0.029s) not ok 2 - reload node paris with synchronous_standby_names = 'london'
[18:47:28.167](0.000s) #   Failed test 'reload node paris with synchronous_standby_names = 'london''
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 52.
[18:47:28.167](0.000s) #          got: ''
#     expected: 't'
[18:47:28.167](0.000s) # Initially, london is primary and paris is standby
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63273" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63273" failed: FATAL:  database "postgres" does not exist
### Stopping node "london" using mode fast
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -m fast stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "london"
### Starting node "london"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log -o --cluster-name=london start
waiting for server to start.... done
server started
# Postmaster PID for node "london" is 21626
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63273" failed: FATAL:  database "postgres" does not exist
[18:47:28.531](0.364s) not ok 3 - Commit prepared transaction after restart
[18:47:28.531](0.000s) #   Failed test 'Commit prepared transaction after restart'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 85.
[18:47:28.531](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63273" failed: FATAL:  database "postgres" does not exist
[18:47:28.574](0.043s) not ok 4 - Rollback prepared transaction after restart
[18:47:28.575](0.000s) #   Failed test 'Rollback prepared transaction after restart'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 88.
[18:47:28.575](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63273" failed: FATAL:  database "postgres" does not exist
### Stopping node "london" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -m immediate stop
waiting for server to shut down... done
server stopped
# No postmaster PID for node "london"
### Starting node "london"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log -o --cluster-name=london start
waiting for server to start.... done
server started
# Postmaster PID for node "london" is 21707
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63273" failed: FATAL:  database "postgres" does not exist
[18:47:28.781](0.206s) not ok 5 - Commit prepared transaction after teardown
[18:47:28.781](0.000s) #   Failed test 'Commit prepared transaction after teardown'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 113.
[18:47:28.781](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63273" failed: FATAL:  database "postgres" does not exist
[18:47:28.825](0.044s) not ok 6 - Rollback prepared transaction after teardown
[18:47:28.825](0.000s) #   Failed test 'Rollback prepared transaction after teardown'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 116.
[18:47:28.825](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63273" failed: FATAL:  database "postgres" does not exist
### Stopping node "london" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "london"
### Starting node "london"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log -o --cluster-name=london start
waiting for server to start.... done
server started
# Postmaster PID for node "london" is 21833
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63273" failed: FATAL:  database "postgres" does not exist
[18:47:29.251](0.426s) not ok 7 - Replay several transactions with same GID
[18:47:29.252](0.000s) #   Failed test 'Replay several transactions with same GID'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 140.
[18:47:29.252](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63273" failed: FATAL:  database "postgres" does not exist
### Stopping node "london" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "london"
### Starting node "london"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log -o --cluster-name=london start
waiting for server to start.... done
server started
# Postmaster PID for node "london" is 21968
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63273" failed: FATAL:  database "postgres" does not exist
[18:47:29.814](0.562s) not ok 8 - Cleanup of shared memory state for 2PC commit
[18:47:29.814](0.000s) #   Failed test 'Cleanup of shared memory state for 2PC commit'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 166.
[18:47:29.815](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63273" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63273" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63274" failed: FATAL:  database "postgres" does not exist
[18:47:29.914](0.099s) not ok 9 - Cleanup of shared memory state on running standby without checkpoint
[18:47:29.914](0.000s) #   Failed test 'Cleanup of shared memory state on running standby without checkpoint'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 186.
[18:47:29.914](0.000s) #          got: ''
#     expected: '0'
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63273" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63274" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63273" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63274" failed: FATAL:  database "postgres" does not exist
[18:47:30.069](0.155s) not ok 10 - Cleanup of shared memory state on running standby after checkpoint
[18:47:30.069](0.000s) #   Failed test 'Cleanup of shared memory state on running standby after checkpoint'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 207.
[18:47:30.069](0.000s) #          got: ''
#     expected: '0'
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63273" failed: FATAL:  database "postgres" does not exist
### Stopping node "london" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "london"
### Promoting node "paris"
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_paris_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_paris.log promote
waiting for server to promote.... done
server promoted
[18:47:30.317](0.248s) # Now paris is primary and london is standby
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63274" failed: FATAL:  database "postgres" does not exist
[18:47:30.361](0.043s) not ok 11 - Restore of prepared transaction on promoted standby
[18:47:30.361](0.000s) #   Failed test 'Restore of prepared transaction on promoted standby'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 233.
[18:47:30.361](0.000s) #          got: '2'
#     expected: '0'
### Enabling streaming replication for node "london"
### Starting node "london"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log -o --cluster-name=london start
waiting for server to start.... done
server started
# Postmaster PID for node "london" is 22220
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63274" failed: FATAL:  database "postgres" does not exist
### Stopping node "paris" using mode fast
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_paris_data/pgdata -m fast stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "paris"
### Restarting node "london"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log restart
waiting for server to shut down.... done
server stopped
waiting for server to start.... done
server started
# Postmaster PID for node "london" is 22328
### Promoting node "london"
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log promote
waiting for server to promote.... done
server promoted
[18:47:30.934](0.573s) # Now london is primary and paris is standby
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63273" failed: FATAL:  database "postgres" does not exist
[18:47:30.946](0.011s) not ok 12 - Restore prepared transactions from files with primary down
[18:47:30.946](0.000s) #   Failed test 'Restore prepared transactions from files with primary down'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 266.
[18:47:30.946](0.000s) #          got: ''
#     expected: '1'
### Enabling streaming replication for node "paris"
### Starting node "paris"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_paris_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_paris.log -o --cluster-name=paris start
waiting for server to start.... done
server started
# Postmaster PID for node "paris" is 22405
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63273" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63273" failed: FATAL:  database "postgres" does not exist
### Stopping node "london" using mode fast
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -m fast stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "london"
### Stopping node "paris" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_paris_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "paris"
### Starting node "paris"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_paris_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_paris.log -o --cluster-name=paris start
waiting for server to start.... done
server started
# Postmaster PID for node "paris" is 22538
### Promoting node "paris"
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_paris_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_paris.log promote
waiting for server to promote.... done
server promoted
[18:47:31.576](0.630s) # Now paris is primary and london is standby
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63274" failed: FATAL:  database "postgres" does not exist
[18:47:31.607](0.031s) not ok 13 - Restore prepared transactions from records with primary down
[18:47:31.608](0.000s) #   Failed test 'Restore prepared transactions from records with primary down'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 302.
[18:47:31.608](0.000s) #          got: ''
#     expected: '1'
### Enabling streaming replication for node "london"
### Starting node "london"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log -o --cluster-name=london start
waiting for server to start.... done
server started
# Postmaster PID for node "london" is 22620
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63274" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63274" failed: FATAL:  database "postgres" does not exist
connection error: 'psql: error: connection to server on socket "/tmp/YrNrUZ7e92/.s.PGSQL.63274" failed: FATAL:  database "postgres" does not exist'
while running 'psql -XAtq -d port=63274 host=/tmp/YrNrUZ7e92 dbname='postgres' -f - -v ON_ERROR_STOP=1' at /tmp/cirrus-ci-build/src/test/perl/PostgreSQL/Test/Cluster.pm line 2043.
# Postmaster PID for node "london" is 22620
### Stopping node "london" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_london_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "london"
# Postmaster PID for node "paris" is 22538
### Stopping node "paris" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/data/t_009_twophase_paris_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "paris"
[18:47:32.059](0.451s) # Tests were run but no plan was declared and done_testing() was not seen.
[18:47:32.059](0.000s) # Looks like your test exited with 29 just after 13.