# Checking port 51848
# Found port 51848
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=51848 host=/tmp/MUEBF7McKe
Log file: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log
[18:43:43.174](0.019s) # 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 23125
# 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/MUEBF7McKe -p 51848 --checkpoint fast --no-sync
# Backup finished
# Checking port 51849
# Found port 51849
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=51849 host=/tmp/MUEBF7McKe
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 23210
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51848" failed: FATAL:  database "postgres" does not exist
[18:43:43.545](0.371s) not ok 1 - reload node london with synchronous_standby_names = 'paris'
[18:43:43.545](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:43:43.546](0.000s) #          got: ''
#     expected: 't'
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51849" failed: FATAL:  database "postgres" does not exist
[18:43:43.555](0.009s) not ok 2 - reload node paris with synchronous_standby_names = 'london'
[18:43:43.555](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:43:43.555](0.000s) #          got: ''
#     expected: 't'
[18:43:43.555](0.000s) # Initially, london is primary and paris is standby
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51848" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51848" 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 23292
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51848" failed: FATAL:  database "postgres" does not exist
[18:43:43.900](0.344s) not ok 3 - Commit prepared transaction after restart
[18:43:43.900](0.000s) #   Failed test 'Commit prepared transaction after restart'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 85.
[18:43:43.900](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51848" failed: FATAL:  database "postgres" does not exist
[18:43:43.959](0.059s) not ok 4 - Rollback prepared transaction after restart
[18:43:43.959](0.000s) #   Failed test 'Rollback prepared transaction after restart'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 88.
[18:43:43.959](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51848" 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 23410
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51848" failed: FATAL:  database "postgres" does not exist
[18:43:44.260](0.301s) not ok 5 - Commit prepared transaction after teardown
[18:43:44.260](0.000s) #   Failed test 'Commit prepared transaction after teardown'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 113.
[18:43:44.260](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51848" failed: FATAL:  database "postgres" does not exist
[18:43:44.303](0.043s) not ok 6 - Rollback prepared transaction after teardown
[18:43:44.303](0.000s) #   Failed test 'Rollback prepared transaction after teardown'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 116.
[18:43:44.303](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51848" 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 23484
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51848" failed: FATAL:  database "postgres" does not exist
[18:43:44.609](0.306s) not ok 7 - Replay several transactions with same GID
[18:43:44.610](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:43:44.610](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51848" 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 23608
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51848" failed: FATAL:  database "postgres" does not exist
[18:43:44.914](0.304s) not ok 8 - Cleanup of shared memory state for 2PC commit
[18:43:44.914](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:43:44.914](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51848" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51848" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51849" failed: FATAL:  database "postgres" does not exist
[18:43:45.010](0.096s) not ok 9 - Cleanup of shared memory state on running standby without checkpoint
[18:43:45.010](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:43:45.010](0.000s) #          got: ''
#     expected: '0'
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51848" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51849" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51848" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51849" failed: FATAL:  database "postgres" does not exist
[18:43:45.149](0.138s) not ok 10 - Cleanup of shared memory state on running standby after checkpoint
[18:43:45.149](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:43:45.149](0.000s) #          got: ''
#     expected: '0'
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51848" 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:43:45.398](0.249s) # Now paris is primary and london is standby
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51849" failed: FATAL:  database "postgres" does not exist
[18:43:45.441](0.043s) not ok 11 - Restore of prepared transaction on promoted standby
[18:43:45.441](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:43:45.441](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 23820
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51849" 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 23917
### 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:43:46.005](0.564s) # Now london is primary and paris is standby
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51848" failed: FATAL:  database "postgres" does not exist
[18:43:46.013](0.008s) not ok 12 - Restore prepared transactions from files with primary down
[18:43:46.013](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:43:46.014](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 23983
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51848" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51848" 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 24113
### 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:43:46.636](0.622s) # Now paris is primary and london is standby
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51849" failed: FATAL:  database "postgres" does not exist
[18:43:46.644](0.008s) not ok 13 - Restore prepared transactions from records with primary down
[18:43:46.644](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:43:46.645](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 24182
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51849" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51849" failed: FATAL:  database "postgres" does not exist
connection error: 'psql: error: connection to server on socket "/tmp/MUEBF7McKe/.s.PGSQL.51849" failed: FATAL:  database "postgres" does not exist'
while running 'psql -XAtq -d port=51849 host=/tmp/MUEBF7McKe 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 24182
### 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 24113
### 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:43:47.056](0.412s) # Tests were run but no plan was declared and done_testing() was not seen.
[18:43:47.057](0.000s) # Looks like your test exited with 29 just after 13.