# Checking port 56346 # Found port 56346 Name: node_A Data directory: /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/data/t_022_twophase_cascade_node_A_data/pgdata Backup directory: /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/data/t_022_twophase_cascade_node_A_data/backup Archive directory: /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/data/t_022_twophase_cascade_node_A_data/archives Connection string: port=56346 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/QU07tOsy6q Log file: /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/log/022_twophase_cascade_node_A.log [13:05:10.726](0.095s) # initializing database system by copying initdb template # Running: cp -RPp /Users/admin/pgsql/build/tmp_install/initdb-template /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/data/t_022_twophase_cascade_node_A_data/pgdata # Running: /Users/admin/pgsql/build/src/test/regress/pg_regress --config-auth /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/data/t_022_twophase_cascade_node_A_data/pgdata ### Starting node "node_A" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/data/t_022_twophase_cascade_node_A_data/pgdata -l /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/log/022_twophase_cascade_node_A.log -o --cluster-name=node_A start waiting for server to start.... done server started # Postmaster PID for node "node_A" is 32190 # Checking port 56347 # Found port 56347 Name: node_B Data directory: /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/data/t_022_twophase_cascade_node_B_data/pgdata Backup directory: /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/data/t_022_twophase_cascade_node_B_data/backup Archive directory: /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/data/t_022_twophase_cascade_node_B_data/archives Connection string: port=56347 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/QU07tOsy6q Log file: /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/log/022_twophase_cascade_node_B.log [13:05:11.448](0.722s) # initializing database system by copying initdb template # Running: cp -RPp /Users/admin/pgsql/build/tmp_install/initdb-template /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/data/t_022_twophase_cascade_node_B_data/pgdata # Running: /Users/admin/pgsql/build/src/test/regress/pg_regress --config-auth /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/data/t_022_twophase_cascade_node_B_data/pgdata ### Starting node "node_B" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/data/t_022_twophase_cascade_node_B_data/pgdata -l /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/log/022_twophase_cascade_node_B.log -o --cluster-name=node_B start waiting for server to start.... done server started # Postmaster PID for node "node_B" is 32310 # Checking port 56348 # Found port 56348 Name: node_C Data directory: /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/data/t_022_twophase_cascade_node_C_data/pgdata Backup directory: /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/data/t_022_twophase_cascade_node_C_data/backup Archive directory: /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/data/t_022_twophase_cascade_node_C_data/archives Connection string: port=56348 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/QU07tOsy6q Log file: /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/log/022_twophase_cascade_node_C.log [13:05:12.682](1.233s) # initializing database system by copying initdb template # Running: cp -RPp /Users/admin/pgsql/build/tmp_install/initdb-template /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/data/t_022_twophase_cascade_node_C_data/pgdata # Running: /Users/admin/pgsql/build/src/test/regress/pg_regress --config-auth /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/data/t_022_twophase_cascade_node_C_data/pgdata ### Starting node "node_C" # Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/data/t_022_twophase_cascade_node_C_data/pgdata -l /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/log/022_twophase_cascade_node_C.log -o --cluster-name=node_C start waiting for server to start.... done server started # Postmaster PID for node "node_C" is 32498 #### Begin standard error psql::5: NOTICE: created replication slot "tap_sub_b" on publisher #### End standard error #### Begin standard error psql::5: NOTICE: created replication slot "tap_sub_c" on publisher #### End standard error Waiting for replication conn tap_sub_B's replay_lsn to pass 0/1544E80 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/1547530 on node_B done [13:05:13.808](1.126s) ok 1 - Cascade setup is complete Waiting for replication conn tap_sub_B's replay_lsn to pass 0/1545008 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/15476B8 on node_B done [13:05:13.944](0.136s) ok 2 - transaction is prepared on subscriber B [13:05:13.955](0.011s) ok 3 - transaction is prepared on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/1545058 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/15476B8 on node_B done [13:05:14.094](0.139s) ok 4 - Row inserted via 2PC has committed on subscriber B [13:05:14.110](0.016s) ok 5 - Row inserted via 2PC has committed on subscriber C [13:05:14.125](0.015s) ok 6 - transaction is committed on subscriber B [13:05:14.140](0.015s) ok 7 - transaction is committed on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/15451E0 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/1547710 on node_B done [13:05:14.288](0.148s) ok 8 - transaction is prepared on subscriber B [13:05:14.307](0.019s) ok 9 - transaction is prepared on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/1545230 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/15478F0 on node_B done [13:05:14.473](0.165s) ok 10 - Row inserted via 2PC is not present on subscriber B [13:05:14.489](0.016s) ok 11 - Row inserted via 2PC is not present on subscriber C [13:05:14.505](0.016s) ok 12 - transaction is ended on subscriber B [13:05:14.519](0.014s) ok 13 - transaction is ended on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/1545450 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/15478F0 on node_B done [13:05:14.664](0.145s) ok 14 - transaction is prepared on subscriber B [13:05:14.682](0.018s) ok 15 - transaction is prepared on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/1545488 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/1547A78 on node_B done [13:05:14.838](0.156s) ok 16 - transaction is ended on subscriber B [13:05:14.855](0.017s) not ok 17 - transaction is ended on subscriber C [13:05:14.855](0.000s) # Failed test 'transaction is ended on subscriber C' # at /Users/admin/pgsql/src/test/subscription/t/022_twophase_cascade.pl line 249. [13:05:14.855](0.000s) # got: '1' # expected: '0' [13:05:14.872](0.017s) ok 18 - Rows committed are present on subscriber B [13:05:14.884](0.012s) ok 19 - Rows committed are present on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/16BF9E0 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/172A8D0 on node_B done [13:05:15.621](0.737s) ok 20 - transaction is prepared on subscriber B [13:05:15.643](0.022s) ok 21 - transaction is prepared on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/16BFA28 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/172A928 on node_B done [13:05:15.903](0.260s) ok 22 - Rows inserted by 2PC have committed on subscriber B, and extra columns have local defaults [13:05:15.926](0.023s) ok 23 - Rows inserted by 2PC have committed on subscriber C, and extra columns have local defaults [13:05:15.947](0.020s) ok 24 - transaction is committed on subscriber B [13:05:15.968](0.021s) ok 25 - transaction is committed on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/1865590 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/1796480 on node_B done [13:05:16.700](0.732s) ok 26 - transaction is prepared on subscriber B [13:05:16.731](0.031s) ok 27 - transaction is prepared on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/18655C8 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/1796480 on node_B done [13:05:16.869](0.138s) ok 28 - transaction is ended on subscriber B [13:05:16.880](0.011s) not ok 29 - transaction is ended on subscriber C [13:05:16.880](0.000s) # Failed test 'transaction is ended on subscriber C' # at /Users/admin/pgsql/src/test/subscription/t/022_twophase_cascade.pl line 404. [13:05:16.880](0.000s) # got: '1' # expected: '0' [13:05:16.891](0.011s) ok 30 - Rows committed are present on subscriber B [13:05:16.902](0.011s) ok 31 - Rows committed are present on subscriber B [13:05:16.913](0.011s) not ok 32 - Rows committed are present on subscriber C [13:05:16.914](0.000s) # Failed test 'Rows committed are present on subscriber C' # at /Users/admin/pgsql/src/test/subscription/t/022_twophase_cascade.pl line 416. [13:05:16.914](0.000s) # got: '0' # expected: '1' [13:05:16.925](0.011s) not ok 33 - Rows committed are present on subscriber C [13:05:16.925](0.000s) # Failed test 'Rows committed are present on subscriber C' # at /Users/admin/pgsql/src/test/subscription/t/022_twophase_cascade.pl line 418. [13:05:16.925](0.000s) # got: '2' # expected: '3' #### Begin standard error psql::1: NOTICE: dropped replication slot "tap_sub_c" on publisher #### End standard error [13:05:17.121](0.196s) ok 34 - check subscription was dropped on subscriber node C [13:05:17.136](0.015s) ok 35 - check subscription relation status was dropped on subscriber node C [13:05:17.147](0.011s) ok 36 - check replication origin was dropped on subscriber node C [13:05:17.159](0.011s) ok 37 - check replication slot was dropped on publisher node B #### Begin standard error psql::1: NOTICE: dropped replication slot "tap_sub_b" on publisher #### End standard error [13:05:17.198](0.039s) ok 38 - check subscription was dropped on subscriber node B [13:05:17.219](0.021s) ok 39 - check subscription relation status was dropped on subscriber node B [13:05:17.249](0.030s) ok 40 - check replication origin was dropped on subscriber node B [13:05:17.278](0.029s) ok 41 - check replication slot was dropped on publisher node A ### Stopping node "node_C" using mode fast # Running: pg_ctl -D /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/data/t_022_twophase_cascade_node_C_data/pgdata -m fast stop waiting for server to shut down.... done server stopped # No postmaster PID for node "node_C" ### Stopping node "node_B" using mode fast # Running: pg_ctl -D /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/data/t_022_twophase_cascade_node_B_data/pgdata -m fast stop waiting for server to shut down.... done server stopped # No postmaster PID for node "node_B" ### Stopping node "node_A" using mode fast # Running: pg_ctl -D /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/data/t_022_twophase_cascade_node_A_data/pgdata -m fast stop waiting for server to shut down.... done server stopped # No postmaster PID for node "node_A" [13:05:17.627](0.349s) 1..41 [13:05:17.628](0.001s) # Looks like you failed 4 tests of 41.