# Checking port 17555
# Found port 17555
Name: node_A
Version: 19devel
Data directory: /Users/admin/pgsql/build/testrun/subscription/030_origin/data/t_030_origin_node_A_data/pgdata
Backup directory: /Users/admin/pgsql/build/testrun/subscription/030_origin/data/t_030_origin_node_A_data/backup
Archive directory: /Users/admin/pgsql/build/testrun/subscription/030_origin/data/t_030_origin_node_A_data/archives
Connection string: port=17555 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/5G62zcStjl
Log file: /Users/admin/pgsql/build/testrun/subscription/030_origin/log/030_origin_node_A.log
[14:57:57.374](0.356s) # initializing database system by copying initdb template
# Running: cp -RPp /Users/admin/pgsql/build/tmp_install/initdb-template /Users/admin/pgsql/build/testrun/subscription/030_origin/data/t_030_origin_node_A_data/pgdata
# Running: /Users/admin/pgsql/build/src/test/regress/pg_regress --config-auth /Users/admin/pgsql/build/testrun/subscription/030_origin/data/t_030_origin_node_A_data/pgdata
### Starting node "node_A"
# Running: pg_ctl --wait --pgdata /Users/admin/pgsql/build/testrun/subscription/030_origin/data/t_030_origin_node_A_data/pgdata --log /Users/admin/pgsql/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 51900
# Checking port 17556
# Found port 17556
Name: node_B
Version: 19devel
Data directory: /Users/admin/pgsql/build/testrun/subscription/030_origin/data/t_030_origin_node_B_data/pgdata
Backup directory: /Users/admin/pgsql/build/testrun/subscription/030_origin/data/t_030_origin_node_B_data/backup
Archive directory: /Users/admin/pgsql/build/testrun/subscription/030_origin/data/t_030_origin_node_B_data/archives
Connection string: port=17556 host=/var/folders/hm/d7rr9ds96qx995ns72ry9g4m0000gn/T/5G62zcStjl
Log file: /Users/admin/pgsql/build/testrun/subscription/030_origin/log/030_origin_node_B.log
[14:57:59.073](1.699s) # initializing database system by copying initdb template
# Running: cp -RPp /Users/admin/pgsql/build/tmp_install/initdb-template /Users/admin/pgsql/build/testrun/subscription/030_origin/data/t_030_origin_node_B_data/pgdata
# Running: /Users/admin/pgsql/build/src/test/regress/pg_regress --config-auth /Users/admin/pgsql/build/testrun/subscription/030_origin/data/t_030_origin_node_B_data/pgdata
### Starting node "node_B"
# Running: pg_ctl --wait --pgdata /Users/admin/pgsql/build/testrun/subscription/030_origin/data/t_030_origin_node_B_data/pgdata --log /Users/admin/pgsql/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 52039
#### 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/017C91E8 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/017C8A20 on node_A
done
done
[14:58:02.811](3.738s) ok 1 - Bidirectional replication setup is complete
Waiting for replication conn tap_sub_B_A's replay_lsn to pass 0/017C8BF8 on node_A
done
Waiting for replication conn tap_sub_A_B's replay_lsn to pass 0/017C93C0 on node_B
done
[14:58:03.495](0.685s) ok 2 - Inserted successfully without leading to infinite recursion in bidirectional replication setup
[14:58:03.612](0.117s) 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/017C8CA8 on node_A
[15:03:04.792](301.180s) # poll_query_until timed out executing this query:
# SELECT '0/017C8CA8' <= 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:03:04.837](0.045s) # Last pg_stat_replication contents:
[15:03:04.838](0.001s) # die: timed out waiting for catchup at /Users/admin/pgsql/src/test/subscription/t/030_origin.pl line 99.
[15:03:04.839](0.001s) 1..3
timed out waiting for catchup at /Users/admin/pgsql/src/test/subscription/t/030_origin.pl line 99.
# Postmaster PID for node "node_A" is 51900
### Stopping node "node_A" using mode immediate
# Running: pg_ctl --pgdata /Users/admin/pgsql/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:03:04.972](0.133s) # Looks like your test exited with 4 just after 3.