# Checking port 60039 # Found port 60039 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=60039 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/BZsclUrpZ5 Log file: /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/log/022_twophase_cascade_node_A.log [04:48:58.473](0.022s) # 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 35883 # Checking port 60040 # Found port 60040 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=60040 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/BZsclUrpZ5 Log file: /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/log/022_twophase_cascade_node_B.log [04:48:59.069](0.596s) # 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 36125 # Checking port 60041 # Found port 60041 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=60041 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/BZsclUrpZ5 Log file: /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/log/022_twophase_cascade_node_C.log [04:48:59.653](0.584s) # 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 36416 #### 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/153B8E8 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/153DF90 on node_B done [04:49:01.175](1.522s) ok 1 - Cascade setup is complete Waiting for replication conn tap_sub_B's replay_lsn to pass 0/153BA70 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/153DF90 on node_B done [04:49:01.358](0.184s) ok 2 - transaction is prepared on subscriber B [04:49:01.380](0.022s) ok 3 - transaction is prepared on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/153BAC0 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/153E130 on node_B done [04:49:01.558](0.178s) ok 4 - Row inserted via 2PC has committed on subscriber B [04:49:01.576](0.017s) not ok 5 - Row inserted via 2PC has committed on subscriber C [04:49:01.576](0.000s) # Failed test 'Row inserted via 2PC has committed on subscriber C' # at /Users/admin/pgsql/src/test/subscription/t/022_twophase_cascade.pl line 156. [04:49:01.576](0.000s) # got: '0' # expected: '1' [04:49:01.593](0.017s) ok 6 - transaction is committed on subscriber B [04:49:01.608](0.015s) ok 7 - transaction is committed on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/153BC48 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/153E188 on node_B done [04:49:01.765](0.157s) ok 8 - transaction is prepared on subscriber B [04:49:01.787](0.022s) ok 9 - transaction is prepared on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/153BC98 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/153E368 on node_B done [04:49:01.948](0.161s) ok 10 - Row inserted via 2PC is not present on subscriber B [04:49:01.971](0.023s) ok 11 - Row inserted via 2PC is not present on subscriber C [04:49:01.994](0.023s) ok 12 - transaction is ended on subscriber B [04:49:02.019](0.025s) ok 13 - transaction is ended on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/153BEB8 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/153E368 on node_B done [04:49:02.162](0.144s) ok 14 - transaction is prepared on subscriber B [04:49:02.179](0.017s) not ok 15 - transaction is prepared on subscriber C [04:49:02.179](0.000s) # Failed test 'transaction is prepared on subscriber C' # at /Users/admin/pgsql/src/test/subscription/t/022_twophase_cascade.pl line 235. [04:49:02.179](0.000s) # got: '0' # expected: '1' Waiting for replication conn tap_sub_B's replay_lsn to pass 0/153BEF0 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/153E4F0 on node_B done [04:49:02.315](0.136s) ok 16 - transaction is ended on subscriber B [04:49:02.331](0.016s) not ok 17 - transaction is ended on subscriber C [04:49:02.331](0.000s) # Failed test 'transaction is ended on subscriber C' # at /Users/admin/pgsql/src/test/subscription/t/022_twophase_cascade.pl line 249. [04:49:02.331](0.000s) # got: '1' # expected: '0' [04:49:02.346](0.015s) ok 18 - Rows committed are present on subscriber B [04:49:02.365](0.019s) not ok 19 - Rows committed are present on subscriber C [04:49:02.365](0.000s) # Failed test 'Rows committed are present on subscriber C' # at /Users/admin/pgsql/src/test/subscription/t/022_twophase_cascade.pl line 259. [04:49:02.365](0.000s) # got: '' # expected: '21' Waiting for replication conn tap_sub_B's replay_lsn to pass 0/16B6460 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/1720000 on node_B done [04:49:02.901](0.536s) ok 20 - transaction is prepared on subscriber B [04:49:02.919](0.018s) not ok 21 - transaction is prepared on subscriber C [04:49:02.919](0.000s) # Failed test 'transaction is prepared on subscriber C' # at /Users/admin/pgsql/src/test/subscription/t/022_twophase_cascade.pl line 325. [04:49:02.919](0.000s) # got: '0' # expected: '1' Waiting for replication conn tap_sub_B's replay_lsn to pass 0/16B64A8 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/1721330 on node_B done [04:49:03.196](0.277s) ok 22 - Rows inserted by 2PC have committed on subscriber B, and extra columns have local defaults [04:49:03.211](0.015s) ok 23 - Rows inserted by 2PC have committed on subscriber C, and extra columns have local defaults [04:49:03.242](0.031s) ok 24 - transaction is committed on subscriber B [04:49:03.266](0.024s) ok 25 - transaction is committed on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/185BFF0 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/178CD80 on node_B done [04:49:03.531](0.264s) ok 26 - transaction is prepared on subscriber B [04:49:03.546](0.015s) ok 27 - transaction is prepared on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/185C040 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/178CD80 on node_B done [04:49:03.687](0.141s) ok 28 - transaction is ended on subscriber B [04:49:03.711](0.024s) ok 29 - transaction is ended on subscriber C [04:49:03.738](0.027s) ok 30 - Rows committed are present on subscriber B [04:49:03.765](0.027s) ok 31 - Rows committed are present on subscriber B [04:49:03.791](0.027s) ok 32 - Rows committed are present on subscriber C [04:49:03.814](0.023s) ok 33 - Rows committed are present on subscriber C #### Begin standard error psql::1: NOTICE: dropped replication slot "tap_sub_c" on publisher #### End standard error [04:49:03.863](0.049s) ok 34 - check subscription was dropped on subscriber node C [04:49:03.882](0.019s) ok 35 - check subscription relation status was dropped on subscriber node C [04:49:03.897](0.016s) ok 36 - check replication origin was dropped on subscriber node C [04:49:03.915](0.018s) 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 [04:49:03.965](0.050s) ok 38 - check subscription was dropped on subscriber node B [04:49:03.980](0.015s) ok 39 - check subscription relation status was dropped on subscriber node B [04:49:03.995](0.015s) ok 40 - check replication origin was dropped on subscriber node B [04:49:04.010](0.014s) 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" [04:49:04.345](0.336s) 1..41 [04:49:04.347](0.001s) # Looks like you failed 5 tests of 41.