# Checking port 61439
# Found port 61439
Name: publisher
Data directory: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_021_twophase_publisher_data/pgdata
Backup directory: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_021_twophase_publisher_data/backup
Archive directory: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_021_twophase_publisher_data/archives
Connection string: port=61439 host=/tmp/HoZ4GwUb3l
Log file: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/log/021_twophase_publisher.log
[13:03:32.512](0.025s) # 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_021_twophase_publisher_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_021_twophase_publisher_data/pgdata
### Starting node "publisher"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_021_twophase_publisher_data/pgdata -l /tmp/cirrus-ci-build/src/test/subscription/tmp_check/log/021_twophase_publisher.log -o --cluster-name=publisher start
waiting for server to start.... done
server started
# Postmaster PID for node "publisher" is 37003
# Checking port 61440
# Found port 61440
Name: subscriber
Data directory: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_021_twophase_subscriber_data/pgdata
Backup directory: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_021_twophase_subscriber_data/backup
Archive directory: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_021_twophase_subscriber_data/archives
Connection string: port=61440 host=/tmp/HoZ4GwUb3l
Log file: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/log/021_twophase_subscriber.log
[13:03:32.982](0.470s) # 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_021_twophase_subscriber_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_021_twophase_subscriber_data/pgdata
### Starting node "subscriber"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_021_twophase_subscriber_data/pgdata -l /tmp/cirrus-ci-build/src/test/subscription/tmp_check/log/021_twophase_subscriber.log -o --cluster-name=subscriber start
waiting for server to start.... done
server started
# Postmaster PID for node "subscriber" is 37071
#### Begin standard error
psql:<stdin>:5: NOTICE:  created replication slot "tap_sub" on publisher
#### End standard error
Waiting for all subscriptions in "subscriber" to synchronize data
Waiting for replication conn tap_sub's replay_lsn to pass 0/1552878 on publisher
done
done
Waiting for replication conn tap_sub's replay_lsn to pass 0/1552A00 on publisher
done
[13:03:34.167](1.186s) ok 1 - transaction is prepared on subscriber
Waiting for replication conn tap_sub's replay_lsn to pass 0/1552A50 on publisher
done
[13:03:34.377](0.210s) ok 2 - Row inserted via 2PC has committed on subscriber
[13:03:34.422](0.045s) ok 3 - transaction is committed on subscriber
Waiting for replication conn tap_sub's replay_lsn to pass 0/1552BD8 on publisher
done
[13:03:34.620](0.198s) ok 4 - transaction is prepared on subscriber
Waiting for replication conn tap_sub's replay_lsn to pass 0/1552C28 on publisher
done
[13:03:34.780](0.161s) ok 5 - Row inserted via 2PC is not present on subscriber
[13:03:34.832](0.052s) ok 6 - transaction is aborted on subscriber
### Stopping node "subscriber" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_021_twophase_subscriber_data/pgdata -m immediate stop
waiting for server to shut down... done
server stopped
# No postmaster PID for node "subscriber"
### Stopping node "publisher" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_021_twophase_publisher_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "publisher"
### Starting node "publisher"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_021_twophase_publisher_data/pgdata -l /tmp/cirrus-ci-build/src/test/subscription/tmp_check/log/021_twophase_publisher.log -o --cluster-name=publisher start
waiting for server to start.... done
server started
# Postmaster PID for node "publisher" is 37599
### Starting node "subscriber"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_021_twophase_subscriber_data/pgdata -l /tmp/cirrus-ci-build/src/test/subscription/tmp_check/log/021_twophase_subscriber.log -o --cluster-name=subscriber start
waiting for server to start.... done
server started
# Postmaster PID for node "subscriber" is 37683
Waiting for replication conn tap_sub's replay_lsn to pass 0/1556DD0 on publisher
done
[13:03:36.011](1.179s) ok 7 - Rows rolled back are not on the subscriber
### Stopping node "subscriber" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_021_twophase_subscriber_data/pgdata -m immediate stop
waiting for server to shut down... done
server stopped
# No postmaster PID for node "subscriber"
### Stopping node "publisher" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_021_twophase_publisher_data/pgdata -m immediate stop
waiting for server to shut down... done
server stopped
# No postmaster PID for node "publisher"
### Starting node "publisher"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_021_twophase_publisher_data/pgdata -l /tmp/cirrus-ci-build/src/test/subscription/tmp_check/log/021_twophase_publisher.log -o --cluster-name=publisher start
waiting for server to start.... done
server started
# Postmaster PID for node "publisher" is 37763
### Starting node "subscriber"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_021_twophase_subscriber_data/pgdata -l /tmp/cirrus-ci-build/src/test/subscription/tmp_check/log/021_twophase_subscriber.log -o --cluster-name=subscriber start
waiting for server to start.... done
server started
# Postmaster PID for node "subscriber" is 37822
Waiting for replication conn tap_sub's replay_lsn to pass 0/15593C8 on publisher
done
[13:03:36.958](0.947s) ok 8 - Rows inserted via 2PC are visible on the subscriber
### Stopping node "subscriber" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_021_twophase_subscriber_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "subscriber"
### Starting node "subscriber"
# Running: pg_ctl -w -D /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_021_twophase_subscriber_data/pgdata -l /tmp/cirrus-ci-build/src/test/subscription/tmp_check/log/021_twophase_subscriber.log -o --cluster-name=subscriber start
waiting for server to start.... done
server started
# Postmaster PID for node "subscriber" is 37967
Waiting for replication conn tap_sub's replay_lsn to pass 0/1559A10 on publisher
[13:07:26.159](229.201s) # poll_query_until timed out executing this query:
# SELECT '0/1559A10' <= replay_lsn AND state = 'streaming'
#          FROM pg_catalog.pg_stat_replication
#          WHERE application_name IN ('tap_sub', 'walreceiver')
# expecting this output:
# t
# last actual query output:
# 
# with stderr:
timed out waiting for catchup at t/021_twophase.pl line 205.
# Postmaster PID for node "publisher" is 37763
### Stopping node "publisher" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_021_twophase_publisher_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "publisher"
# Postmaster PID for node "subscriber" is 37967
### Stopping node "subscriber" using mode immediate
# Running: pg_ctl -D /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_021_twophase_subscriber_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "subscriber"
[13:07:26.369](0.210s) # Tests were run but no plan was declared and done_testing() was not seen.
[13:07:26.370](0.000s) # Looks like your test exited with 29 just after 8.