# Checking port 55982
# Found port 55982
Name: primary_a
Data directory: /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/data/t_007_standby_source_primary_a_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/data/t_007_standby_source_primary_a_data/backup
Archive directory: /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/data/t_007_standby_source_primary_a_data/archives
Connection string: port=55982 host=/tmp/HKvWvUF6k1
Log file: /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/log/007_standby_source_primary_a.log
[13:55:24.611](0.051s) # 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/pg_rewind/007_standby_source/data/t_007_standby_source_primary_a_data/pgdata
# Running: /tmp/cirrus-ci-build/build/src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/data/t_007_standby_source_primary_a_data/pgdata --create-role rewind_user
### Starting node "primary_a"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/data/t_007_standby_source_primary_a_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/log/007_standby_source_primary_a.log -o --cluster-name=primary_a start
waiting for server to start.... done
server started
# Postmaster PID for node "primary_a" is 48623
#### Begin standard error
psql:<stdin>:2: WARNING:  roles created by regression test cases should have names starting with "regress_"
#### End standard error
# Running: psql -q --no-psqlrc -d port=55982 host=/tmp/HKvWvUF6k1 dbname='postgres' -c CHECKPOINT
# Taking pg_basebackup my_backup from node "primary_a"
# Running: pg_basebackup -D /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/data/t_007_standby_source_primary_a_data/backup/my_backup -h /tmp/HKvWvUF6k1 -p 55982 --checkpoint fast --no-sync
# Backup finished
# Checking port 55983
# Found port 55983
Name: node_b
Data directory: /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/data/t_007_standby_source_node_b_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/data/t_007_standby_source_node_b_data/backup
Archive directory: /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/data/t_007_standby_source_node_b_data/archives
Connection string: port=55983 host=/tmp/HKvWvUF6k1
Log file: /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/log/007_standby_source_node_b.log
# Initializing node "node_b" from backup "my_backup" of node "primary_a"
### Enabling streaming replication for node "node_b"
### Starting node "node_b"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/data/t_007_standby_source_node_b_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/log/007_standby_source_node_b.log -o --cluster-name=node_b start
waiting for server to start.... done
server started
# Postmaster PID for node "node_b" is 48685
# Taking pg_basebackup my_backup from node "node_b"
# Running: pg_basebackup -D /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/data/t_007_standby_source_node_b_data/backup/my_backup -h /tmp/HKvWvUF6k1 -p 55983 --checkpoint fast --no-sync
# Backup finished
# Checking port 55984
# Found port 55984
Name: node_c
Data directory: /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/data/t_007_standby_source_node_c_data/pgdata
Backup directory: /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/data/t_007_standby_source_node_c_data/backup
Archive directory: /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/data/t_007_standby_source_node_c_data/archives
Connection string: port=55984 host=/tmp/HKvWvUF6k1
Log file: /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/log/007_standby_source_node_c.log
# Initializing node "node_c" from backup "my_backup" of node "node_b"
### Enabling streaming replication for node "node_c"
### Starting node "node_c"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/data/t_007_standby_source_node_c_data/pgdata -l /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/log/007_standby_source_node_c.log -o --cluster-name=node_c start
waiting for server to start.... done
server started
# Postmaster PID for node "node_c" is 48724
Waiting for replication conn node_b's write_lsn to pass 0/30001C8 on primary_a
[13:59:00.008](215.397s) # poll_query_until timed out executing this query:
# SELECT '0/30001C8' <= write_lsn AND state = 'streaming'
#          FROM pg_catalog.pg_stat_replication
#          WHERE application_name IN ('node_b', '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/bin/pg_rewind/t/007_standby_source.pl line 78.
# Postmaster PID for node "primary_a" is 48623
### Stopping node "primary_a" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/data/t_007_standby_source_primary_a_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "primary_a"
# Postmaster PID for node "node_b" is 48685
### Stopping node "node_b" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/data/t_007_standby_source_node_b_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "node_b"
# Postmaster PID for node "node_c" is 48724
### Stopping node "node_c" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/build/testrun/pg_rewind/007_standby_source/data/t_007_standby_source_node_c_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "node_c"