# Checking port 29595
# Found port 29595
Name: publisher
Version: 19devel
Data directory: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_013_partition_publisher_data/pgdata
Backup directory: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_013_partition_publisher_data/backup
Archive directory: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_013_partition_publisher_data/archives
Connection string: port=29595 host=/tmp/NfIJL5o9tT
Log file: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/log/013_partition_publisher.log
[14:59:16.881](0.022s) # initializing database system by copying initdb template
# Running: cp -RPp /tmp/cirrus-ci-build/tmp_install/initdb-template /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_013_partition_publisher_data/pgdata
# Running: /tmp/cirrus-ci-build/src/test/subscription/../../../src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_013_partition_publisher_data/pgdata
### Starting node "publisher"
# Running: pg_ctl --wait --pgdata /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_013_partition_publisher_data/pgdata --log /tmp/cirrus-ci-build/src/test/subscription/tmp_check/log/013_partition_publisher.log --options --cluster-name=publisher start
waiting for server to start.... done
server started
# Postmaster PID for node "publisher" is 66518
# Checking port 29596
# Found port 29596
Name: subscriber1
Version: 19devel
Data directory: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_013_partition_subscriber1_data/pgdata
Backup directory: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_013_partition_subscriber1_data/backup
Archive directory: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_013_partition_subscriber1_data/archives
Connection string: port=29596 host=/tmp/NfIJL5o9tT
Log file: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/log/013_partition_subscriber1.log
[14:59:17.109](0.227s) # initializing database system by copying initdb template
# Running: cp -RPp /tmp/cirrus-ci-build/tmp_install/initdb-template /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_013_partition_subscriber1_data/pgdata
# Running: /tmp/cirrus-ci-build/src/test/subscription/../../../src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_013_partition_subscriber1_data/pgdata
### Starting node "subscriber1"
# Running: pg_ctl --wait --pgdata /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_013_partition_subscriber1_data/pgdata --log /tmp/cirrus-ci-build/src/test/subscription/tmp_check/log/013_partition_subscriber1.log --options --cluster-name=subscriber1 start
waiting for server to start.... done
server started
# Postmaster PID for node "subscriber1" is 66545
# Checking port 29597
# Found port 29597
Name: subscriber2
Version: 19devel
Data directory: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_013_partition_subscriber2_data/pgdata
Backup directory: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_013_partition_subscriber2_data/backup
Archive directory: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_013_partition_subscriber2_data/archives
Connection string: port=29597 host=/tmp/NfIJL5o9tT
Log file: /tmp/cirrus-ci-build/src/test/subscription/tmp_check/log/013_partition_subscriber2.log
[14:59:17.337](0.229s) # initializing database system by copying initdb template
# Running: cp -RPp /tmp/cirrus-ci-build/tmp_install/initdb-template /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_013_partition_subscriber2_data/pgdata
# Running: /tmp/cirrus-ci-build/src/test/subscription/../../../src/test/regress/pg_regress --config-auth /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_013_partition_subscriber2_data/pgdata
### Starting node "subscriber2"
# Running: pg_ctl --wait --pgdata /tmp/cirrus-ci-build/src/test/subscription/tmp_check/t_013_partition_subscriber2_data/pgdata --log /tmp/cirrus-ci-build/src/test/subscription/tmp_check/log/013_partition_subscriber2.log --options --cluster-name=subscriber2 start
waiting for server to start.... done
server started
# Postmaster PID for node "subscriber2" is 66573
#### 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/01805258 on publisher
done
Waiting for replication conn sub2's replay_lsn to pass 0/01805258 on publisher
done
[14:59:19.189](1.851s) ok 1 - inserts into tab1 and its partitions replicated
[14:59:19.219](0.031s) ok 2 - inserts into tab1_2 replicated into tab1_2_1 correctly
[14:59:19.261](0.041s) ok 3 - inserts into tab1_2 replicated into tab1_2_2 correctly
[14:59:19.291](0.031s) ok 4 - inserts into tab1_1 replicated
[14:59:19.321](0.030s) ok 5 - inserts into tab1_2 replicated
[14:59:19.349](0.028s) ok 6 - check replica insert after trigger applied on subscriber
[14:59:19.384](0.034s) ok 7 - inserts into tab1_def replicated
Waiting for replication conn sub1's replay_lsn to pass 0/01805558 on publisher
done
Waiting for replication conn sub2's replay_lsn to pass 0/01805558 on publisher
done
[14:59:19.726](0.343s) ok 8 - update of tab1_1, tab1_2 replicated
[14:59:19.753](0.027s) ok 9 - updates of tab1_2 replicated into tab1_2_1 correctly
[14:59:19.782](0.028s) ok 10 - updates of tab1_2 replicated into tab1_2_2 correctly
[14:59:19.809](0.028s) ok 11 - check replica update after trigger applied on subscriber
[14:59:19.839](0.030s) ok 12 - update of tab1_1 replicated
[14:59:19.874](0.036s) ok 13 - tab1_2 updated
[14:59:19.902](0.028s) ok 14 - check replica update after trigger applied on subscriber
[14:59:19.930](0.028s) ok 15 - tab1_def unchanged
Waiting for replication conn sub1's replay_lsn to pass 0/01805738 on publisher
done
Waiting for replication conn sub2's replay_lsn to pass 0/01805738 on publisher
done
[14:59:20.203](0.273s) ok 16 - update of tab1 (delete from tab1_def + insert into tab1_1) replicated
[14:59:20.232](0.029s) ok 17 - updates of tab1 (delete + insert) replicated into tab1_2_2 correctly
[14:59:20.266](0.034s) ok 18 - tab1_1 unchanged
[14:59:20.296](0.031s) ok 19 - insert into tab1_2 replicated
[14:59:20.324](0.028s) ok 20 - delete from tab1_def replicated
Waiting for replication conn sub1's replay_lsn to pass 0/01805898 on publisher
done
Waiting for replication conn sub2's replay_lsn to pass 0/01805898 on publisher
done
[14:59:20.598](0.274s) ok 21 - delete from tab1_1, tab1_2 replicated
[14:59:20.624](0.026s) ok 22 - delete from tab1_1 replicated
[14:59:20.652](0.028s) ok 23 - delete from tab1_2 replicated
Waiting for replication conn sub1's replay_lsn to pass 0/01806600 on publisher
done
Waiting for replication conn sub2's replay_lsn to pass 0/01806600 on publisher
done
[14:59:20.962](0.310s) ok 24 - truncate of tab1_2 replicated
[14:59:20.989](0.028s) ok 25 - truncate of tab1_2 replicated
Waiting for replication conn sub1's replay_lsn to pass 0/0180AF38 on publisher
done
Waiting for replication conn sub2's replay_lsn to pass 0/0180AF38 on publisher
done
[14:59:21.226](0.237s) ok 26 - truncate of tab1_1 replicated
[14:59:21.254](0.027s) ok 27 - truncate of tab1 replicated
Waiting for replication conn sub1's replay_lsn to pass 0/0180B208 on publisher
done
Waiting for replication conn sub2's replay_lsn to pass 0/0180B208 on publisher
done
Waiting for replication conn sub1's replay_lsn to pass 0/0180B378 on publisher
[15:03:19.071](237.817s) # poll_query_until timed out executing this query:
# SELECT '0/0180B378' <= 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:03:19.102](0.032s) # Last pg_stat_replication contents:
# 66706|10|postgres|sub2|||-1|2026-05-06 14:59:18.582249+00||streaming|0/0184C6C0|0/0184C6C0|0/0184C6C0|0/0184C6C0||||0|async|2026-05-06 15:03:17.664001+00
[15:03:19.103](0.000s) # die: timed out waiting for catchup at t/013_partition.pl line 366.
[15:03:19.103](0.000s) 1..27
timed out waiting for catchup at t/013_partition.pl line 366.
# Postmaster PID for node "publisher" is 66518
### Stopping node "publisher" using mode immediate
# Running: pg_ctl --pgdata /tmp/cirrus-ci-build/src/test/subscription/tmp_check/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 66573
### Stopping node "subscriber2" using mode immediate
# Running: pg_ctl --pgdata /tmp/cirrus-ci-build/src/test/subscription/tmp_check/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:03:19.323](0.220s) # Looks like your test exited with 29 just after 27.