# Checking port 62331
# Found port 62331
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=62331 host=/tmp/FEWGWwmK15
Log file: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log
[02:06:55.693](0.013s) # 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 23115
# 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/FEWGWwmK15 -p 62331 --checkpoint fast --no-sync
# Backup finished
# Checking port 62332
# Found port 62332
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=62332 host=/tmp/FEWGWwmK15
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 23229
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62331" failed: FATAL:  database "postgres" does not exist
[02:06:56.245](0.551s) not ok 1 - reload node london with synchronous_standby_names = 'paris'
[02:06:56.245](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.
[02:06:56.245](0.000s) #          got: ''
#     expected: 't'
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62332" failed: FATAL:  database "postgres" does not exist
[02:06:56.259](0.013s) not ok 2 - reload node paris with synchronous_standby_names = 'london'
[02:06:56.259](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.
[02:06:56.259](0.000s) #          got: ''
#     expected: 't'
[02:06:56.259](0.000s) # Initially, london is primary and paris is standby
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62331" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62331" 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 23340
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62331" failed: FATAL:  database "postgres" does not exist
[02:06:56.625](0.366s) not ok 3 - Commit prepared transaction after restart
[02:06:56.626](0.000s) #   Failed test 'Commit prepared transaction after restart'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 85.
[02:06:56.626](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62331" failed: FATAL:  database "postgres" does not exist
[02:06:56.678](0.052s) not ok 4 - Rollback prepared transaction after restart
[02:06:56.678](0.000s) #   Failed test 'Rollback prepared transaction after restart'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 88.
[02:06:56.678](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62331" 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 23463
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62331" failed: FATAL:  database "postgres" does not exist
[02:06:56.983](0.305s) not ok 5 - Commit prepared transaction after teardown
[02:06:56.983](0.000s) #   Failed test 'Commit prepared transaction after teardown'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 113.
[02:06:56.983](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62331" failed: FATAL:  database "postgres" does not exist
[02:06:57.026](0.043s) not ok 6 - Rollback prepared transaction after teardown
[02:06:57.027](0.000s) #   Failed test 'Rollback prepared transaction after teardown'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 116.
[02:06:57.027](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62331" 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 23581
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62331" failed: FATAL:  database "postgres" does not exist
[02:06:57.446](0.420s) not ok 7 - Replay several transactions with same GID
[02:06:57.447](0.000s) #   Failed test 'Replay several transactions with same GID'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 140.
[02:06:57.447](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62331" 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 23727
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62331" failed: FATAL:  database "postgres" does not exist
[02:06:57.861](0.414s) not ok 8 - Cleanup of shared memory state for 2PC commit
[02:06:57.861](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.
[02:06:57.861](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62331" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62331" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62332" failed: FATAL:  database "postgres" does not exist
[02:06:57.962](0.101s) not ok 9 - Cleanup of shared memory state on running standby without checkpoint
[02:06:57.963](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.
[02:06:57.963](0.000s) #          got: ''
#     expected: '0'
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62331" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62332" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62331" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62332" failed: FATAL:  database "postgres" does not exist
[02:06:58.102](0.140s) not ok 10 - Cleanup of shared memory state on running standby after checkpoint
[02:06:58.103](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.
[02:06:58.103](0.000s) #          got: ''
#     expected: '0'
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62331" 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
[02:06:58.356](0.253s) # Now paris is primary and london is standby
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62332" failed: FATAL:  database "postgres" does not exist
[02:06:58.402](0.046s) not ok 11 - Restore of prepared transaction on promoted standby
[02:06:58.402](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.
[02:06:58.402](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 23987
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62332" 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 24089
### 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
[02:06:58.980](0.578s) # Now london is primary and paris is standby
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62331" failed: FATAL:  database "postgres" does not exist
[02:06:58.990](0.010s) not ok 12 - Restore prepared transactions from files with primary down
[02:06:58.991](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.
[02:06:58.991](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 24172
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62331" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62331" 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 24300
### 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
[02:06:59.621](0.630s) # Now paris is primary and london is standby
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62332" failed: FATAL:  database "postgres" does not exist
[02:06:59.641](0.020s) not ok 13 - Restore prepared transactions from records with primary down
[02:06:59.641](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.
[02:06:59.641](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 24383
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62332" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62332" failed: FATAL:  database "postgres" does not exist
connection error: 'psql: error: connection to server on socket "/tmp/FEWGWwmK15/.s.PGSQL.62332" failed: FATAL:  database "postgres" does not exist'
while running 'psql -XAtq -d port=62332 host=/tmp/FEWGWwmK15 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 24383
### 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 24300
### 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"
[02:07:00.104](0.463s) # Tests were run but no plan was declared and done_testing() was not seen.
[02:07:00.105](0.000s) # Looks like your test exited with 29 just after 13.