# Checking port 61424
# Found port 61424
Name: publisher
Data directory: /Users/admin/pgsql/build/testrun/subscription/015_stream/data/t_015_stream_publisher_data/pgdata
Backup directory: /Users/admin/pgsql/build/testrun/subscription/015_stream/data/t_015_stream_publisher_data/backup
Archive directory: /Users/admin/pgsql/build/testrun/subscription/015_stream/data/t_015_stream_publisher_data/archives
Connection string: port=61424 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/nQO3GObmrj
Log file: /Users/admin/pgsql/build/testrun/subscription/015_stream/log/015_stream_publisher.log
[03:47:26.525](0.032s) # initializing database system by copying initdb template
# Running: cp -RPp /Users/admin/pgsql/build/tmp_install/initdb-template /Users/admin/pgsql/build/testrun/subscription/015_stream/data/t_015_stream_publisher_data/pgdata
# Running: /Users/admin/pgsql/build/src/test/regress/pg_regress --config-auth /Users/admin/pgsql/build/testrun/subscription/015_stream/data/t_015_stream_publisher_data/pgdata
### Starting node "publisher"
# Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/subscription/015_stream/data/t_015_stream_publisher_data/pgdata -l /Users/admin/pgsql/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 33140
# Checking port 61425
# Found port 61425
Name: subscriber
Data directory: /Users/admin/pgsql/build/testrun/subscription/015_stream/data/t_015_stream_subscriber_data/pgdata
Backup directory: /Users/admin/pgsql/build/testrun/subscription/015_stream/data/t_015_stream_subscriber_data/backup
Archive directory: /Users/admin/pgsql/build/testrun/subscription/015_stream/data/t_015_stream_subscriber_data/archives
Connection string: port=61425 host=/var/folders/n8/pp_chp6x3jlfy1n94r6hdjv40000gn/T/nQO3GObmrj
Log file: /Users/admin/pgsql/build/testrun/subscription/015_stream/log/015_stream_subscriber.log
[03:47:27.048](0.523s) # initializing database system by copying initdb template
# Running: cp -RPp /Users/admin/pgsql/build/tmp_install/initdb-template /Users/admin/pgsql/build/testrun/subscription/015_stream/data/t_015_stream_subscriber_data/pgdata
# Running: /Users/admin/pgsql/build/src/test/regress/pg_regress --config-auth /Users/admin/pgsql/build/testrun/subscription/015_stream/data/t_015_stream_subscriber_data/pgdata
### Starting node "subscriber"
# Running: pg_ctl -w -D /Users/admin/pgsql/build/testrun/subscription/015_stream/data/t_015_stream_subscriber_data/pgdata -l /Users/admin/pgsql/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 33265
#### 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/1541B10 on publisher
done
done
[03:47:28.085](1.038s) ok 1 - check initial data was copied to subscriber
[03:47:28.106](0.020s) # 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;
# 	
[03:47:28.156](0.050s) # issuing query via background psql: COMMIT
Waiting for replication conn tap_sub's replay_lsn to pass 0/17D0C48 on publisher
done
[03:47:28.652](0.496s) ok 2 - check extra columns contain local defaults
Waiting for replication conn tap_sub's replay_lsn to pass 0/1996AD0 on publisher
done
[03:47:29.668](1.016s) ok 3 - check extra columns contain local defaults
Waiting for replication conn tap_sub's replay_lsn to pass 0/1A7BD88 on publisher
done
[03:47:29.925](0.257s) ok 4 - check extra columns contain locally changed data
Waiting for replication conn tap_sub's replay_lsn to pass 0/1AE6748 on publisher
done
### Reloading node "subscriber"
# Running: pg_ctl -D /Users/admin/pgsql/build/testrun/subscription/015_stream/data/t_015_stream_subscriber_data/pgdata reload
server signaled
[03:47:31.153](1.228s) # 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;
# 	
[03:47:31.254](0.102s) # issuing query via background psql: COMMIT
Waiting for replication conn tap_sub's replay_lsn to pass 0/1D6CC80 on publisher
done
[03:47:31.856](0.601s) ok 5 - check extra columns contain local defaults
Waiting for replication conn tap_sub's replay_lsn to pass 0/1F265D8 on publisher
done
[03:47:32.609](0.753s) ok 6 - check extra columns contain local defaults
Waiting for replication conn tap_sub's replay_lsn to pass 0/200C738 on publisher
done
[03:47:32.770](0.161s) ok 7 - check extra columns contain locally changed data
Waiting for replication conn tap_sub's replay_lsn to pass 0/2077228 on publisher
done
### Reloading node "subscriber"
# Running: pg_ctl -D /Users/admin/pgsql/build/testrun/subscription/015_stream/data/t_015_stream_subscriber_data/pgdata reload
server signaled
[03:47:33.040](0.270s) # issuing query via background psql: 
# BEGIN;
# INSERT INTO test_tab_2 SELECT i FROM generate_series(1, 5000) s(i);
[03:47:33.168](0.128s) # issuing query via background psql: COMMIT
Waiting for replication conn tap_sub's replay_lsn to pass 0/20C5870 on publisher
done
[03:47:33.772](0.604s) ok 8 - data replicated to subscriber after dropping index
Waiting for replication conn tap_sub's replay_lsn to pass 0/20C5B90 on publisher
done
[03:47:33.876](0.104s) # issuing query via background psql: 
# BEGIN;
# INSERT INTO test_tab_2 SELECT i FROM generate_series(1, 5000) s(i);
[03:47:34.123](0.247s) # issuing query via background psql: COMMIT
Waiting for replication conn tap_sub's replay_lsn to pass 0/2162758 on publisher
[03:51:50.190](256.068s) # poll_query_until timed out executing this query:
# SELECT '0/2162758' <= 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 /Users/admin/pgsql/src/test/subscription/t/015_stream.pl line 289.
# Postmaster PID for node "publisher" is 33140
### Stopping node "publisher" using mode immediate
# Running: pg_ctl -D /Users/admin/pgsql/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"
[03:51:50.326](0.136s) # Tests were run but no plan was declared and done_testing() was not seen.
[03:51:50.327](0.000s) # Looks like your test exited with 60 just after 8.