# Checking port 61516 # Found port 61516 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=61516 host=/tmp/xZObNp6BCE Log file: /tmp/cirrus-ci-build/build/testrun/recovery/009_twophase/log/009_twophase_london.log [19:33:51.483](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 23428 # 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/xZObNp6BCE -p 61516 --checkpoint fast --no-sync # Backup finished # Checking port 61517 # Found port 61517 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=61517 host=/tmp/xZObNp6BCE 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 23518 psql: error: connection to server on socket "/tmp/xZObNp6BCE/.s.PGSQL.61516" failed: FATAL: database "postgres" does not exist [19:33:51.856](0.373s) not ok 1 - reload node london with synchronous_standby_names = 'paris' [19:33:51.856](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. [19:33:51.856](0.000s) # got: '' # expected: 't' psql: error: connection to server on socket "/tmp/xZObNp6BCE/.s.PGSQL.61517" failed: FATAL: database "postgres" does not exist [19:33:51.863](0.007s) not ok 2 - reload node paris with synchronous_standby_names = 'london' [19:33:51.863](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. [19:33:51.863](0.000s) # got: '' # expected: 't' [19:33:51.864](0.000s) # Initially, london is primary and paris is standby psql: error: connection to server on socket "/tmp/xZObNp6BCE/.s.PGSQL.61516" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/xZObNp6BCE/.s.PGSQL.61516" 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 23620 psql: error: connection to server on socket "/tmp/xZObNp6BCE/.s.PGSQL.61516" failed: FATAL: database "postgres" does not exist [19:33:52.215](0.351s) not ok 3 - Commit prepared transaction after restart [19:33:52.215](0.000s) # Failed test 'Commit prepared transaction after restart' # at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 85. [19:33:52.215](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/xZObNp6BCE/.s.PGSQL.61516" failed: FATAL: database "postgres" does not exist [19:33:52.258](0.043s) not ok 4 - Rollback prepared transaction after restart [19:33:52.258](0.000s) # Failed test 'Rollback prepared transaction after restart' # at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 88. [19:33:52.258](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/xZObNp6BCE/.s.PGSQL.61516" 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 23714 psql: error: connection to server on socket "/tmp/xZObNp6BCE/.s.PGSQL.61516" failed: FATAL: database "postgres" does not exist [19:33:52.477](0.219s) not ok 5 - Commit prepared transaction after teardown [19:33:52.478](0.000s) # Failed test 'Commit prepared transaction after teardown' # at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 113. [19:33:52.478](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/xZObNp6BCE/.s.PGSQL.61516" failed: FATAL: database "postgres" does not exist [19:33:52.521](0.043s) not ok 6 - Rollback prepared transaction after teardown [19:33:52.521](0.000s) # Failed test 'Rollback prepared transaction after teardown' # at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 116. [19:33:52.521](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/xZObNp6BCE/.s.PGSQL.61516" 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 23830 psql: error: connection to server on socket "/tmp/xZObNp6BCE/.s.PGSQL.61516" failed: FATAL: database "postgres" does not exist [19:33:52.834](0.313s) not ok 7 - Replay several transactions with same GID [19:33:52.834](0.000s) # Failed test 'Replay several transactions with same GID' # at /tmp/cirrus-ci-build/src/test/recovery/t/009_twophase.pl line 140. [19:33:52.834](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/xZObNp6BCE/.s.PGSQL.61516" 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 23934 psql: error: connection to server on socket "/tmp/xZObNp6BCE/.s.PGSQL.61516" failed: FATAL: database "postgres" does not exist [19:33:53.270](0.436s) not ok 8 - Cleanup of shared memory state for 2PC commit [19:33:53.270](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. [19:33:53.271](0.000s) # got: '2' # expected: '0' psql: error: connection to server on socket "/tmp/xZObNp6BCE/.s.PGSQL.61516" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/xZObNp6BCE/.s.PGSQL.61516" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/xZObNp6BCE/.s.PGSQL.61517" failed: FATAL: database "postgres" does not exist [19:33:53.364](0.093s) not ok 9 - Cleanup of shared memory state on running standby without checkpoint [19:33:53.364](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. [19:33:53.364](0.000s) # got: '' # expected: '0' psql: error: connection to server on socket "/tmp/xZObNp6BCE/.s.PGSQL.61516" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/xZObNp6BCE/.s.PGSQL.61517" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/xZObNp6BCE/.s.PGSQL.61516" failed: FATAL: database "postgres" does not exist psql: error: connection to server on socket "/tmp/xZObNp6BCE/.s.PGSQL.61517" failed: FATAL: database "postgres" does not exist [19:33:53.510](0.146s) not ok 10 - Cleanup of shared memory state on running standby after checkpoint [19:33:53.510](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. [19:33:53.510](0.000s) # got: '' # expected: '0' psql: error: connection to server on socket "/tmp/xZObNp6BCE/.s.PGSQL.61516" 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 [19:33:53.759](0.248s) # Now paris is primary and london is standby psql: error: connection to server on socket "/tmp/xZObNp6BCE/.s.PGSQL.61517" failed: FATAL: database "postgres" does not exist ack Broken pipe: write( 13, 'SET synchronous_commit = off; COMMIT PREPARED 'xact_009_10'' ) at /usr/share/perl5/IPC/Run/IO.pm line 549. # No postmaster PID for node "london" # Postmaster PID for node "paris" is 23518 ### 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" [19:33:53.882](0.123s) # Tests were run but no plan was declared and done_testing() was not seen. [19:33:53.882](0.000s) # Looks like your test exited with 32 just after 10.