# Checking port 18681
# Found port 18681
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=18681 host=/tmp/UMeSzixEtT
Log file: /home/postgres/postgres/build/testrun/subscription/030_origin/log/030_origin_node_A.log
[15:00:39.907](0.130s) # 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 29883
# Checking port 18682
# Found port 18682
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=18682 host=/tmp/UMeSzixEtT
Log file: /home/postgres/postgres/build/testrun/subscription/030_origin/log/030_origin_node_B.log
[15:00:40.875](0.968s) # 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 11353
#### 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/017B2DF8 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/017B2630 on node_A
done
done
[15:00:43.344](2.470s) ok 1 - Bidirectional replication setup is complete
Waiting for replication conn tap_sub_B_A's replay_lsn to pass 0/017B2808 on node_A
done
Waiting for replication conn tap_sub_A_B's replay_lsn to pass 0/017B2FD0 on node_B
done
[15:00:44.486](1.141s) ok 2 - Inserted successfully without leading to infinite recursion in bidirectional replication setup
[15:00:44.530](0.045s) 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/017B28B8 on node_A
[15:05:19.106](274.575s) # poll_query_until timed out executing this query:
# SELECT '0/017B28B8' <= 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:05:19.133](0.028s) # Last pg_stat_replication contents:
[15:05:19.134](0.001s) # die: timed out waiting for catchup at /home/postgres/postgres/src/test/subscription/t/030_origin.pl line 99.
[15:05:19.134](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 29883
### 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"
[15:05:19.249](0.115s) # Looks like your test exited with 4 just after 3.