# Checking port 14155
# Found port 14155
Name: node_A
Version: 19devel
Data directory: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_030_origin_node_A_data/pgdata
Backup directory: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_030_origin_node_A_data/backup
Archive directory: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_030_origin_node_A_data/archives
Connection string: port=14155 host=/tmp/oEBcivtmA4
Log file: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/log/030_origin_node_A.log
[15:11:26.673](0.023s) # initializing database system by copying initdb template
# Running: cp -RPp /tmp/cirrus-ci-build/tmp_install/initdb-template /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_030_origin_node_A_data/pgdata
# Running: /tmp/cirrus-ci-build/src/test/subscription/../../../src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_030_origin_node_A_data/pgdata
### Starting node "node_A"
# Running: pg_ctl --wait --pgdata /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_030_origin_node_A_data/pgdata --log /tmp/cirrus-ci-build/src/test/subscription/tmp_check/log/030_origin_node_A.log --options --cluster-name=node_A start
waiting for server to start.... done
server started
# Postmaster PID for node "node_A" is 86241
# Checking port 14156
# Found port 14156
Name: node_B
Version: 19devel
Data directory: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_030_origin_node_B_data/pgdata
Backup directory: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_030_origin_node_B_data/backup
Archive directory: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_030_origin_node_B_data/archives
Connection string: port=14156 host=/tmp/oEBcivtmA4
Log file: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/log/030_origin_node_B.log
[15:11:26.892](0.219s) # initializing database system by copying initdb template
# Running: cp -RPp /tmp/cirrus-ci-build/tmp_install/initdb-template /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_030_origin_node_B_data/pgdata
# Running: /tmp/cirrus-ci-build/src/test/subscription/../../../src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_030_origin_node_B_data/pgdata
### Starting node "node_B"
# Running: pg_ctl --wait --pgdata /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_030_origin_node_B_data/pgdata --log /tmp/cirrus-ci-build/src/test/subscription/tmp_check/log/030_origin_node_B.log --options --cluster-name=node_B start
waiting for server to start.... done
server started
# Postmaster PID for node "node_B" is 86256
#### Begin standard error
psql:<stdin>:5: NOTICE:  created replication slot "tap_sub_b_a" on publisher
#### End standard error
#### Begin standard error
psql:<stdin>:5: NOTICE:  created replication slot "tap_sub_a_b" on publisher
#### End standard error
Waiting for all subscriptions in "node_A" to synchronize data
Waiting for replication conn tap_sub_A_B's replay_lsn to pass 0/017E31F0 on node_B
done
done
Waiting for all subscriptions in "node_B" to synchronize data
Waiting for replication conn tap_sub_B_A's replay_lsn to pass 0/017E2A28 on node_A
done
done
[15:11:27.620](0.728s) ok 1 - Bidirectional replication setup is complete
Waiting for replication conn tap_sub_B_A's replay_lsn to pass 0/017E2B38 on node_A
done
Waiting for replication conn tap_sub_A_B's replay_lsn to pass 0/017E33C8 on node_B
done
[15:11:27.867](0.247s) ok 2 - Inserted successfully without leading to infinite recursion in bidirectional replication setup
[15:11:27.895](0.028s) ok 3 - Inserted successfully without leading to infinite recursion in bidirectional replication setup
Waiting for replication conn tap_sub_B_A's replay_lsn to pass 0/017E2CB0 on node_A
[15:15:25.037](237.142s) # poll_query_until timed out executing this query:
# SELECT '0/017E2CB0' <= replay_lsn AND state = 'streaming'
#          FROM pg_catalog.pg_stat_replication
#          WHERE application_name IN ('tap_sub_B_A', 'walreceiver')
# expecting this output:
# t
# last actual query output:
# 
# with stderr:
[15:15:25.069](0.032s) # Last pg_stat_replication contents:
[15:15:25.069](0.000s) # die: timed out waiting for catchup at t/030_origin.pl line 99.
[15:15:25.070](0.000s) 1..3
timed out waiting for catchup at t/030_origin.pl line 99.
# Postmaster PID for node "node_A" is 86241
### Stopping node "node_A" using mode immediate
# Running: pg_ctl --pgdata /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_030_origin_node_A_data/pgdata --mode immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "node_A"
# No postmaster PID for node "node_B"
[15:15:25.180](0.110s) # Looks like your test exited with 29 just after 3.