# Checking port 29636
# Found port 29636
Name: node_A
Version: 19devel
Data directory: C:\cirrus\build/testrun/subscription/030_origin\data/t_030_origin_node_A_data/pgdata
Backup directory: C:\cirrus\build/testrun/subscription/030_origin\data/t_030_origin_node_A_data/backup
Archive directory: C:\cirrus\build/testrun/subscription/030_origin\data/t_030_origin_node_A_data/archives
Connection string: port=29636 host=C:/Windows/TEMP/epI4wb8bzl
Log file: C:\cirrus\build/testrun/subscription/030_origin\log/030_origin_node_A.log
[15:08:56.812](0.113s) # initializing database system by copying initdb template
# Running: robocopy /E /NJS /NJH /NFL /NDL /NP C:/cirrus/build/tmp_install/initdb-template C:\cirrus\build/testrun/subscription/030_origin\data/t_030_origin_node_A_data/pgdata

# Running: C:\cirrus\build\src/test\regress\pg_regress.exe --config-auth C:\cirrus\build/testrun/subscription/030_origin\data/t_030_origin_node_A_data/pgdata
### Starting node "node_A"
# Running: pg_ctl --wait --pgdata C:\cirrus\build/testrun/subscription/030_origin\data/t_030_origin_node_A_data/pgdata --log C:\cirrus\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 4504
# Checking port 29637
# Found port 29637
Name: node_B
Version: 19devel
Data directory: C:\cirrus\build/testrun/subscription/030_origin\data/t_030_origin_node_B_data/pgdata
Backup directory: C:\cirrus\build/testrun/subscription/030_origin\data/t_030_origin_node_B_data/backup
Archive directory: C:\cirrus\build/testrun/subscription/030_origin\data/t_030_origin_node_B_data/archives
Connection string: port=29637 host=C:/Windows/TEMP/epI4wb8bzl
Log file: C:\cirrus\build/testrun/subscription/030_origin\log/030_origin_node_B.log
[15:08:58.564](1.753s) # initializing database system by copying initdb template
# Running: robocopy /E /NJS /NJH /NFL /NDL /NP C:/cirrus/build/tmp_install/initdb-template C:\cirrus\build/testrun/subscription/030_origin\data/t_030_origin_node_B_data/pgdata

# Running: C:\cirrus\build\src/test\regress\pg_regress.exe --config-auth C:\cirrus\build/testrun/subscription/030_origin\data/t_030_origin_node_B_data/pgdata
### Starting node "node_B"
# Running: pg_ctl --wait --pgdata C:\cirrus\build/testrun/subscription/030_origin\data/t_030_origin_node_B_data/pgdata --log C:\cirrus\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 2244
#### 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/017C7090 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/017C68C8 on node_A
done
done
[15:09:02.429](3.865s) ok 1 - Bidirectional replication setup is complete
Waiting for replication conn tap_sub_B_A's replay_lsn to pass 0/017C6AA0 on node_A
done
Waiting for replication conn tap_sub_A_B's replay_lsn to pass 0/017C7268 on node_B
done
[15:09:03.605](1.176s) ok 2 - Inserted successfully without leading to infinite recursion in bidirectional replication setup
[15:09:03.726](0.121s) 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/017C6B50 on node_A
[15:15:15.487](371.761s) # poll_query_until timed out executing this query:
# SELECT '0/017C6B50' <= 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:15.602](0.115s) # Last pg_stat_replication contents:
[15:15:15.602](0.001s) # die: timed out waiting for catchup at C:/cirrus/src/test/subscription/t/030_origin.pl line 99.
[15:15:15.603](0.001s) 1..3
timed out waiting for catchup at C:/cirrus/src/test/subscription/t/030_origin.pl line 99.
# Postmaster PID for node "node_A" is 4504
### Stopping node "node_A" using mode immediate
# Running: pg_ctl --pgdata C:\cirrus\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:15:15.731](0.128s) # Looks like your test exited with 2 just after 3.