# Checking port 56111
# Found port 56111
Name: primary
Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/data/t_001_stream_rep_primary_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/data/t_001_stream_rep_primary_data/backup
Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/data/t_001_stream_rep_primary_data/archives
Connection string: port=56111 host=/tmp/8gVFKKABMj
Log file: /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/log/001_stream_rep_primary.log
[15:43:00.331](0.025s) # 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/001_stream_rep/data/t_001_stream_rep_primary_data/pgdata
# Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/data/t_001_stream_rep_primary_data/pgdata --create-role repl_role
### Starting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/data/t_001_stream_rep_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/log/001_stream_rep_primary.log -o --cluster-name=primary start
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 7435
# Taking pg_basebackup my_backup from node "primary"
# Running: pg_basebackup -D /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/data/t_001_stream_rep_primary_data/backup/my_backup -h /tmp/8gVFKKABMj -p 56111 --checkpoint fast --no-sync
# Backup finished
# Checking port 56112
# Found port 56112
Name: standby_1
Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/data/t_001_stream_rep_standby_1_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/data/t_001_stream_rep_standby_1_data/backup
Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/data/t_001_stream_rep_standby_1_data/archives
Connection string: port=56112 host=/tmp/8gVFKKABMj
Log file: /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/log/001_stream_rep_standby_1.log
# Initializing node "standby_1" from backup "my_backup" of node "primary"
### Enabling streaming replication for node "standby_1"
### Starting node "standby_1"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/data/t_001_stream_rep_standby_1_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/log/001_stream_rep_standby_1.log -o --cluster-name=standby_1 start
waiting for server to start.... done
server started
# Postmaster PID for node "standby_1" is 7707
# Taking pg_basebackup my_backup from node "standby_1"
# Running: pg_basebackup -D /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/data/t_001_stream_rep_standby_1_data/backup/my_backup -h /tmp/8gVFKKABMj -p 56112 --checkpoint fast --no-sync
# Backup finished
### Stopping node "primary" using mode fast
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/data/t_001_stream_rep_primary_data/pgdata -m fast stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "primary"
# Taking pg_basebackup my_backup_2 from node "standby_1"
# Running: pg_basebackup -D /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/data/t_001_stream_rep_standby_1_data/backup/my_backup_2 -h /tmp/8gVFKKABMj -p 56112 --checkpoint fast --no-sync
# Backup finished
### Starting node "primary"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/data/t_001_stream_rep_primary_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/log/001_stream_rep_primary.log -o --cluster-name=primary start
waiting for server to start.... done
server started
# Postmaster PID for node "primary" is 8060
# Checking port 56113
# Found port 56113
Name: standby_2
Data directory: /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/data/t_001_stream_rep_standby_2_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/data/t_001_stream_rep_standby_2_data/backup
Archive directory: /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/data/t_001_stream_rep_standby_2_data/archives
Connection string: port=56113 host=/tmp/8gVFKKABMj
Log file: /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/log/001_stream_rep_standby_2.log
# Initializing node "standby_2" from backup "my_backup" of node "standby_1"
### Enabling streaming replication for node "standby_2"
### Starting node "standby_2"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/data/t_001_stream_rep_standby_2_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/log/001_stream_rep_standby_2.log -o --cluster-name=standby_2 start
waiting for server to start.... done
server started
# Postmaster PID for node "standby_2" is 8258
Waiting for replication conn standby_1's replay_lsn to pass 0/303FC58 on primary
[15:46:42.167](221.835s) # poll_query_until timed out executing this query:
# SELECT '0/303FC58' <= replay_lsn AND state = 'streaming'
#          FROM pg_catalog.pg_stat_replication
#          WHERE application_name IN ('standby_1', 'walreceiver')
# expecting this output:
# t
# last actual query output:
# t
# with stderr:
# WARNING:  outfuncs/readfuncs failed to produce an equal rewritten parse tree
timed out waiting for catchup at /tmp/cirrus-ci-build/src/test/recovery/t/001_stream_rep.pl line 69.
# Postmaster PID for node "primary" is 8060
### Stopping node "primary" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/data/t_001_stream_rep_primary_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "primary"
# Postmaster PID for node "standby_1" is 7707
### Stopping node "standby_1" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/data/t_001_stream_rep_standby_1_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "standby_1"
# Postmaster PID for node "standby_2" is 8258
### Stopping node "standby_2" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/recovery/001_stream_rep/data/t_001_stream_rep_standby_2_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "standby_2"