# Checking port 58673
# Found port 58673
Name: publisher
Data directory: C:\cirrus\build/testrun/subscription/015_stream\data/t_015_stream_publisher_data/pgdata
Backup directory: C:\cirrus\build/testrun/subscription/015_stream\data/t_015_stream_publisher_data/backup
Archive directory: C:\cirrus\build/testrun/subscription/015_stream\data/t_015_stream_publisher_data/archives
Connection string: port=58673 host=C:/Windows/TEMP/fPihLnj4KS
Log file: C:\cirrus\build/testrun/subscription/015_stream\log/015_stream_publisher.log
[00:24:09.169](0.076s) # 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/015_stream\data/t_015_stream_publisher_data/pgdata

# Running: C:\cirrus\build\src/test\regress\pg_regress.exe --config-auth C:\cirrus\build/testrun/subscription/015_stream\data/t_015_stream_publisher_data/pgdata
### Starting node "publisher"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/subscription/015_stream\data/t_015_stream_publisher_data/pgdata -l C:\cirrus\build/testrun/subscription/015_stream\log/015_stream_publisher.log -o --cluster-name=publisher start
waiting for server to start.... done
server started
# Postmaster PID for node "publisher" is 7524
# Checking port 58674
# Found port 58674
Name: subscriber
Data directory: C:\cirrus\build/testrun/subscription/015_stream\data/t_015_stream_subscriber_data/pgdata
Backup directory: C:\cirrus\build/testrun/subscription/015_stream\data/t_015_stream_subscriber_data/backup
Archive directory: C:\cirrus\build/testrun/subscription/015_stream\data/t_015_stream_subscriber_data/archives
Connection string: port=58674 host=C:/Windows/TEMP/fPihLnj4KS
Log file: C:\cirrus\build/testrun/subscription/015_stream\log/015_stream_subscriber.log
[00:24:12.253](3.084s) # 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/015_stream\data/t_015_stream_subscriber_data/pgdata

# Running: C:\cirrus\build\src/test\regress\pg_regress.exe --config-auth C:\cirrus\build/testrun/subscription/015_stream\data/t_015_stream_subscriber_data/pgdata
### Starting node "subscriber"
# Running: pg_ctl -w -D C:\cirrus\build/testrun/subscription/015_stream\data/t_015_stream_subscriber_data/pgdata -l C:\cirrus\build/testrun/subscription/015_stream\log/015_stream_subscriber.log -o --cluster-name=subscriber start
waiting for server to start.... done
server started
# Postmaster PID for node "subscriber" is 3236
#### Begin standard error
psql:<stdin>:1: NOTICE:  created replication slot "tap_sub" on publisher
#### End standard error
Waiting for all subscriptions in "subscriber" to synchronize data
Waiting for replication conn tap_sub's replay_lsn to pass 0/15335E0 on publisher
done
done
[00:24:18.542](6.289s) ok 1 - check initial data was copied to subscriber
[00:24:18.743](0.200s) # issuing query via background psql: 
# 	BEGIN;
# 	INSERT INTO test_tab SELECT i, sha256(i::text::bytea) FROM generate_series(3, 5000) s(i);
# 	UPDATE test_tab SET b = sha256(b) WHERE mod(a,2) = 0;
# 	DELETE FROM test_tab WHERE mod(a,3) = 0;
# 	
[00:24:19.091](0.348s) # issuing query via background psql: COMMIT
Waiting for replication conn tap_sub's replay_lsn to pass 0/17C2718 on publisher
done
[00:24:20.092](1.001s) ok 2 - check extra columns contain local defaults
Waiting for replication conn tap_sub's replay_lsn to pass 0/19885A0 on publisher
done
[00:24:21.576](1.484s) ok 3 - check extra columns contain local defaults
Waiting for replication conn tap_sub's replay_lsn to pass 0/1A6D858 on publisher
done
[00:24:22.562](0.985s) ok 4 - check extra columns contain locally changed data
Waiting for replication conn tap_sub's replay_lsn to pass 0/1AD8218 on publisher
done
### Reloading node "subscriber"
# Running: pg_ctl -D C:\cirrus\build/testrun/subscription/015_stream\data/t_015_stream_subscriber_data/pgdata reload
server signaled
[00:24:25.567](3.005s) # issuing query via background psql: 
# 	BEGIN;
# 	INSERT INTO test_tab SELECT i, sha256(i::text::bytea) FROM generate_series(3, 5000) s(i);
# 	UPDATE test_tab SET b = sha256(b) WHERE mod(a,2) = 0;
# 	DELETE FROM test_tab WHERE mod(a,3) = 0;
# 	
[00:24:26.176](0.609s) # issuing query via background psql: COMMIT
Waiting for replication conn tap_sub's replay_lsn to pass 0/1D5E750 on publisher
done
[00:24:27.084](0.908s) ok 5 - check extra columns contain local defaults
Waiting for replication conn tap_sub's replay_lsn to pass 0/1F180E0 on publisher
done
[00:24:28.797](1.713s) ok 6 - check extra columns contain local defaults
Waiting for replication conn tap_sub's replay_lsn to pass 0/1FFE230 on publisher
done
[00:24:30.275](1.478s) ok 7 - check extra columns contain locally changed data
Waiting for replication conn tap_sub's replay_lsn to pass 0/2068D30 on publisher
done
### Reloading node "subscriber"
# Running: pg_ctl -D C:\cirrus\build/testrun/subscription/015_stream\data/t_015_stream_subscriber_data/pgdata reload
server signaled
[00:24:32.073](1.798s) # issuing query via background psql: 
# BEGIN;
# INSERT INTO test_tab_2 SELECT i FROM generate_series(1, 5000) s(i);
[00:24:32.473](0.400s) # issuing query via background psql: COMMIT
Waiting for replication conn tap_sub's replay_lsn to pass 0/20B7378 on publisher
done
[00:24:33.498](1.025s) ok 8 - data replicated to subscriber after dropping index
Waiting for replication conn tap_sub's replay_lsn to pass 0/20B7698 on publisher
done
[00:24:34.428](0.930s) # issuing query via background psql: 
# BEGIN;
# INSERT INTO test_tab_2 SELECT i FROM generate_series(1, 5000) s(i);
[00:24:35.206](0.778s) # issuing query via background psql: COMMIT
Waiting for replication conn tap_sub's replay_lsn to pass 0/2154260 on publisher
[00:30:46.140](370.934s) # poll_query_until timed out executing this query:
# SELECT '0/2154260' <= replay_lsn AND state = 'streaming'
#          FROM pg_catalog.pg_stat_replication
#          WHERE application_name IN ('tap_sub', 'walreceiver')
# expecting this output:
# t
# last actual query output:
# 
# with stderr:
timed out waiting for catchup at C:/cirrus/src/test/subscription/t/015_stream.pl line 289.
# Postmaster PID for node "publisher" is 7524
### Stopping node "publisher" using mode immediate
# Running: pg_ctl -D C:\cirrus\build/testrun/subscription/015_stream\data/t_015_stream_publisher_data/pgdata -m immediate stop
waiting for server to shut down.... done
server stopped
# No postmaster PID for node "publisher"
# No postmaster PID for node "subscriber"
[00:30:46.272](0.133s) # Tests were run but no plan was declared and done_testing() was not seen.
[00:30:46.272](0.000s) # Looks like your test exited with 255 just after 8.