# Checking port 49493 # Found port 49493 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=49493 host=/tmp/hIwyva4HBM Log file: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log [04:17:20.060](0.015s) # 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 22683 # 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/hIwyva4HBM -p 49493 --checkpoint fast --no-sync # Backup finished # Checking port 49494 # Found port 49494 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=49494 host=/tmp/hIwyva4HBM 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 22744 psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49493" failed: FATAL: database "postgres" does not exist [04:17:20.429](0.369s) not ok 1 - reload node london with synchronous_standby_names = 'paris' [04:17:20.430](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. [04:17:20.430](0.000s) # got: '' # expected: 't' psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49494" failed: FATAL: database "postgres" does not exist [04:17:20.452](0.022s) not ok 2 - reload node paris with synchronous_standby_names = 'london' [04:17:20.452](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. [04:17:20.452](0.000s) # got: '' # expected: 't' [04:17:20.453](0.000s) # Initially, london is primary and paris is standby psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49493" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49493" 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 22867 psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49493" failed: FATAL: database "postgres" does not exist [04:17:20.911](0.459s) not ok 3 - Commit prepared transaction after restart [04:17:20.912](0.000s) # Failed test 'Commit prepared transaction after restart' # at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 85. [04:17:20.912](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49493" failed: FATAL: database "postgres" does not exist [04:17:20.955](0.043s) not ok 4 - Rollback prepared transaction after restart [04:17:20.955](0.000s) # Failed test 'Rollback prepared transaction after restart' # at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 88. [04:17:20.955](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49493" 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 22992 psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49493" failed: FATAL: database "postgres" does not exist [04:17:21.261](0.307s) not ok 5 - Commit prepared transaction after teardown [04:17:21.262](0.000s) # Failed test 'Commit prepared transaction after teardown' # at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 113. [04:17:21.262](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49493" failed: FATAL: database "postgres" does not exist [04:17:21.304](0.043s) not ok 6 - Rollback prepared transaction after teardown [04:17:21.305](0.000s) # Failed test 'Rollback prepared transaction after teardown' # at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 116. [04:17:21.305](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49493" 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 23091 psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49493" failed: FATAL: database "postgres" does not exist [04:17:21.716](0.411s) not ok 7 - Replay several transactions with same GID [04:17:21.716](0.000s) # Failed test 'Replay several transactions with same GID' # at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 140. [04:17:21.716](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49493" 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 23204 psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49493" failed: FATAL: database "postgres" does not exist [04:17:21.920](0.204s) not ok 8 - Cleanup of shared memory state for 2PC commit [04:17:21.920](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. [04:17:21.920](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49493" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49493" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49494" failed: FATAL: database "postgres" does not exist [04:17:22.033](0.113s) not ok 9 - Cleanup of shared memory state on running standby without checkpoint [04:17:22.033](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. [04:17:22.033](0.000s) # got: '' # expected: '0' psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49493" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49494" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49493" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49494" failed: FATAL: database "postgres" does not exist [04:17:22.170](0.137s) not ok 10 - Cleanup of shared memory state on running standby after checkpoint [04:17:22.170](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. [04:17:22.171](0.000s) # got: '' # expected: '0' psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49493" 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 [04:17:22.422](0.251s) # Now paris is primary and london is standby psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49494" failed: FATAL: database "postgres" does not exist [04:17:22.465](0.043s) not ok 11 - Restore of prepared transaction on promoted standby [04:17:22.465](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. [04:17:22.466](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 23406 psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49494" 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 23485 ### 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 [04:17:23.031](0.565s) # Now london is primary and paris is standby psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49493" failed: FATAL: database "postgres" does not exist [04:17:23.039](0.008s) not ok 12 - Restore prepared transactions from files with primary down [04:17:23.039](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. [04:17:23.040](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 23547 psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49493" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49493" 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 23691 ### 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 [04:17:23.779](0.739s) # Now paris is primary and london is standby psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49494" failed: FATAL: database "postgres" does not exist [04:17:23.787](0.008s) not ok 13 - Restore prepared transactions from records with primary down [04:17:23.787](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. [04:17:23.787](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 23752 psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49494" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49494" failed: FATAL: database "postgres" does not exist connection error: 'psql: error: connection to server on socket "/tmp/hIwyva4HBM/.s.PGSQL.49494" failed: FATAL: database "postgres" does not exist' while running 'psql -XAtq -d port=49494 host=/tmp/hIwyva4HBM dbname='postgres' -f - -v ON_ERROR_STOP=1' at /tmp/cirrus-ci-build/src/test/perl/PostgreSQL/Test/Cluster.pm line 2036. # Postmaster PID for node "london" is 23752 ### 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 23691 ### 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" [04:17:24.211](0.424s) # Tests were run but no plan was declared and done_testing() was not seen. [04:17:24.211](0.000s) # Looks like your test exited with 29 just after 13.