# Checking port 23774
# Found port 23774
Name: publisher
Version: 19devel
Data directory: C:\cirrus\build/testrun/subscription/013_partition\data/t_013_partition_publisher_data/pgdata
Backup directory: C:\cirrus\build/testrun/subscription/013_partition\data/t_013_partition_publisher_data/backup
Archive directory: C:\cirrus\build/testrun/subscription/013_partition\data/t_013_partition_publisher_data/archives
Connection string: port=23774 host=C:/Windows/TEMP/XaIyDbYwBk
Log file: C:\cirrus\build/testrun/subscription/013_partition\log/013_partition_publisher.log
[15:07:59.164](0.164s) # initializing database system by copying initdb template
# Running: robocopy /E /NJS /NJH /NFL /NDL /NP C:/cirrus/build/tmp_install/initdb-template C:\cirrus\build/testrun/subscription/013_partition\data/t_013_partition_publisher_data/pgdata

# Running: C:\cirrus\build\src/test\regress\pg_regress.exe --config-auth C:\cirrus\build/testrun/subscription/013_partition\data/t_013_partition_publisher_data/pgdata
### Starting node "publisher"
# Running: pg_ctl --wait --pgdata C:\cirrus\build/testrun/subscription/013_partition\data/t_013_partition_publisher_data/pgdata --log C:\cirrus\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 8440
# Checking port 23775
# Found port 23775
Name: subscriber1
Version: 19devel
Data directory: C:\cirrus\build/testrun/subscription/013_partition\data/t_013_partition_subscriber1_data/pgdata
Backup directory: C:\cirrus\build/testrun/subscription/013_partition\data/t_013_partition_subscriber1_data/backup
Archive directory: C:\cirrus\build/testrun/subscription/013_partition\data/t_013_partition_subscriber1_data/archives
Connection string: port=23775 host=C:/Windows/TEMP/XaIyDbYwBk
Log file: C:\cirrus\build/testrun/subscription/013_partition\log/013_partition_subscriber1.log
[15:08:02.004](2.840s) # initializing database system by copying initdb template
# Running: robocopy /E /NJS /NJH /NFL /NDL /NP C:/cirrus/build/tmp_install/initdb-template C:\cirrus\build/testrun/subscription/013_partition\data/t_013_partition_subscriber1_data/pgdata

# Running: C:\cirrus\build\src/test\regress\pg_regress.exe --config-auth C:\cirrus\build/testrun/subscription/013_partition\data/t_013_partition_subscriber1_data/pgdata
### Starting node "subscriber1"
# Running: pg_ctl --wait --pgdata C:\cirrus\build/testrun/subscription/013_partition\data/t_013_partition_subscriber1_data/pgdata --log C:\cirrus\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 1864
# Checking port 23776
# Found port 23776
Name: subscriber2
Version: 19devel
Data directory: C:\cirrus\build/testrun/subscription/013_partition\data/t_013_partition_subscriber2_data/pgdata
Backup directory: C:\cirrus\build/testrun/subscription/013_partition\data/t_013_partition_subscriber2_data/backup
Archive directory: C:\cirrus\build/testrun/subscription/013_partition\data/t_013_partition_subscriber2_data/archives
Connection string: port=23776 host=C:/Windows/TEMP/XaIyDbYwBk
Log file: C:\cirrus\build/testrun/subscription/013_partition\log/013_partition_subscriber2.log
[15:08:04.643](2.639s) # initializing database system by copying initdb template
# Running: robocopy /E /NJS /NJH /NFL /NDL /NP C:/cirrus/build/tmp_install/initdb-template C:\cirrus\build/testrun/subscription/013_partition\data/t_013_partition_subscriber2_data/pgdata

