# Checking port 54967
# Found port 54967
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=54967 host=/tmp/wSFqYK8Sle
Log file: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log
[06:07:56.053](0.023s) # 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 22728
# 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/wSFqYK8Sle -p 54967 --checkpoint fast --no-sync
# Backup finished
# Checking port 54968
# Found port 54968
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=54968 host=/tmp/wSFqYK8Sle
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 22859
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54967" failed: FATAL:  database "postgres" does not exist
[06:07:56.740](0.687s) not ok 1 - reload node london with synchronous_standby_names = 'paris'
[06:07:56.740](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 55.
[06:07:56.740](0.000s) #          got: ''
#     expected: 't'
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54968" failed: FATAL:  database "postgres" does not exist
[06:07:56.748](0.008s) not ok 2 - reload node paris with synchronous_standby_names = 'london'
[06:07:56.749](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 56.
[06:07:56.749](0.000s) #          got: ''
#     expected: 't'
[06:07:56.749](0.000s) # Initially, london is primary and paris is standby
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54967" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54967" 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 23001
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54967" failed: FATAL:  database "postgres" does not exist
[06:07:57.109](0.361s) not ok 3 - Commit prepared transaction after restart
[06:07:57.110](0.000s) #   Failed test 'Commit prepared transaction after restart'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 89.
[06:07:57.110](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54967" failed: FATAL:  database "postgres" does not exist
[06:07:57.153](0.043s) not ok 4 - Rollback prepared transaction after restart
[06:07:57.153](0.000s) #   Failed test 'Rollback prepared transaction after restart'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 92.
[06:07:57.153](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54967" 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 23109
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54967" failed: FATAL:  database "postgres" does not exist
[06:07:57.461](0.308s) not ok 5 - Commit prepared transaction after teardown
[06:07:57.461](0.000s) #   Failed test 'Commit prepared transaction after teardown'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 117.
[06:07:57.461](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54967" failed: FATAL:  database "postgres" does not exist
[06:07:57.507](0.045s) not ok 6 - Rollback prepared transaction after teardown
[06:07:57.507](0.000s) #   Failed test 'Rollback prepared transaction after teardown'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 120.
[06:07:57.507](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54967" 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 23230
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54967" failed: FATAL:  database "postgres" does not exist
[06:07:57.914](0.407s) not ok 7 - Replay several transactions with same GID
[06:07:57.914](0.000s) #   Failed test 'Replay several transactions with same GID'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 144.
[06:07:57.914](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54967" 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 23360
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54967" failed: FATAL:  database "postgres" does not exist
[06:07:58.321](0.407s) not ok 8 - Cleanup of shared memory state for 2PC commit
[06:07:58.321](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 170.
[06:07:58.322](0.000s) #          got: '2'
#     expected: '0'
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54967" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54967" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54968" failed: FATAL:  database "postgres" does not exist
[06:07:58.416](0.094s) not ok 9 - Cleanup of shared memory state on running standby without checkpoint
[06:07:58.416](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 190.
[06:07:58.416](0.000s) #          got: ''
#     expected: '0'
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54967" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54968" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54967" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54968" failed: FATAL:  database "postgres" does not exist
[06:07:58.634](0.218s) not ok 10 - Cleanup of shared memory state on running standby after checkpoint
[06:07:58.634](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 211.
[06:07:58.635](0.000s) #          got: ''
#     expected: '0'
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54967" 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
[06:07:58.966](0.331s) # Now paris is primary and london is standby
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54968" failed: FATAL:  database "postgres" does not exist
[06:07:59.009](0.043s) not ok 11 - Restore of prepared transaction on promoted standby
[06:07:59.009](0.000s) #   Failed test 'Restore of prepared transaction on promoted standby'
#   at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 237.
[06:07:59.009](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 23648
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54968" 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 23769
### 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
[06:07:59.578](0.569s) # Now london is primary and paris is standby
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54967" failed: FATAL:  database "postgres" does not exist
[06:07:59.587](0.009s) not ok 12 - Restore prepared transactions from files with primary down
[06:07:59.587](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 270.
[06:07:59.587](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 23853
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54967" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54967" 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 23970
### 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
[06:08:00.161](0.574s) # Now paris is primary and london is standby
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54968" failed: FATAL:  database "postgres" does not exist
[06:08:00.177](0.015s) not ok 13 - Restore prepared transactions from records with primary down
[06:08:00.177](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 306.
[06:08:00.177](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 24037
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54968" failed: FATAL:  database "postgres" does not exist
psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54968" failed: FATAL:  database "postgres" does not exist
connection error: 'psql: error: connection to server on socket "/tmp/wSFqYK8Sle/.s.PGSQL.54968" failed: FATAL:  database "postgres" does not exist'
while running 'psql -XAtq -d port=54968 host=/tmp/wSFqYK8Sle 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 24037
### 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 23970
### 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"
[06:08:00.682](0.505s) # Tests were run but no plan was declared and done_testing() was not seen.
[06:08:00.682](0.000s) # Looks like your test exited with 29 just after 13.