# Checking port 64545 # Found port 64545 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=64545 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/xnwgjc_qkH Log file: /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/log/022_twophase_cascade_node_A.log [10:13:24.063](0.025s) # 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 31491 # Checking port 64546 # Found port 64546 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=64546 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/xnwgjc_qkH Log file: /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/log/022_twophase_cascade_node_B.log [10:13:24.722](0.659s) # 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 31692 # Checking port 64547 # Found port 64547 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=64547 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/xnwgjc_qkH Log file: /Users/admin/pgsql/build/testrun/subscription/022_twophase_cascade/log/022_twophase_cascade_node_C.log [10:13:25.196](0.474s) # 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 31916 #### 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/1546AD8 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/1549118 on node_B done [10:13:26.232](1.036s) ok 1 - Cascade setup is complete Waiting for replication conn tap_sub_B's replay_lsn to pass 0/1546C60 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/15492A0 on node_B done [10:13:26.407](0.174s) ok 2 - transaction is prepared on subscriber B [10:13:26.426](0.019s) ok 3 - transaction is prepared on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/1546CB0 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/15492A0 on node_B done [10:13:26.548](0.122s) ok 4 - Row inserted via 2PC has committed on subscriber B [10:13:26.561](0.013s) ok 5 - Row inserted via 2PC has committed on subscriber C [10:13:26.577](0.016s) ok 6 - transaction is committed on subscriber B [10:13:26.596](0.019s) ok 7 - transaction is committed on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/1546E38 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/1549480 on node_B done [10:13:26.836](0.240s) ok 8 - transaction is prepared on subscriber B [10:13:26.864](0.028s) ok 9 - transaction is prepared on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/1546E88 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/15494D8 on node_B done [10:13:27.047](0.183s) ok 10 - Row inserted via 2PC is not present on subscriber B [10:13:27.065](0.018s) ok 11 - Row inserted via 2PC is not present on subscriber C [10:13:27.088](0.023s) ok 12 - transaction is ended on subscriber B [10:13:27.107](0.018s) ok 13 - transaction is ended on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/15470A8 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/1549660 on node_B done [10:13:27.299](0.193s) ok 14 - transaction is prepared on subscriber B [10:13:27.331](0.032s) ok 15 - transaction is prepared on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/15470E0 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/15496B8 on node_B done [10:13:27.470](0.138s) ok 16 - transaction is ended on subscriber B [10:13:27.494](0.024s) ok 17 - transaction is ended on subscriber C [10:13:27.517](0.023s) ok 18 - Rows committed are present on subscriber B [10:13:27.531](0.014s) ok 19 - Rows committed are present on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/16C1638 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/172C4B8 on node_B done [10:13:28.220](0.689s) ok 20 - transaction is prepared on subscriber B [10:13:28.235](0.014s) ok 21 - transaction is prepared on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/16C1680 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/172C4B8 on node_B done [10:13:28.364](0.130s) ok 22 - Rows inserted by 2PC have committed on subscriber B, and extra columns have local defaults [10:13:28.382](0.018s) ok 23 - Rows inserted by 2PC have committed on subscriber C, and extra columns have local defaults [10:13:28.399](0.017s) ok 24 - transaction is committed on subscriber B [10:13:28.416](0.018s) ok 25 - transaction is committed on subscriber C Waiting for replication conn tap_sub_B's replay_lsn to pass 0/18671E8 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/1790000 on node_B done [10:13:28.880](0.464s) ok 26 - transaction is prepared on subscriber B [10:13:28.907](0.027s) not ok 27 - transaction is prepared on subscriber C [10:13:28.907](0.000s) # Failed test 'transaction is prepared on subscriber C' # at /Users/admin/pgsql/src/test/subscription/t/022_twophase_cascade.pl line 390. [10:13:28.907](0.000s) # got: '0' # expected: '1' Waiting for replication conn tap_sub_B's replay_lsn to pass 0/1867220 on node_A done Waiting for replication conn tap_sub_C's replay_lsn to pass 0/17980C0 on node_B done [10:13:29.049](0.142s) ok 28 - transaction is ended on subscriber B [10:13:29.064](0.015s) ok 29 - transaction is ended on subscriber C [10:13:29.085](0.021s) ok 30 - Rows committed are present on subscriber B [10:13:29.102](0.016s) ok 31 - Rows committed are present on subscriber B [10:13:29.120](0.018s) ok 32 - Rows committed are present on subscriber C [10:13:29.136](0.017s) 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 [10:13:29.174](0.037s) ok 34 - check subscription was dropped on subscriber node C [10:13:29.186](0.013s) ok 35 - check subscription relation status was dropped on subscriber node C [10:13:29.198](0.012s) ok 36 - check replication origin was dropped on subscriber node C [10:13:29.210](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 [10:13:29.250](0.040s) ok 38 - check subscription was dropped on subscriber node B [10:13:29.271](0.021s) ok 39 - check subscription relation status was dropped on subscriber node B [10:13:29.285](0.014s) ok 40 - check replication origin was dropped on subscriber node B [10:13:29.298](0.013s) 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" [10:13:29.626](0.329s) 1..41 [10:13:29.628](0.002s) # Looks like you failed 1 test of 41.