# Checking port 10256
# Found port 10256
Name: node_A
Version: 19devel
Data directory: /home/postgres/postgres/build/testrun/subscription/030_origin/data/t_030_origin_node_A_data/pgdata
Backup directory: /home/postgres/postgres/build/testrun/subscription/030_origin/data/t_030_origin_node_A_data/backup
Archive directory: /home/postgres/postgres/build/testrun/subscription/030_origin/data/t_030_origin_node_A_data/archives
Connection string: port=10256 host=/tmp/4_1cFGc5qw
Log file: /home/postgres/postgres/build/testrun/subscription/030_origin/log/030_origin_node_A.log
[14:54:06.756](0.017s) # initializing database system by copying initdb template
# Running: cp -RPp /home/postgres/postgres/build/tmp_install/initdb-template /home/postgres/postgres/build/testrun/subscription/030_origin/data/t_030_origin_node_A_data/pgdata
# Running: /home/postgres/postgres/build/src/test/regress/pg_regress --config-auth /home/postgres/postgres/build/testrun/subscription/030_origin/data/t_030_origin_node_A_data/pgdata
### Starting node "node_A"
# Running: pg_ctl --wait --pgdata /home/postgres/postgres/build/testrun/subscription/030_origin/data/t_030_origin_node_A_data/pgdata --log /home/postgres/postgres/build/testrun/subscription/030_origin/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 14028
# Checking port 10257
# Found port 10257
Name: node_B
Version: 19devel
Data directory: /home/postgres/postgres/build/testrun/subscription/030_origin/data/t_030_origin_node_B_data/pgdata
Backup directory: /home/postgres/postgres/build/testrun/subscription/030_origin/data/t_030_origin_node_B_data/backup
Archive directory: /home/postgres/postgres/build/testrun/subscription/030_origin/data/t_030_origin_node_B_data/archives
Connection string: port=10257 host=/tmp/4_1cFGc5qw
Log file: /home/postgres/postgres/build/testrun/subscription/030_origin/log/030_origin_node_B.log
[14:54:06.957](0.200s) # initializing database system by copying initdb template
# Running: cp -RPp /home/postgres/postgres/build/tmp_install/initdb-template /home/postgres/postgres/build/testrun/subscription/030_origin/data/t_030_origin_node_B_data/pgdata
# Running: /home/postgres/postgres/build/src/test/regress/pg_regress --config-auth /home/postgres/postgres/build/testrun/subscription/030_origin/data/t_030_origin_node_B_data/pgdata
### Starting node "node_B"
# Running: pg_ctl --wait --pgdata /home/postgres/postgres/build/testrun/subscription/030_origin/data/t_030_origin_node_B_data/pgdata --log /home/postgres/postgres/build/testrun/subscription/030_origin/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 7815
#### 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/017C9078 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/017C8A08 on node_A
done
done
[14:54:07.431](0.474s) ok 1 - Bidirectional replication setup is complete
Waiting for replication conn tap_sub_B_A's replay_lsn to pass 0/017C8B18 on node_A
done
Waiting for replication conn tap_sub_A_B's replay_lsn to pass 0/017C93B0 on node_B
done
[14:54:07.582](0.150s) ok 2 - Inserted successfully without leading to infinite recursion in bidirectional replication setup
[14:54:07.597](0.016s) 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/017C8C90 on node_A
[14:57:48.681](221.084s) # poll_query_until timed out executing this query:
# SELECT '0/017C8C90' <= 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:
[14:57:48.699](0.018s) # Last pg_stat_replication contents:
[14:57:48.702](0.002s) # die: timed out waiting for catchup at /home/postgres/postgres/src/test/subscription/t/030_origin.pl line 99.
[14:57:48.702](0.000s) 1..3
timed out waiting for catchup at /home/postgres/postgres/src/test/subscription/t/030_origin.pl line 99.
# Postmaster PID for node "node_A" is 14028
### Stopping node "node_A" using mode immediate
# Running: pg_ctl --pgdata /home/postgres/postgres/build/testrun/subscription/030_origin/data/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"
[14:57:48.812](0.110s) # Looks like your test exited with 4 just after 3.