# Checking port 31488
# Found port 31488
Name: publisher
Version: 19devel
Data directory: /home/postgres/postgres/build/testrun/subscription/013_partition/data/t_013_partition_publisher_data/pgdata
Backup directory: /home/postgres/postgres/build/testrun/subscription/013_partition/data/t_013_partition_publisher_data/backup
Archive directory: /home/postgres/postgres/build/testrun/subscription/013_partition/data/t_013_partition_publisher_data/archives
Connection string: port=31488 host=/tmp/hRufIj9kcC
Log file: /home/postgres/postgres/build/testrun/subscription/013_partition/log/013_partition_publisher.log
[15:00:06.043](0.062s) # initializing database system by copying initdb template
# Running: cp -RPp /home/postgres/postgres/build/tmp_install/initdb-template /home/postgres/postgres/build/testrun/subscription/013_partition/data/t_013_partition_publisher_data/pgdata
# Running: /home/postgres/postgres/build/src/test/regress/pg_regress --config-auth /home/postgres/postgres/build/testrun/subscription/013_partition/data/t_013_partition_publisher_data/pgdata
### Starting node "publisher"
# Running: pg_ctl --wait --pgdata /home/postgres/postgres/build/testrun/subscription/013_partition/data/t_013_partition_publisher_data/pgdata --log /home/postgres/postgres/build/testrun/subscription/013_partition/log/013_partition_publisher.log --options --cluster-name=publisher start
waiting for server to start.... done
server started
# Postmaster PID for node "publisher" is 49396
# Checking port 31489
# Found port 31489
Name: subscriber1
Version: 19devel
Data directory: /home/postgres/postgres/build/testrun/subscription/013_partition/data/t_013_partition_subscriber1_data/pgdata
Backup directory: /home/postgres/postgres/build/testrun/subscription/013_partition/data/t_013_partition_subscriber1_data/backup
Archive directory: /home/postgres/postgres/build/testrun/subscription/013_partition/data/t_013_partition_subscriber1_data/archives
Connection string: port=31489 host=/tmp/hRufIj9kcC
Log file: /home/postgres/postgres/build/testrun/subscription/013_partition/log/013_partition_subscriber1.log
[15:00:06.825](0.782s) # initializing database system by copying initdb template
# Running: cp -RPp /home/postgres/postgres/build/tmp_install/initdb-template /home/postgres/postgres/build/testrun/subscription/013_partition/data/t_013_partition_subscriber1_data/pgdata
# Running: /home/postgres/postgres/build/src/test/regress/pg_regress --config-auth /home/postgres/postgres/build/testrun/subscription/013_partition/data/t_013_partition_subscriber1_data/pgdata
### Starting node "subscriber1"
# Running: pg_ctl --wait --pgdata /home/postgres/postgres/build/testrun/subscription/013_partition/data/t_013_partition_subscriber1_data/pgdata --log /home/postgres/postgres/build/testrun/subscription/013_partition/log/013_partition_subscriber1.log --options --cluster-name=subscriber1 start
waiting for server to start.... done
server started
# Postmaster PID for node "subscriber1" is 61654
# Checking port 31490
# Found port 31490
Name: subscriber2
Version: 19devel
Data directory: /home/postgres/postgres/build/testrun/subscription/013_partition/data/t_013_partition_subscriber2_data/pgdata
Backup directory: /home/postgres/postgres/build/testrun/subscription/013_partition/data/t_013_partition_subscriber2_data/backup
Archive directory: /home/postgres/postgres/build/testrun/subscription/013_partition/data/t_013_partition_subscriber2_data/archives
Connection string: port=31490 host=/tmp/hRufIj9kcC
Log file: /home/postgres/postgres/build/testrun/subscription/013_partition/log/013_partition_subscriber2.log
[15:00:08.081](1.256s) # initializing database system by copying initdb template
# Running: cp -RPp /home/postgres/postgres/build/tmp_install/initdb-template /home/postgres/postgres/build/testrun/subscription/013_partition/data/t_013_partition_subscriber2_data/pgdata
# Running: /home/postgres/postgres/build/src/test/regress/pg_regress --config-auth /home/postgres/postgres/build/testrun/subscription/013_partition/data/t_013_partition_subscriber2_data/pgdata
### Starting node "subscriber2"
# Running: pg_ctl --wait --pgdata /home/postgres/postgres/build/testrun/subscription/013_partition/data/t_013_partition_subscriber2_data/pgdata --log /home/postgres/postgres/build/testrun/subscription/013_partition/log/013_partition_subscriber2.log --options --cluster-name=subscriber2 start
waiting for server to start.... done
server started
# Postmaster PID for node "subscriber2" is 91494
#### Begin standard error
psql:<stdin>:1: NOTICE:  created replication slot "sub1" on publisher
#### End standard error
#### Begin standard error
psql:<stdin>:1: NOTICE:  created replication slot "sub2" on publisher
#### End standard error
Waiting for all subscriptions in "subscriber1" to synchronize data
done
Waiting for all subscriptions in "subscriber2" to synchronize data
done
Waiting for replication conn sub1's replay_lsn to pass 0/017D3E08 on publisher
done
Waiting for replication conn sub2's replay_lsn to pass 0/017D3E08 on publisher
done
[15:00:12.557](4.476s) ok 1 - inserts into tab1 and its partitions replicated
[15:00:12.806](0.249s) ok 2 - inserts into tab1_2 replicated into tab1_2_1 correctly
[15:00:12.850](0.044s) ok 3 - inserts into tab1_2 replicated into tab1_2_2 correctly
[15:00:12.904](0.054s) ok 4 - inserts into tab1_1 replicated
[15:00:12.946](0.042s) ok 5 - inserts into tab1_2 replicated
[15:00:13.242](0.296s) ok 6 - check replica insert after trigger applied on subscriber
[15:00:13.298](0.057s) ok 7 - inserts into tab1_def replicated
Waiting for replication conn sub1's replay_lsn to pass 0/017D4120 on publisher
done
Waiting for replication conn sub2's replay_lsn to pass 0/017D4120 on publisher
done
[15:00:14.278](0.980s) ok 8 - update of tab1_1, tab1_2 replicated
[15:00:14.325](0.047s) ok 9 - updates of tab1_2 replicated into tab1_2_1 correctly
[15:00:14.382](0.058s) ok 10 - updates of tab1_2 replicated into tab1_2_2 correctly
[15:00:14.424](0.042s) ok 11 - check replica update after trigger applied on subscriber
[15:00:14.458](0.034s) ok 12 - update of tab1_1 replicated
[15:00:14.485](0.027s) ok 13 - tab1_2 updated
[15:00:14.537](0.052s) ok 14 - check replica update after trigger applied on subscriber
[15:00:14.608](0.071s) ok 15 - tab1_def unchanged
Waiting for replication conn sub1's replay_lsn to pass 0/017D4300 on publisher
done
Waiting for replication conn sub2's replay_lsn to pass 0/017D4300 on publisher
done
[15:00:15.349](0.741s) ok 16 - update of tab1 (delete from tab1_def + insert into tab1_1) replicated
[15:00:15.498](0.149s) ok 17 - updates of tab1 (delete + insert) replicated into tab1_2_2 correctly
[15:00:15.604](0.107s) ok 18 - tab1_1 unchanged
[15:00:15.641](0.036s) ok 19 - insert into tab1_2 replicated
[15:00:15.680](0.040s) ok 20 - delete from tab1_def replicated
Waiting for replication conn sub1's replay_lsn to pass 0/017D4460 on publisher
done
Waiting for replication conn sub2's replay_lsn to pass 0/017D4460 on publisher
done
[15:00:16.258](0.578s) ok 21 - delete from tab1_1, tab1_2 replicated
[15:00:16.292](0.034s) ok 22 - delete from tab1_1 replicated
[15:00:16.341](0.049s) ok 23 - delete from tab1_2 replicated
Waiting for replication conn sub1's replay_lsn to pass 0/017D51B0 on publisher
done
Waiting for replication conn sub2's replay_lsn to pass 0/017D51B0 on publisher
done
[15:00:16.842](0.501s) ok 24 - truncate of tab1_2 replicated
[15:00:16.891](0.048s) ok 25 - truncate of tab1_2 replicated
Waiting for replication conn sub1's replay_lsn to pass 0/017DACB0 on publisher
done
Waiting for replication conn sub2's replay_lsn to pass 0/017DACB0 on publisher
done
[15:00:17.547](0.656s) ok 26 - truncate of tab1_1 replicated
[15:00:17.630](0.083s) ok 27 - truncate of tab1 replicated
Waiting for replication conn sub1's replay_lsn to pass 0/017DAF80 on publisher
done
Waiting for replication conn sub2's replay_lsn to pass 0/017DAF80 on publisher
done
Waiting for replication conn sub1's replay_lsn to pass 0/017DB0F0 on publisher
[15:05:04.426](286.795s) # poll_query_until timed out executing this query:
# SELECT '0/017DB0F0' <= replay_lsn AND state = 'streaming'
#          FROM pg_catalog.pg_stat_replication
#          WHERE application_name IN ('sub1', 'walreceiver')
# expecting this output:
# t
# last actual query output:
# 
# with stderr:
[15:05:04.466](0.040s) # Last pg_stat_replication contents:
# 17476|10|postgres|sub2|||-1|2026-05-06 15:00:11.326371+00||streaming|0/01834738|0/01834738|0/01834738|0/01834738||||0|async|2026-05-06 15:04:58.265007+00
[15:05:04.466](0.001s) # die: timed out waiting for catchup at /home/postgres/postgres/src/test/subscription/t/013_partition.pl line 366.
[15:05:04.467](0.000s) 1..27
timed out waiting for catchup at /home/postgres/postgres/src/test/subscription/t/013_partition.pl line 366.
# Postmaster PID for node "publisher" is 49396
### Stopping node "publisher" using mode immediate
# Running: pg_ctl --pgdata /home/postgres/postgres/build/testrun/subscription/013_partition/data/t_013_partition_publisher_data/pgdata --mode immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "publisher"
# No postmaster PID for node "subscriber1"
# Postmaster PID for node "subscriber2" is 91494
### Stopping node "subscriber2" using mode immediate
# Running: pg_ctl --pgdata /home/postgres/postgres/build/testrun/subscription/013_partition/data/t_013_partition_subscriber2_data/pgdata --mode immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "subscriber2"
[15:05:04.709](0.242s) # Looks like your test exited with 4 just after 27.