# Running: C:\cirrus\build\src/test\regress\pg_regress.exe --config-auth C:\cirrus\build/testrun/subscription/013_partition\data/t_013_partition_subscriber2_data/pgdata
### Starting node "subscriber2"
# Running: pg_ctl --wait --pgdata C:\cirrus\build/testrun/subscription/013_partition\data/t_013_partition_subscriber2_data/pgdata --log C:\cirrus\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 8816
#### 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/017E80B0 on publisher
done
Waiting for replication conn sub2's replay_lsn to pass 0/017E80B0 on publisher
done
[15:08:14.892](10.249s) ok 1 - inserts into tab1 and its partitions replicated
[15:08:15.026](0.134s) ok 2 - inserts into tab1_2 replicated into tab1_2_1 correctly
[15:08:15.139](0.113s) ok 3 - inserts into tab1_2 replicated into tab1_2_2 correctly
[15:08:15.360](0.222s) ok 4 - inserts into tab1_1 replicated
[15:08:15.533](0.172s) ok 5 - inserts into tab1_2 replicated
[15:08:15.788](0.255s) ok 6 - check replica insert after trigger applied on subscriber
[15:08:15.986](0.198s) ok 7 - inserts into tab1_def replicated
Waiting for replication conn sub1's replay_lsn to pass 0/017E83E8 on publisher
done
Waiting for replication conn sub2's replay_lsn to pass 0/017E83E8 on publisher
done
[15:08:17.691](1.705s) ok 8 - update of tab1_1, tab1_2 replicated
[15:08:17.837](0.146s) ok 9 - updates of tab1_2 replicated into tab1_2_1 correctly
[15:08:17.934](0.097s) ok 10 - updates of tab1_2 replicated into tab1_2_2 correctly
[15:08:18.095](0.160s) ok 11 - check replica update after trigger applied on subscriber
[15:08:18.302](0.207s) ok 12 - update of tab1_1 replicated
[15:08:18.560](0.258s) ok 13 - tab1_2 updated
[15:08:18.946](0.385s) ok 14 - check replica update after trigger applied on subscriber
[15:08:19.139](0.194s) ok 15 - tab1_def unchanged
Waiting for replication conn sub1's replay_lsn to pass 0/017E85C8 on publisher
done
Waiting for replication conn sub2's replay_lsn to pass 0/017E85C8 on publisher
done
[15:08:21.345](2.205s) ok 16 - update of tab1 (delete from tab1_def + insert into tab1_1) replicated
[15:08:21.495](0.150s) ok 17 - updates of tab1 (delete + insert) replicated into tab1_2_2 correctly
[15:08:22.001](0.506s) ok 18 - tab1_1 unchanged
[15:08:22.081](0.080s) ok 19 - insert into tab1_2 replicated
[15:08:22.201](0.120s) ok 20 - delete from tab1_def replicated
Waiting for replication conn sub1's replay_lsn to pass 0/017E8728 on publisher
done
Waiting for replication conn sub2's replay_lsn to pass 0/017E8728 on publisher
done
[15:08:24.319](2.118s) ok 21 - delete from tab1_1, tab1_2 replicated
[15:08:24.428](0.109s) ok 22 - delete from tab1_1 replicated
[15:08:24.611](0.183s) ok 23 - delete from tab1_2 replicated
Waiting for replication conn sub1's replay_lsn to pass 0/017E9478 on publisher
done
Waiting for replication conn sub2's replay_lsn to pass 0/017E9478 on publisher
done
[15:08:26.600](1.989s) ok 24 - truncate of tab1_2 replicated
[15:08:26.796](0.196s) ok 25 - truncate of tab1_2 replicated
Waiting for replication conn sub1's replay_lsn to pass 0/017EEF78 on publisher
done
Waiting for replication conn sub2's replay_lsn to pass 0/017EEF78 on publisher
done
[15:08:28.354](1.559s) ok 26 - truncate of tab1_1 replicated
[15:08:28.506](0.152s) ok 27 - truncate of tab1 replicated
Waiting for replication conn sub1's replay_lsn to pass 0/017EF248 on publisher
done
Waiting for replication conn sub2's replay_lsn to pass 0/017EF248 on publisher
done
Waiting for replication conn sub1's replay_lsn to pass 0/017EF3B8 on publisher
[15:14:53.158](384.652s) # poll_query_until timed out executing this query:
# SELECT '0/017EF3B8' <= 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:14:53.227](0.069s) # Last pg_stat_replication contents:
# 9088|10|SYSTEM|sub2|||-1|2026-05-06 15:08:12.254118+00||streaming|0/018054A8|0/018054A8|0/018054A8|0/018054A8||||0|async|2026-05-06 15:14:52.817356+00
[15:14:53.228](0.001s) # die: timed out waiting for catchup at C:/cirrus/src/test/subscription/t/013_partition.pl line 366.
[15:14:53.228](0.001s) 1..27
timed out waiting for catchup at C:/cirrus/src/test/subscription/t/013_partition.pl line 366.
# Postmaster PID for node "publisher" is 8440
### Stopping node "publisher" using mode immediate
# Running: pg_ctl --pgdata C:\cirrus\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 8816
### Stopping node "subscriber2" using mode immediate
# Running: pg_ctl --pgdata C:\cirrus\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:14:53.475](0.247s) # Looks like your test exited with 2 just after 27.