本次测试环境
| IP | 10.189.102.118 | 10.189.100.195 | 10.189.100.226 |
| PGHOME | /usr/local/pgsql | /usr/local/pgsql | /usr/local/pgsql |
| Role | Origin,Providers | Subscribers,Providers | Subscribers |
| Database | region_il | region_il | region_il |
| Table |
il_longlive.entity il_longlive.entity_result il_longlive.result il_longlive.result_logbook |
il_longlive.entity il_longlive.entity_result il_longlive.result il_longlive.result_logbook |
il_longlive.entity il_longlive.entity_result il_longlive.result il_longlive.result_logbook |
| Sequences |
il_longlive.entity_entity_id_seq il_longlive.entity_result_id_seq il_longlive.result_id_seq il_longlive.result_logbook_record_id_seq |
il_longlive.entity_entity_id_seq il_longlive.entity_result_id_seq il_longlive.result_id_seq il_longlive.result_logbook_record_id_seq |
il_longlive.entity_entity_id_seq il_longlive.entity_result_id_seq il_longlive.result_id_seq il_longlive.result_logbook_record_id_seq |
Slony-I一般要求表有主键,或者唯一键,Slony-I会自动识别主键,如果表没有主键,需要手工指定唯一键,如果没有指定唯一键,则不能复制,下图为Slony-I架构图.
1. 查看主库表信息
region_il=# \d
List of relations
Schema | Name | Type | Owner
-------------+------------------------------+----------+----------
il_longlive | entity | table | postgres
il_longlive | entity_entity_id_seq | sequence | postgres
il_longlive | entity_result | table | postgres
il_longlive | entity_result_id_seq | sequence | postgres
il_longlive | result | table | postgres
il_longlive | result_id_seq | sequence | postgres
il_longlive | result_logbook | table | postgres
il_longlive | result_logbook_record_id_seq | sequence | postgres
il_longlive | result_vendor_schema | table | postgres
il_longlive | result_vendor_schema_id_seq | sequence | postgres
il_longlive | snapshot | table | postgres
il_longlive | snapshot_id_seq | sequence | postgres
(12 rows) region_il=# \d entity
Table "il_longlive.entity"
Column | Type | Modifiers
--------------+------------------------+------------------------------------------------------------
entity_id | integer | not null default nextval('entity_entity_id_seq'::regclass)
country_code | character varying(3) |
state_code | character varying(50) |
name | character varying(128) |
islive | boolean |
Indexes:
"entity_pkey" PRIMARY KEY, btree (entity_id) region_il=# select count(*) from entity;
count
---------
2528612
(1 row)
2. 在所有节点安装Slony-I
$ wget http://www.slony.info/downloads/2.2/source/slony1-2.2.4.tar.bz2
$ tar -jxf slony1-2.2..tar.bz2
$ cd slony1-2.2.
$ ./configure --prefix=/usr/local/pgsql --with-pgconfigdir=/usr/local/pgsql/bin --with-perltools
$ make
$ make install
3. 在所有节点创建Slony-I使用的专用用户
$ psql -c "CREATE USER slonyadmin WITH SUPERUSER REPLICATION LOGIN PASSWORD '';"
4. 在所有节点设置允许Slony-I用户远程登录
$ grep slonyadmin /usr/local/pgsql/data/pg_hba.conf
host all slonyadmin 0.0.0.0/ md5
5. 在所有节点的对应数据库创建plpgsql语言
$ createlang plpgsql -d region_il
6. 在主节点创建Slony-I配置文件,并复制到所有其他节点
$ cat /usr/local/pgsql/etc/slon_tools.conf
if ($ENV{"SLONYNODES"}) {
require $ENV{"SLONYNODES"};
} else {
$CLUSTER_NAME = 'region_il';
$PIDFILE_DIR = '/usr/local/pgsql/data';
$LOGDIR = '/usr/local/pgsql/data/pg_log';
$MASTERNODE = 1;
$DEBUGLEVEL = 0;
$START_WATCHDOG = 1;
$SLEEP_TIME = 60;
$WATCHDOG_VERBOSE = 0; add_node(node => 1,
host => '10.189.102.118',
dbname => 'region_il',
port => 5432,
user => 'slonyadmin',
password => '888888',); add_node(node => 2,
parent => 1,
host => '10.189.100.195',
dbname => 'region_il',
port => 5432,
user => 'slonyadmin',
password => '888888',); add_node(node => 3,
parent => 2,
host => '10.189.100.226',
dbname => 'region_il',
port => 5432,
user => 'slonyadmin',
password => '888888',);
} $SLONY_SETS = {
"il_longlive_schema" => {
"set_id" => 1,
"table_id" => 101,
"sequence_id" => 101,
"pkeyedtables" => [
'il_longlive.entity',
'il_longlive.entity_result',
'il_longlive.result',
'il_longlive.result_logbook',
], "keyedtables" => {}, "sequences" => ['il_longlive.entity_entity_id_seq',
'il_longlive.entity_result_id_seq',
'il_longlive.result_id_seq',
'il_longlive.result_logbook_record_id_seq',
],
}, }; if ($ENV{"SLONYSET"}) {
require $ENV{"SLONYSET"};
} # Please do not add or change anything below this point.
1;
$ scp /usr/local/pgsql/etc/slon_tools.conf postgres@10.189.100.195:/usr/local/pgsql/etc/
$ scp /usr/local/pgsql/etc/slon_tools.conf postgres@10.189.100.226:/usr/local/pgsql/etc/
7. 将需要复制的表结构从主节点导出并导入到所有辅节点中
$ pg_dump -U slonyadmin -s -t il_longlive.entity -t il_longlive.entity_result -t il_longlive.result -t il_longlive.result_logbook region_il | psql -h 10.189.100.195 -U slonyadmin region_il
$ pg_dump -U slonyadmin -s -t il_longlive.entity -t il_longlive.entity_result -t il_longlive.result -t il_longlive.result_logbook region_il | psql -h 10.189.100.226 -U slonyadmin region_il
8. 在主节点初始化Slony-I集群
$ /usr/local/pgsql/bin/slonik_init_cluster # INIT CLUSTER
cluster name = region_il;
node admin conninfo='host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888';
init cluster (id = , comment = 'Node 1 - region_il@10.189.102.118'); # STORE NODE
store node (id = , event node = , comment = 'Node 2 - region_il@10.189.100.195');
store node (id = , event node = , comment = 'Node 3 - region_il@10.189.100.226');
echo 'Set up replication nodes'; # STORE PATH
echo 'Next: configure paths for each node/origin';
store path (server = , client = , conninfo = 'host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888');
store path (server = , client = , conninfo = 'host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888');
store path (server = , client = , conninfo = 'host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888');
store path (server = , client = , conninfo = 'host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888');
store path (server = , client = , conninfo = 'host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888');
store path (server = , client = , conninfo = 'host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888');
echo 'Replication nodes prepared';
echo 'Please start a slon replication daemon for each node';
$ /usr/local/pgsql/bin/slonik_init_cluster | slonik
<stdin>:: Possible unsupported PostgreSQL version () 9.5, defaulting to 8.4 support
<stdin>:: Possible unsupported PostgreSQL version () 9.5, defaulting to 8.4 support
<stdin>:: Possible unsupported PostgreSQL version () 9.5, defaulting to 8.4 support
<stdin>:: Set up replication nodes
<stdin>:: Next: configure paths for each node/origin
<stdin>:: Replication nodes prepared
<stdin>:: Please start a slon replication daemon for each node
9. 在所有节点启动slon进程
- 在主节点启动slon进程
$ slon_start
Invoke slon for node - /usr/local/pgsql/bin/slon -p /usr/local/pgsql/data/region_il_node1.pid -s -d0 region_il 'host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888' > /usr/local/pgsql/data/pg_log/node1/region_il---.log >& &
Slon successfully started for cluster region_il, node node1
PID []
Start the watchdog process as well...
$ slon_status
Slon is running for the 'region_il' cluster on node1.
$ ps -ef | grep slon
postgres : pts/ :: /usr/local/pgsql/bin/slon -p /usr/local/pgsql/data/region_il_node1.pid -s -d0 region_il host=10.189.102.118 dbname=region_il user=slonyadmin port= password=
postgres : pts/ :: /usr/local/pgsql/bin/slon -p /usr/local/pgsql/data/region_il_node1.pid -s -d0 region_il host=10.189.102.118 dbname=region_il user=slonyadmin port= password=
postgres : ? :: postgres: slonyadmin region_il 10.189.102.118() idle
postgres : ? :: postgres: slonyadmin region_il 10.189.102.118() idle
postgres : ? :: postgres: slonyadmin region_il 10.189.102.118() idle
postgres : ? :: postgres: slonyadmin region_il 10.189.102.118() idle
postgres : ? :: postgres: slonyadmin region_il 10.189.102.118() idle
postgres : ? :: postgres: slonyadmin region_il 10.189.102.118() idle
postgres : pts/ :: /usr/bin/perl /usr/local/pgsql/bin/slon_watchdog --config=/usr/local/pgsql/etc/slon_tools.conf node1
- 在辅节点1启动slave进程
$ slon_start
Invoke slon for node - /usr/local/pgsql/bin/slon -p /usr/local/pgsql/data/region_il_node2.pid -s -d0 region_il 'host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888' > /usr/local/pgsql/data/pg_log/node2/region_il---.log >& &
Slon successfully started for cluster region_il, node node2
PID []
Start the watchdog process as well...
$ slon_status
Slon is running for the 'region_il' cluster on node2.
$ ps -ef | grep slon
postgres : ? :: postgres: slonyadmin region_il 10.189.102.118() idle
postgres : pts/ :: /usr/local/pgsql/bin/slon -p /usr/local/pgsql/data/region_il_node2.pid -s -d0 region_il host=10.189.100.195 dbname=region_il user=slonyadmin port= password=
postgres : pts/ :: /usr/local/pgsql/bin/slon -p /usr/local/pgsql/data/region_il_node2.pid -s -d0 region_il host=10.189.100.195 dbname=region_il user=slonyadmin port= password=
postgres : ? :: postgres: slonyadmin region_il 10.189.100.195() idle
postgres : ? :: postgres: slonyadmin region_il 10.189.100.195() idle
postgres : ? :: postgres: slonyadmin region_il 10.189.100.195() idle
postgres : ? :: postgres: slonyadmin region_il 10.189.100.195() idle
postgres : ? :: postgres: slonyadmin region_il 10.189.100.195() idle
postgres : ? :: postgres: slonyadmin region_il 10.189.100.195() idle
postgres : pts/ :: /usr/bin/perl /usr/local/pgsql/bin/slon_watchdog --config=/usr/local/pgsql/etc/slon_tools.conf node2
postgres : ? :: postgres: slonyadmin region_il 10.189.102.118() idle
- 在辅节点2启动slave进程
$ slon_start
Invoke slon for node - /usr/local/pgsql/bin/slon -p /usr/local/pgsql/data/region_il_node3.pid -s -d0 region_il 'host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888' > /usr/local/pgsql/data/pg_log/node3/region_il---.log >& &
Slon successfully started for cluster region_il, node node3
PID []
Start the watchdog process as well...
$ slon_status
Slon is running for the 'region_il' cluster on node3.
$ ps -ef | grep slon
postgres Jun02 ? :: /usr/local/pgsql/bin/slon -p /usr/local/pgsql/data/region_il_node2.pid -s -d0 region_il host=ec2t-pgtest- dbname=region_il user=slonyadmin port= password=mypna123
postgres Jun02 ? :: /usr/bin/perl /usr/local/pgsql/bin/slon_watchdog --config=/usr/local/pgsql/etc/slon_tools.conf node2
postgres : ? :: postgres: slonyadmin region_il 10.189.102.118() idle
postgres : ? :: postgres: slonyadmin region_il 10.189.100.195() idle
postgres : pts/ :: /usr/local/pgsql/bin/slon -p /usr/local/pgsql/data/region_il_node3.pid -s -d0 region_il host=10.189.100.226 dbname=region_il user=slonyadmin port= password=
postgres : pts/ :: /usr/local/pgsql/bin/slon -p /usr/local/pgsql/data/region_il_node3.pid -s -d0 region_il host=10.189.100.226 dbname=region_il user=slonyadmin port= password=
postgres : ? :: postgres: slonyadmin region_il 10.189.100.226() idle
postgres : ? :: postgres: slonyadmin region_il 10.189.100.226() idle
postgres : ? :: postgres: slonyadmin region_il 10.189.100.226() idle
postgres : ? :: postgres: slonyadmin region_il 10.189.100.226() idle
postgres : ? :: postgres: slonyadmin region_il 10.189.100.226() idle
postgres : ? :: postgres: slonyadmin region_il 10.189.100.226() idle
postgres : pts/ :: /usr/bin/perl /usr/local/pgsql/bin/slon_watchdog --config=/usr/local/pgsql/etc/slon_tools.conf node3
postgres : ? :: postgres: slonyadmin region_il 10.189.102.118() idle
postgres : ? :: /usr/local/pgsql/bin/slon -p /usr/local/pgsql/data/region_il_node2.pid -s -d0 region_il host=ec2t-pgtest- dbname=region_il user=slonyadmin port= password=mypna123
postgres : ? :: postgres: slonyadmin region_il 10.189.102.118() idle
10. 在主节点创建复制集
$ /usr/local/pgsql/bin/slonik_create_set
cluster name = region_il;
node admin conninfo='host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888'; # TABLE ADD KEY # CREATE SET
create set (id = , origin = , comment = 'Set 1 (il_longlive_schema) for region_il'); # SET ADD TABLE
echo 'Subscription set 1 (il_longlive_schema) created';
echo 'Adding tables to the subscription set';
set add table (set id = , origin = , id = ,
full qualified name = 'il_longlive.entity',
comment = 'Table il_longlive.entity with primary key');
echo 'Add primary keyed table il_longlive.entity';
set add table (set id = , origin = , id = ,
full qualified name = 'il_longlive.entity_result',
comment = 'Table il_longlive.entity_result with primary key');
echo 'Add primary keyed table il_longlive.entity_result';
set add table (set id = , origin = , id = ,
full qualified name = 'il_longlive.result',
comment = 'Table il_longlive.result with primary key');
echo 'Add primary keyed table il_longlive.result';
set add table (set id = , origin = , id = ,
full qualified name = 'il_longlive.result_logbook',
comment = 'Table il_longlive.result_logbook with primary key');
echo 'Add primary keyed table il_longlive.result_logbook'; # SET ADD SEQUENCE
echo 'Adding sequences to the subscription set';
set add sequence (set id = , origin = , id = ,
full qualified name = 'il_longlive.entity_entity_id_seq',
comment = 'Sequence il_longlive.entity_entity_id_seq');
echo 'Add sequence il_longlive.entity_entity_id_seq';
set add sequence (set id = , origin = , id = ,
full qualified name = 'il_longlive.entity_result_id_seq',
comment = 'Sequence il_longlive.entity_result_id_seq');
echo 'Add sequence il_longlive.entity_result_id_seq';
set add sequence (set id = , origin = , id = ,
full qualified name = 'il_longlive.result_id_seq',
comment = 'Sequence il_longlive.result_id_seq');
echo 'Add sequence il_longlive.result_id_seq';
set add sequence (set id = , origin = , id = ,
full qualified name = 'il_longlive.result_logbook_record_id_seq',
comment = 'Sequence il_longlive.result_logbook_record_id_seq');
echo 'Add sequence il_longlive.result_logbook_record_id_seq';
echo 'All tables added';
$ /usr/local/pgsql/bin/slonik_create_set | slonik
<stdin>:: Subscription set (il_longlive_schema) created
<stdin>:: Adding tables to the subscription set
<stdin>:: Add primary keyed table il_longlive.entity
<stdin>:: Add primary keyed table il_longlive.entity_result
<stdin>:: Add primary keyed table il_longlive.result
<stdin>:: Add primary keyed table il_longlive.result_logbook
<stdin>:: Adding sequences to the subscription set
<stdin>:: Add sequence il_longlive.entity_entity_id_seq
<stdin>:: Add sequence il_longlive.entity_result_id_seq
<stdin>:: Add sequence il_longlive.result_id_seq
<stdin>:: Add sequence il_longlive.result_logbook_record_id_seq
<stdin>:: All tables added
11. 在主节点创建复制订阅者
$ slonik_subscribe_set
cluster name = region_il;
node admin conninfo='host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888'; subscribe set (id = , provider = , receiver = , forward = yes);
echo 'Subscribed nodes to set 1';
$ slonik_subscribe_set | slonik
<stdin>:: Subscribed nodes to set
$ slonik_subscribe_set
cluster name = region_il;
node admin conninfo='host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888'; subscribe set (id = , provider = , receiver = , forward = yes);
echo 'Subscribed nodes to set 1';
$ slonik_subscribe_set | slonik
<stdin>:: Subscribed nodes to set
12.查看各节点Slony-I日志信息
- 主节点查看Slony-I日志信息
$ cat region_il-2017-06-14.log
2017-06-14 22:54:42 PDT CONFIG main: slon version 2.2.4 starting up
2017-06-14 22:54:42 PDT INFO slon: watchdog process started
2017-06-14 22:54:42 PDT CONFIG slon: watchdog ready - pid = 2454
2017-06-14 22:54:42 PDT CONFIG slon: worker process created - pid = 2455
2017-06-14 22:54:42 PDT CONFIG main: Integer option vac_frequency = 3
2017-06-14 22:54:42 PDT CONFIG main: Integer option log_level = 0
2017-06-14 22:54:42 PDT CONFIG main: Integer option sync_interval = 1000
2017-06-14 22:54:42 PDT CONFIG main: Integer option sync_interval_timeout = 10000
2017-06-14 22:54:42 PDT CONFIG main: Integer option sync_group_maxsize = 20
2017-06-14 22:54:42 PDT CONFIG main: Integer option syslog = 0
2017-06-14 22:54:42 PDT CONFIG main: Integer option quit_sync_provider = 0
2017-06-14 22:54:42 PDT CONFIG main: Integer option remote_listen_timeout = 300
2017-06-14 22:54:42 PDT CONFIG main: Integer option monitor_interval = 500
2017-06-14 22:54:42 PDT CONFIG main: Integer option explain_interval = 0
2017-06-14 22:54:42 PDT CONFIG main: Integer option tcp_keepalive_idle = 0
2017-06-14 22:54:42 PDT CONFIG main: Integer option tcp_keepalive_interval = 0
2017-06-14 22:54:42 PDT CONFIG main: Integer option tcp_keepalive_count = 0
2017-06-14 22:54:42 PDT CONFIG main: Integer option apply_cache_size = 100
2017-06-14 22:54:42 PDT CONFIG main: Boolean option log_pid = 0
2017-06-14 22:54:42 PDT CONFIG main: Boolean option log_timestamp = 1
2017-06-14 22:54:42 PDT CONFIG main: Boolean option tcp_keepalive = 1
2017-06-14 22:54:42 PDT CONFIG main: Boolean option monitor_threads = 1
2017-06-14 22:54:42 PDT CONFIG main: Real option real_placeholder = 0.000000
2017-06-14 22:54:42 PDT CONFIG main: String option cluster_name = region_il
2017-06-14 22:54:42 PDT CONFIG main: String option conn_info = host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888
2017-06-14 22:54:42 PDT CONFIG main: String option pid_file = /usr/local/pgsql/data/region_il_node1.pid
2017-06-14 22:54:42 PDT CONFIG main: String option log_timestamp_format = %Y-%m-%d %H:%M:%S %Z
2017-06-14 22:54:42 PDT CONFIG main: String option archive_dir = [NULL]
2017-06-14 22:54:42 PDT CONFIG main: String option sql_on_connection = [NULL]
2017-06-14 22:54:42 PDT CONFIG main: String option lag_interval = [NULL]
2017-06-14 22:54:42 PDT CONFIG main: String option command_on_logarchive = [NULL]
2017-06-14 22:54:42 PDT CONFIG main: String option syslog_facility = LOCAL0
2017-06-14 22:54:42 PDT CONFIG main: String option syslog_ident = slon
2017-06-14 22:54:42 PDT CONFIG main: String option cleanup_interval = 10 minutes
2017-06-14 22:54:42 PDT CONFIG main: local node id = 1
2017-06-14 22:54:42 PDT INFO main: main process started
2017-06-14 22:54:42 PDT CONFIG main: launching sched_start_mainloop
2017-06-14 22:54:42 PDT CONFIG main: loading current cluster configuration
2017-06-14 22:54:42 PDT CONFIG storeNode: no_id=2 no_comment='Node 2 - region_il@10.189.100.195'
2017-06-14 22:54:42 PDT CONFIG storeNode: no_id=3 no_comment='Node 3 - region_il@10.189.100.226'
2017-06-14 22:54:42 PDT CONFIG storePath: pa_server=2 pa_client=1 pa_conninfo="host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" pa_connretry=10
2017-06-14 22:54:42 PDT CONFIG storePath: pa_server=3 pa_client=1 pa_conninfo="host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888" pa_connretry=10
2017-06-14 22:54:42 PDT CONFIG storeListen: li_origin=2 li_receiver=1 li_provider=2
2017-06-14 22:54:42 PDT CONFIG storeListen: li_origin=3 li_receiver=1 li_provider=3
2017-06-14 22:54:42 PDT CONFIG main: last local event sequence = 5000000007
2017-06-14 22:54:42 PDT CONFIG main: configuration complete - starting threads
2017-06-14 22:54:42 PDT INFO localListenThread: thread starts
2017-06-14 22:54:42 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 22:54:42 PDT CONFIG enableNode: no_id=2
2017-06-14 22:54:42 PDT CONFIG enableNode: no_id=3
2017-06-14 22:54:42 PDT INFO main: running scheduler mainloop
2017-06-14 22:54:42 PDT INFO remoteWorkerThread_3: thread starts
2017-06-14 22:54:42 PDT INFO remoteListenThread_3: thread starts
2017-06-14 22:54:42 PDT CONFIG cleanupThread: thread starts
2017-06-14 22:54:42 PDT INFO syncThread: thread starts
2017-06-14 22:54:42 PDT INFO monitorThread: thread starts
2017-06-14 22:54:42 PDT INFO remoteListenThread_2: thread starts
2017-06-14 22:54:42 PDT INFO remoteWorkerThread_2: thread starts
2017-06-14 22:54:42 PDT CONFIG version for "host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 22:54:42 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 22:54:42 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 22:54:42 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 22:54:42 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 22:54:42 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 22:54:42 PDT CONFIG cleanupThread: bias = 60
2017-06-14 22:54:42 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 22:54:42 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 22:54:42 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 22:54:42 PDT CONFIG storeListen: li_origin=2 li_receiver=1 li_provider=2
2017-06-14 22:54:42 PDT CONFIG storeListen: li_origin=3 li_receiver=1 li_provider=3
2017-06-14 22:54:42 PDT CONFIG storeListen: li_origin=3 li_receiver=1 li_provider=2
2017-06-14 22:54:42 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 22:54:42 PDT CONFIG storeListen: li_origin=2 li_receiver=1 li_provider=2
2017-06-14 22:54:42 PDT CONFIG storeListen: li_origin=3 li_receiver=1 li_provider=3
2017-06-14 22:54:42 PDT CONFIG storeListen: li_origin=3 li_receiver=1 li_provider=2
2017-06-14 22:54:42 PDT CONFIG storeListen: li_origin=2 li_receiver=1 li_provider=3
2017-06-14 22:54:42 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 22:54:42 PDT CONFIG storeListen: li_origin=2 li_receiver=1 li_provider=2
2017-06-14 22:54:42 PDT CONFIG storeListen: li_origin=3 li_receiver=1 li_provider=3
2017-06-14 22:54:42 PDT CONFIG storeListen: li_origin=3 li_receiver=1 li_provider=2
2017-06-14 22:54:42 PDT CONFIG storeListen: li_origin=2 li_receiver=1 li_provider=3
2017-06-14 22:54:42 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 22:54:42 PDT CONFIG storeListen: li_origin=2 li_receiver=1 li_provider=2
2017-06-14 22:54:42 PDT CONFIG storeListen: li_origin=3 li_receiver=1 li_provider=3
2017-06-14 22:54:42 PDT CONFIG storeListen: li_origin=3 li_receiver=1 li_provider=2
2017-06-14 22:54:42 PDT CONFIG storeListen: li_origin=2 li_receiver=1 li_provider=3
2017-06-14 22:54:42 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 22:54:42 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 22:57:50 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 22:57:50 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 22:57:50 PDT INFO remoteWorkerThread_2: SYNC 5000000003 done in 0.014 seconds
2017-06-14 22:57:58 PDT INFO remoteWorkerThread_2: SYNC 5000000004 done in 0.003 seconds
2017-06-14 22:58:11 PDT INFO remoteWorkerThread_2: SYNC 5000000005 done in 0.002 seconds
2017-06-14 22:58:19 PDT INFO remoteWorkerThread_2: SYNC 5000000006 done in 0.002 seconds
2017-06-14 22:58:27 PDT INFO remoteWorkerThread_2: SYNC 5000000007 done in 0.003 seconds
2017-06-14 22:58:35 PDT INFO remoteWorkerThread_2: SYNC 5000000008 done in 0.002 seconds
2017-06-14 22:58:43 PDT INFO remoteWorkerThread_2: SYNC 5000000009 done in 0.002 seconds
2017-06-14 22:59:01 PDT INFO remoteWorkerThread_2: SYNC 5000000010 done in 0.003 seconds
2017-06-14 22:59:09 PDT INFO remoteWorkerThread_2: SYNC 5000000011 done in 0.002 seconds
2017-06-14 22:59:17 PDT INFO remoteWorkerThread_2: SYNC 5000000012 done in 0.002 seconds
2017-06-14 22:59:25 PDT INFO remoteWorkerThread_2: SYNC 5000000013 done in 0.002 seconds
2017-06-14 22:59:38 PDT INFO remoteWorkerThread_2: SYNC 5000000014 done in 0.003 seconds
2017-06-14 22:59:51 PDT INFO remoteWorkerThread_2: SYNC 5000000015 done in 0.002 seconds
2017-06-14 22:59:54 PDT INFO remoteWorkerThread_2: SYNC 5000000016 done in 0.002 seconds
2017-06-14 23:00:05 PDT INFO remoteWorkerThread_2: SYNC 5000000017 done in 0.002 seconds
2017-06-14 23:00:19 PDT INFO remoteWorkerThread_2: SYNC 5000000018 done in 0.003 seconds
2017-06-14 23:00:32 PDT INFO remoteWorkerThread_2: SYNC 5000000019 done in 0.003 seconds
2017-06-14 23:00:35 PDT INFO remoteWorkerThread_2: SYNC 5000000020 done in 0.003 seconds
2017-06-14 23:00:43 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 23:00:43 PDT CONFIG version for "host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:00:43 PDT INFO remoteWorkerThread_3: SYNC 5000000003 done in 0.045 seconds
2017-06-14 23:00:47 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:00:47 PDT CONFIG version for "host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:00:47 PDT INFO remoteWorkerThread_2: SYNC 5000000021 done in 0.014 seconds
2017-06-14 23:00:55 PDT INFO remoteWorkerThread_3: SYNC 5000000004 done in 0.002 seconds
2017-06-14 23:00:55 PDT INFO remoteWorkerThread_2: SYNC 5000000022 done in 0.005 seconds
2017-06-14 23:01:08 PDT INFO remoteWorkerThread_3: SYNC 5000000005 done in 0.002 seconds
2017-06-14 23:01:08 PDT INFO remoteWorkerThread_2: SYNC 5000000023 done in 0.005 seconds
2017-06-14 23:01:16 PDT INFO remoteWorkerThread_3: SYNC 5000000006 done in 0.002 seconds
2017-06-14 23:01:20 PDT INFO remoteWorkerThread_3: SYNC 5000000007 done in 0.002 seconds
2017-06-14 23:01:20 PDT INFO remoteWorkerThread_2: SYNC 5000000024 done in 0.005 seconds
2017-06-14 23:01:24 PDT INFO remoteWorkerThread_2: SYNC 5000000025 done in 0.005 seconds
2017-06-14 23:01:38 PDT INFO remoteWorkerThread_3: SYNC 5000000008 done in 0.003 seconds
2017-06-14 23:01:38 PDT INFO remoteWorkerThread_2: SYNC 5000000026 done in 0.005 seconds
2017-06-14 23:01:41 PDT INFO remoteWorkerThread_3: SYNC 5000000009 done in 0.002 seconds
2017-06-14 23:01:44 PDT INFO remoteWorkerThread_2: SYNC 5000000027 done in 0.004 seconds
2017-06-14 23:01:52 PDT INFO remoteWorkerThread_3: SYNC 5000000010 done in 0.003 seconds
2017-06-14 23:01:55 PDT INFO remoteWorkerThread_2: SYNC 5000000028 done in 0.005 seconds
2017-06-14 23:02:08 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 23:02:08 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:02:08 PDT INFO remoteWorkerThread_2: SYNC 5000000029 done in 0.012 seconds
2017-06-14 23:02:08 PDT INFO remoteWorkerThread_3: SYNC 5000000011 done in 0.016 seconds
2017-06-14 23:02:11 PDT INFO remoteWorkerThread_3: SYNC 5000000012 done in 0.004 seconds
2017-06-14 23:02:19 PDT INFO remoteWorkerThread_2: SYNC 5000000030 done in 0.005 seconds
2017-06-14 23:02:22 PDT INFO remoteWorkerThread_3: SYNC 5000000013 done in 0.005 seconds
2017-06-14 23:02:25 PDT INFO remoteWorkerThread_2: SYNC 5000000031 done in 0.004 seconds
2017-06-14 23:02:38 PDT INFO remoteWorkerThread_2: SYNC 5000000032 done in 0.004 seconds
2017-06-14 23:02:38 PDT INFO remoteWorkerThread_3: SYNC 5000000014 done in 0.005 seconds
2017-06-14 23:02:46 PDT INFO remoteWorkerThread_3: SYNC 5000000015 done in 0.005 seconds
2017-06-14 23:02:50 PDT INFO remoteWorkerThread_3: SYNC 5000000016 done in 0.004 seconds
2017-06-14 23:02:50 PDT INFO remoteWorkerThread_2: SYNC 5000000033 done in 0.005 seconds
2017-06-14 23:02:54 PDT INFO remoteWorkerThread_2: SYNC 5000000034 done in 0.004 seconds
2017-06-14 23:03:08 PDT INFO remoteWorkerThread_3: SYNC 5000000017 done in 0.004 seconds
2017-06-14 23:03:08 PDT INFO remoteWorkerThread_2: SYNC 5000000035 done in 0.005 seconds
2017-06-14 23:03:11 PDT CONFIG storeSet: set_id=1 set_origin=1 set_comment='Set 1 (il_longlive_schema) for region_il'
2017-06-14 23:03:16 PDT INFO remoteWorkerThread_3: SYNC 5000000018 done in 0.005 seconds
2017-06-14 23:03:17 PDT INFO remoteWorkerThread_2: SYNC 5000000036 done in 0.005 seconds
2017-06-14 23:03:21 PDT INFO remoteWorkerThread_3: SYNC 5000000019 done in 0.004 seconds
2017-06-14 23:03:29 PDT INFO remoteWorkerThread_2: SYNC 5000000037 done in 0.016 seconds
2017-06-14 23:03:32 PDT INFO remoteWorkerThread_3: SYNC 5000000020 done in 0.012 seconds
2017-06-14 23:03:35 PDT INFO remoteWorkerThread_2: SYNC 5000000038 done in 0.004 seconds
2017-06-14 23:03:48 PDT INFO remoteWorkerThread_2: SYNC 5000000039 done in 0.004 seconds
2017-06-14 23:03:48 PDT INFO remoteWorkerThread_3: SYNC 5000000021 done in 0.005 seconds
2017-06-14 23:03:56 PDT INFO remoteWorkerThread_3: SYNC 5000000022 done in 0.005 seconds
2017-06-14 23:03:57 PDT INFO remoteWorkerThread_2: SYNC 5000000040 done in 0.005 seconds
2017-06-14 23:04:01 PDT INFO remoteWorkerThread_3: SYNC 5000000023 done in 0.005 seconds
2017-06-14 23:04:04 PDT INFO remoteWorkerThread_2: SYNC 5000000041 done in 0.005 seconds
2017-06-14 23:04:17 PDT INFO remoteWorkerThread_2: SYNC 5000000042 done in 0.005 seconds
2017-06-14 23:04:17 PDT INFO remoteWorkerThread_3: SYNC 5000000024 done in 0.005 seconds
2017-06-14 23:04:20 PDT INFO remoteWorkerThread_3: SYNC 5000000025 done in 0.004 seconds
2017-06-14 23:04:23 PDT INFO remoteWorkerThread_2: SYNC 5000000043 done in 0.008 seconds
2017-06-14 23:04:31 PDT INFO remoteWorkerThread_3: SYNC 5000000026 done in 0.005 seconds
2017-06-14 23:04:34 PDT INFO remoteWorkerThread_2: SYNC 5000000044 done in 0.004 seconds
2017-06-14 23:04:42 PDT INFO remoteWorkerThread_3: SYNC 5000000027 done in 0.005 seconds
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=2498
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=2499
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=2500
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=2526
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: Logswitch to sl_log_2 initiated
CONTEXT: SQL statement "SELECT "_region_il".logswitch_start()"
PL/pgSQL function _region_il.cleanupevent(interval) line 97 at PERFORM
2017-06-14 23:04:43 PDT INFO cleanupThread: 0.011 seconds for cleanupEvent()
2017-06-14 23:04:45 PDT INFO remoteWorkerThread_2: SYNC 5000000045 done in 0.004 seconds
2017-06-14 23:04:53 PDT INFO remoteWorkerThread_2: SYNC 5000000046 done in 0.005 seconds
2017-06-14 23:04:53 PDT INFO remoteWorkerThread_3: SYNC 5000000028 done in 0.005 seconds
2017-06-14 23:05:06 PDT INFO remoteWorkerThread_3: SYNC 5000000029 done in 0.005 seconds
2017-06-14 23:05:06 PDT INFO remoteWorkerThread_2: SYNC 5000000047 done in 0.005 seconds
2017-06-14 23:05:19 PDT INFO remoteWorkerThread_2: SYNC 5000000048 done in 0.004 seconds
2017-06-14 23:05:19 PDT INFO remoteWorkerThread_3: SYNC 5000000030 done in 0.012 seconds
2017-06-14 23:05:22 PDT INFO remoteWorkerThread_3: SYNC 5000000031 done in 0.004 seconds
2017-06-14 23:05:25 PDT INFO remoteWorkerThread_2: SYNC 5000000049 done in 0.005 seconds
2017-06-14 23:05:38 PDT INFO remoteWorkerThread_2: SYNC 5000000050 done in 0.004 seconds
2017-06-14 23:05:38 PDT INFO remoteWorkerThread_3: SYNC 5000000032 done in 0.005 seconds
2017-06-14 23:05:41 PDT INFO remoteWorkerThread_3: SYNC 5000000033 done in 0.005 seconds
2017-06-14 23:05:45 PDT INFO remoteWorkerThread_2: SYNC 5000000051 done in 0.005 seconds
2017-06-14 23:05:53 PDT INFO remoteWorkerThread_3: SYNC 5000000034 done in 0.004 seconds
2017-06-14 23:05:53 PDT INFO remoteWorkerThread_2: SYNC 5000000052 done in 0.005 seconds
2017-06-14 23:06:06 PDT INFO remoteWorkerThread_3: SYNC 5000000035 done in 0.005 seconds
2017-06-14 23:06:06 PDT INFO remoteWorkerThread_2: SYNC 5000000053 done in 0.005 seconds
2017-06-14 23:06:14 PDT INFO remoteWorkerThread_2: SYNC 5000000054 done in 0.005 seconds
2017-06-14 23:06:14 PDT INFO remoteWorkerThread_3: SYNC 5000000036 done in 0.006 seconds
2017-06-14 23:06:22 PDT INFO remoteWorkerThread_3: SYNC 5000000037 done in 0.005 seconds
2017-06-14 23:06:25 PDT INFO remoteWorkerThread_2: SYNC 5000000055 done in 0.004 seconds
2017-06-14 23:06:30 PDT INFO remoteWorkerThread_3: SYNC 5000000038 done in 0.005 seconds
2017-06-14 23:06:36 PDT INFO remoteWorkerThread_2: SYNC 5000000056 done in 0.005 seconds
2017-06-14 23:06:40 PDT INFO remoteWorkerThread_3: SYNC 5000000039 done in 0.005 seconds
2017-06-14 23:06:48 PDT INFO remoteWorkerThread_2: SYNC 5000000057 done in 0.007 seconds
2017-06-14 23:06:56 PDT INFO remoteWorkerThread_3: SYNC 5000000040 done in 0.004 seconds
2017-06-14 23:06:56 PDT INFO remoteWorkerThread_2: SYNC 5000000058 done in 0.005 seconds
2017-06-14 23:07:09 PDT INFO remoteWorkerThread_3: SYNC 5000000041 done in 0.004 seconds
2017-06-14 23:07:09 PDT INFO remoteWorkerThread_2: SYNC 5000000059 done in 0.005 seconds
2017-06-14 23:07:10 PDT INFO remoteWorkerThread_3: SYNC 5000000042 done in 0.004 seconds
2017-06-14 23:07:19 PDT INFO remoteWorkerThread_2: SYNC 5000000060 done in 0.005 seconds
2017-06-14 23:07:23 PDT INFO remoteWorkerThread_3: SYNC 5000000043 done in 0.015 seconds
2017-06-14 23:07:23 PDT INFO remoteWorkerThread_2: SYNC 5000000061 done in 0.017 seconds
2017-06-14 23:07:31 PDT INFO remoteWorkerThread_3: SYNC 5000000044 done in 0.004 seconds
2017-06-14 23:07:39 PDT INFO remoteWorkerThread_2: SYNC 5000000062 done in 0.013 seconds
2017-06-14 23:07:42 PDT INFO remoteWorkerThread_3: SYNC 5000000045 done in 0.006 seconds
2017-06-14 23:07:43 PDT INFO remoteWorkerThread_2: SYNC 5000000063 done in 0.005 seconds
2017-06-14 23:07:51 PDT INFO remoteWorkerThread_3: SYNC 5000000046 done in 0.008 seconds
2017-06-14 23:07:56 PDT INFO remoteWorkerThread_2: SYNC 5000000064 done in 0.005 seconds
2017-06-14 23:08:02 PDT INFO remoteWorkerThread_3: SYNC 5000000047 done in 0.022 seconds
2017-06-14 23:08:07 PDT INFO remoteWorkerThread_2: SYNC 5000000065 done in 0.005 seconds
2017-06-14 23:08:13 PDT INFO remoteWorkerThread_3: SYNC 5000000048 done in 0.008 seconds
2017-06-14 23:08:18 PDT INFO remoteWorkerThread_2: SYNC 5000000066 done in 0.011 seconds
2017-06-14 23:08:24 PDT INFO remoteWorkerThread_3: SYNC 5000000049 done in 0.004 seconds
2017-06-14 23:08:24 PDT INFO remoteWorkerThread_2: SYNC 5000000067 done in 0.017 seconds
2017-06-14 23:08:35 PDT INFO remoteWorkerThread_3: SYNC 5000000050 done in 0.005 seconds
2017-06-14 23:08:39 PDT INFO remoteWorkerThread_2: SYNC 5000000068 done in 0.005 seconds
2017-06-14 23:08:40 PDT INFO remoteWorkerThread_3: SYNC 5000000051 done in 0.004 seconds
2017-06-14 23:08:49 PDT INFO remoteWorkerThread_2: SYNC 5000000069 done in 0.005 seconds
2017-06-14 23:08:53 PDT INFO remoteWorkerThread_2: SYNC 5000000070 done in 0.005 seconds
2017-06-14 23:08:54 PDT INFO remoteWorkerThread_3: SYNC 5000000052 done in 0.005 seconds
2017-06-14 23:09:02 PDT INFO remoteWorkerThread_3: SYNC 5000000053 done in 0.006 seconds
2017-06-14 23:09:07 PDT INFO remoteWorkerThread_2: SYNC 5000000071 done in 0.005 seconds
2017-06-14 23:09:13 PDT INFO remoteWorkerThread_3: SYNC 5000000054 done in 0.005 seconds
2017-06-14 23:09:18 PDT INFO remoteWorkerThread_2: SYNC 5000000072 done in 0.005 seconds
2017-06-14 23:09:24 PDT INFO remoteWorkerThread_3: SYNC 5000000055 done in 0.005 seconds
2017-06-14 23:09:29 PDT INFO remoteWorkerThread_2: SYNC 5000000073 done in 0.005 seconds
2017-06-14 23:09:33 PDT INFO remoteWorkerThread_2: SYNC 5000000074 done in 0.005 seconds
2017-06-14 23:09:34 PDT INFO remoteWorkerThread_3: SYNC 5000000056 done in 0.005 seconds
2017-06-14 23:09:48 PDT INFO remoteWorkerThread_3: SYNC 5000000057 done in 0.004 seconds
2017-06-14 23:09:48 PDT INFO remoteWorkerThread_2: SYNC 5000000075 done in 0.005 seconds
2017-06-14 23:09:52 PDT INFO remoteWorkerThread_3: SYNC 5000000058 done in 0.005 seconds
2017-06-14 23:09:55 PDT INFO remoteWorkerThread_2: SYNC 5000000076 done in 0.004 seconds
2017-06-14 23:10:00 PDT INFO remoteWorkerThread_3: SYNC 5000000059 done in 0.005 seconds
2017-06-14 23:10:06 PDT INFO remoteWorkerThread_2: SYNC 5000000077 done in 0.005 seconds
2017-06-14 23:10:11 PDT INFO remoteWorkerThread_3: SYNC 5000000060 done in 0.005 seconds
2017-06-14 23:10:17 PDT INFO remoteWorkerThread_2: SYNC 5000000078 done in 0.005 seconds
2017-06-14 23:10:22 PDT INFO remoteWorkerThread_3: SYNC 5000000061 done in 0.005 seconds
2017-06-14 23:10:28 PDT INFO remoteWorkerThread_2: SYNC 5000000079 done in 0.005 seconds
2017-06-14 23:10:33 PDT INFO remoteWorkerThread_3: SYNC 5000000062 done in 0.005 seconds
2017-06-14 23:10:37 PDT INFO remoteWorkerThread_2: SYNC 5000000080 done in 0.005 seconds
2017-06-14 23:10:41 PDT INFO remoteWorkerThread_3: SYNC 5000000063 done in 0.005 seconds
2017-06-14 23:10:46 PDT INFO remoteWorkerThread_2: SYNC 5000000081 done in 0.005 seconds
2017-06-14 23:10:52 PDT INFO remoteWorkerThread_3: SYNC 5000000064 done in 0.004 seconds
2017-06-14 23:10:57 PDT INFO remoteWorkerThread_2: SYNC 5000000082 done in 0.004 seconds
2017-06-14 23:11:03 PDT INFO remoteWorkerThread_3: SYNC 5000000065 done in 0.004 seconds
2017-06-14 23:11:08 PDT INFO remoteWorkerThread_2: SYNC 5000000083 done in 0.005 seconds
2017-06-14 23:11:14 PDT INFO remoteWorkerThread_3: SYNC 5000000066 done in 0.004 seconds
2017-06-14 23:11:19 PDT INFO remoteWorkerThread_2: SYNC 5000000084 done in 0.005 seconds
2017-06-14 23:11:23 PDT INFO remoteWorkerThread_2: SYNC 5000000085 done in 0.005 seconds
2017-06-14 23:11:25 PDT INFO remoteWorkerThread_3: SYNC 5000000067 done in 0.005 seconds
2017-06-14 23:11:34 PDT INFO remoteWorkerThread_2: SYNC 5000000086 done in 0.006 seconds
2017-06-14 23:11:38 PDT INFO remoteWorkerThread_3: SYNC 5000000068 done in 0.005 seconds
2017-06-14 23:11:42 PDT INFO remoteWorkerThread_3: SYNC 5000000069 done in 0.005 seconds
2017-06-14 23:11:46 PDT INFO remoteWorkerThread_2: SYNC 5000000087 done in 0.005 seconds
2017-06-14 23:11:54 PDT CONFIG storeListen: li_origin=2 li_receiver=1 li_provider=2
2017-06-14 23:11:54 PDT CONFIG storeListen: li_origin=3 li_receiver=1 li_provider=3
2017-06-14 23:11:54 PDT CONFIG storeListen: li_origin=2 li_receiver=1 li_provider=3
2017-06-14 23:11:54 PDT CONFIG storeListen: li_origin=3 li_receiver=1 li_provider=2
2017-06-14 23:11:54 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:11:54 PDT CONFIG remoteWorkerThread_2: connection for provider 2 terminated
2017-06-14 23:11:54 PDT CONFIG remoteWorkerThread_2: disconnecting from data provider 2
2017-06-14 23:11:54 PDT CONFIG remoteWorkerThread_2: connection for provider 3 terminated
2017-06-14 23:11:54 PDT CONFIG remoteWorkerThread_2: disconnecting from data provider 3
2017-06-14 23:11:54 PDT CONFIG storeListen: li_origin=2 li_receiver=1 li_provider=2
2017-06-14 23:11:54 PDT CONFIG storeListen: li_origin=3 li_receiver=1 li_provider=3
2017-06-14 23:11:54 PDT CONFIG storeListen: li_origin=2 li_receiver=1 li_provider=3
2017-06-14 23:11:54 PDT CONFIG storeListen: li_origin=3 li_receiver=1 li_provider=2
2017-06-14 23:11:54 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:11:54 PDT INFO remoteWorkerThread_3: SYNC 5000000070 done in 0.005 seconds
2017-06-14 23:11:54 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 23:11:54 PDT CONFIG remoteWorkerThread_3: connection for provider 3 terminated
2017-06-14 23:11:54 PDT CONFIG remoteWorkerThread_3: disconnecting from data provider 3
2017-06-14 23:11:54 PDT CONFIG remoteWorkerThread_3: connection for provider 2 terminated
2017-06-14 23:11:54 PDT CONFIG remoteWorkerThread_3: disconnecting from data provider 2
2017-06-14 23:11:58 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:11:58 PDT CONFIG version for "host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:11:58 PDT INFO remoteWorkerThread_2: SYNC 5000000088 done in 0.012 seconds
2017-06-14 23:12:02 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 23:12:02 PDT CONFIG version for "host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:12:02 PDT INFO remoteWorkerThread_3: SYNC 5000000071 done in 0.020 seconds
2017-06-14 23:12:06 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:12:06 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:12:07 PDT INFO remoteWorkerThread_2: SYNC 5000000089 done in 0.278 seconds
2017-06-14 23:12:13 PDT INFO remoteWorkerThread_3: SYNC 5000000072 done in 0.003 seconds
2017-06-14 23:12:24 PDT INFO remoteWorkerThread_3: SYNC 5000000073 done in 0.003 seconds
2017-06-14 23:12:33 PDT INFO remoteWorkerThread_2: SYNC 5000000090 done in 0.005 seconds
2017-06-14 23:12:37 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 23:12:37 PDT INFO remoteWorkerThread_2: SYNC 5000000091 done in 0.004 seconds
2017-06-14 23:12:37 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:12:38 PDT INFO remoteWorkerThread_3: SYNC 5000000074 done in 0.522 seconds
2017-06-14 23:12:41 PDT INFO remoteWorkerThread_3: SYNC 5000000075 done in 0.005 seconds
2017-06-14 23:12:52 PDT INFO remoteWorkerThread_3: SYNC 5000000076 done in 0.005 seconds
2017-06-14 23:12:55 PDT INFO remoteWorkerThread_2: SYNC 5000000092 done in 0.005 seconds
2017-06-14 23:13:03 PDT INFO remoteWorkerThread_2: SYNC 5000000093 done in 0.016 seconds
2017-06-14 23:13:09 PDT INFO remoteWorkerThread_3: SYNC 5000000077 done in 0.005 seconds
2017-06-14 23:13:10 PDT INFO remoteWorkerThread_3: SYNC 5000000078 done in 0.005 seconds
2017-06-14 23:13:14 PDT INFO remoteWorkerThread_2: SYNC 5000000094 done in 0.005 seconds
2017-06-14 23:13:21 PDT INFO remoteWorkerThread_3: SYNC 5000000079 done in 0.005 seconds
2017-06-14 23:13:25 PDT INFO remoteWorkerThread_2: SYNC 5000000095 done in 0.004 seconds
2017-06-14 23:13:32 PDT INFO remoteWorkerThread_3: SYNC 5000000080 done in 0.005 seconds
2017-06-14 23:13:36 PDT INFO remoteWorkerThread_2: SYNC 5000000096 done in 0.012 seconds
2017-06-14 23:13:43 PDT INFO remoteWorkerThread_3: SYNC 5000000081 done in 0.005 seconds
2017-06-14 23:13:47 PDT INFO remoteWorkerThread_2: SYNC 5000000097 done in 0.089 seconds
2017-06-14 23:13:54 PDT INFO remoteWorkerThread_3: SYNC 5000000082 done in 0.005 seconds
2017-06-14 23:13:58 PDT INFO remoteWorkerThread_2: SYNC 5000000098 done in 0.006 seconds
2017-06-14 23:14:06 PDT INFO remoteWorkerThread_3: SYNC 5000000083 done in 0.005 seconds
2017-06-14 23:14:10 PDT INFO remoteWorkerThread_3: SYNC 5000000084 done in 0.004 seconds
2017-06-14 23:14:11 PDT INFO remoteWorkerThread_2: SYNC 5000000099 done in 0.005 seconds
2017-06-14 23:14:21 PDT INFO remoteWorkerThread_3: SYNC 5000000085 done in 0.015 seconds
2017-06-14 23:14:21 PDT INFO remoteWorkerThread_2: SYNC 5000000100 done in 0.016 seconds
2017-06-14 23:14:32 PDT INFO remoteWorkerThread_3: SYNC 5000000086 done in 0.009 seconds
2017-06-14 23:14:35 PDT INFO remoteWorkerThread_2: SYNC 5000000101 done in 0.005 seconds
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=2529
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=2552
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=2567
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=2568
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: log switch to sl_log_2 complete - truncate sl_log_1
CONTEXT: PL/pgSQL function _region_il.cleanupevent(interval) line 95 at assignment
2017-06-14 23:14:43 PDT INFO cleanupThread: 0.086 seconds for cleanupEvent()
2017-06-14 23:14:49 PDT INFO remoteWorkerThread_3: SYNC 5000000087 done in 0.005 seconds
2017-06-14 23:14:50 PDT INFO remoteWorkerThread_3: SYNC 5000000088 done in 0.005 seconds
2017-06-14 23:14:54 PDT CONFIG storeListen: li_origin=2 li_receiver=1 li_provider=2
2017-06-14 23:14:54 PDT CONFIG storeListen: li_origin=3 li_receiver=1 li_provider=3
2017-06-14 23:14:54 PDT CONFIG storeListen: li_origin=2 li_receiver=1 li_provider=3
2017-06-14 23:14:54 PDT CONFIG storeListen: li_origin=3 li_receiver=1 li_provider=2
2017-06-14 23:14:54 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:14:54 PDT CONFIG remoteWorkerThread_2: connection for provider 3 terminated
2017-06-14 23:14:54 PDT CONFIG remoteWorkerThread_2: disconnecting from data provider 3
2017-06-14 23:14:54 PDT CONFIG remoteWorkerThread_2: connection for provider 2 terminated
2017-06-14 23:14:54 PDT CONFIG remoteWorkerThread_2: disconnecting from data provider 2
2017-06-14 23:14:54 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 23:14:54 PDT CONFIG remoteWorkerThread_3: connection for provider 3 terminated
2017-06-14 23:14:54 PDT CONFIG remoteWorkerThread_3: disconnecting from data provider 3
2017-06-14 23:14:54 PDT CONFIG remoteWorkerThread_3: connection for provider 2 terminated
2017-06-14 23:14:54 PDT CONFIG remoteWorkerThread_3: disconnecting from data provider 2
2017-06-14 23:14:54 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:14:54 PDT CONFIG storeListen: li_origin=2 li_receiver=1 li_provider=2
2017-06-14 23:14:54 PDT CONFIG storeListen: li_origin=3 li_receiver=1 li_provider=3
2017-06-14 23:14:54 PDT CONFIG storeListen: li_origin=2 li_receiver=1 li_provider=3
2017-06-14 23:14:54 PDT CONFIG storeListen: li_origin=3 li_receiver=1 li_provider=2
2017-06-14 23:14:54 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 23:14:54 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:14:54 PDT INFO remoteWorkerThread_2: SYNC 5000000102 done in 0.037 seconds
2017-06-14 23:14:54 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:14:54 PDT CONFIG remoteWorkerThread_2: connection for provider 2 terminated
2017-06-14 23:14:54 PDT CONFIG remoteWorkerThread_2: disconnecting from data provider 2
2017-06-14 23:15:02 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:15:02 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:15:02 PDT INFO remoteWorkerThread_2: SYNC 5000000103 done in 0.013 seconds
2017-06-14 23:15:06 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 23:15:06 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:15:06 PDT INFO remoteWorkerThread_3: SYNC 5000000089 done in 0.013 seconds
2017-06-14 23:15:14 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 23:15:14 PDT CONFIG version for "host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:15:17 PDT INFO remoteWorkerThread_3: SYNC 5000000090 done in 2.438 seconds
2017-06-14 23:15:20 PDT INFO remoteWorkerThread_2: SYNC 5000000104 done in 0.003 seconds
2017-06-14 23:15:21 PDT INFO remoteWorkerThread_2: SYNC 5000000105 done in 0.002 seconds
2017-06-14 23:15:25 PDT INFO remoteWorkerThread_3: SYNC 5000000091 done in 0.016 seconds
2017-06-14 23:15:32 PDT INFO remoteWorkerThread_2: SYNC 5000000106 done in 0.002 seconds
2017-06-14 23:15:36 PDT INFO remoteWorkerThread_3: SYNC 5000000092 done in 0.005 seconds
2017-06-14 23:15:43 PDT INFO remoteWorkerThread_2: SYNC 5000000107 done in 0.003 seconds
2017-06-14 23:15:47 PDT INFO remoteWorkerThread_3: SYNC 5000000093 done in 0.006 seconds
2017-06-14 23:15:55 PDT INFO remoteWorkerThread_2: SYNC 5000000108 done in 0.003 seconds
2017-06-14 23:15:55 PDT INFO remoteWorkerThread_3: SYNC 5000000094 done in 0.013 seconds
2017-06-14 23:16:06 PDT INFO remoteWorkerThread_2: SYNC 5000000109 done in 0.003 seconds
2017-06-14 23:16:10 PDT INFO remoteWorkerThread_3: SYNC 5000000095 done in 0.004 seconds
2017-06-14 23:16:11 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:16:11 PDT CONFIG version for "host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:16:11 PDT INFO remoteWorkerThread_2: SYNC 5000000110 done in 0.058 seconds
2017-06-14 23:16:12 PDT INFO remoteWorkerThread_3: SYNC 5000000096 done in 0.012 seconds
2017-06-14 23:16:27 PDT INFO remoteWorkerThread_2: SYNC 5000000111 done in 0.008 seconds
2017-06-14 23:16:31 PDT INFO remoteWorkerThread_2: SYNC 5000000112 done in 0.013 seconds
2017-06-14 23:16:31 PDT INFO remoteWorkerThread_3: SYNC 5000000097 done in 0.037 seconds
2017-06-14 23:16:42 PDT INFO remoteWorkerThread_3: SYNC 5000000098 done in 0.006 seconds
2017-06-14 23:16:42 PDT INFO remoteWorkerThread_2: SYNC 5000000113 done in 0.006 seconds
2017-06-14 23:16:53 PDT INFO remoteWorkerThread_3: SYNC 5000000099 done in 0.005 seconds
2017-06-14 23:16:53 PDT INFO remoteWorkerThread_2: SYNC 5000000114 done in 0.005 seconds
2017-06-14 23:17:04 PDT INFO remoteWorkerThread_3: SYNC 5000000100 done in 0.005 seconds
2017-06-14 23:17:04 PDT INFO remoteWorkerThread_2: SYNC 5000000115 done in 0.006 seconds
2017-06-14 23:17:08 PDT INFO remoteWorkerThread_3: SYNC 5000000101 done in 0.005 seconds
2017-06-14 23:17:14 PDT INFO remoteWorkerThread_2: SYNC 5000000116 done in 0.004 seconds
2017-06-14 23:17:19 PDT INFO remoteWorkerThread_3: SYNC 5000000102 done in 0.005 seconds
2017-06-14 23:17:25 PDT INFO remoteWorkerThread_2: SYNC 5000000117 done in 0.005 seconds
2017-06-14 23:17:30 PDT INFO remoteWorkerThread_3: SYNC 5000000103 done in 0.005 seconds
2017-06-14 23:17:34 PDT INFO remoteWorkerThread_2: SYNC 5000000118 done in 0.013 seconds
2017-06-14 23:17:38 PDT INFO remoteWorkerThread_3: SYNC 5000000104 done in 0.012 seconds
2017-06-14 23:17:43 PDT INFO remoteWorkerThread_2: SYNC 5000000119 done in 0.005 seconds
2017-06-14 23:17:49 PDT INFO remoteWorkerThread_3: SYNC 5000000105 done in 0.004 seconds
2017-06-14 23:17:53 PDT INFO remoteWorkerThread_2: SYNC 5000000120 done in 0.005 seconds
2017-06-14 23:18:01 PDT INFO remoteWorkerThread_2: SYNC 5000000121 done in 0.005 seconds
2017-06-14 23:18:01 PDT INFO remoteWorkerThread_3: SYNC 5000000106 done in 0.006 seconds
2017-06-14 23:18:14 PDT INFO remoteWorkerThread_2: SYNC 5000000122 done in 0.005 seconds
2017-06-14 23:18:14 PDT INFO remoteWorkerThread_3: SYNC 5000000107 done in 0.006 seconds
2017-06-14 23:18:27 PDT INFO remoteWorkerThread_2: SYNC 5000000123 done in 0.005 seconds
2017-06-14 23:18:27 PDT INFO remoteWorkerThread_3: SYNC 5000000108 done in 0.006 seconds
2017-06-14 23:18:30 PDT INFO remoteWorkerThread_3: SYNC 5000000109 done in 0.005 seconds
2017-06-14 23:18:33 PDT INFO remoteWorkerThread_2: SYNC 5000000124 done in 0.004 seconds
2017-06-14 23:18:38 PDT INFO remoteWorkerThread_3: SYNC 5000000110 done in 0.004 seconds
2017-06-14 23:18:44 PDT INFO remoteWorkerThread_2: SYNC 5000000125 done in 0.004 seconds
2017-06-14 23:18:49 PDT INFO remoteWorkerThread_3: SYNC 5000000111 done in 0.005 seconds
2017-06-14 23:18:55 PDT INFO remoteWorkerThread_2: SYNC 5000000126 done in 0.005 seconds
2017-06-14 23:19:00 PDT INFO remoteWorkerThread_3: SYNC 5000000112 done in 0.011 seconds
2017-06-14 23:19:04 PDT INFO remoteWorkerThread_2: SYNC 5000000127 done in 0.005 seconds
2017-06-14 23:19:08 PDT INFO remoteWorkerThread_3: SYNC 5000000113 done in 0.005 seconds
2017-06-14 23:19:12 PDT INFO remoteWorkerThread_2: SYNC 5000000128 done in 0.005 seconds
2017-06-14 23:19:20 PDT INFO remoteWorkerThread_3: SYNC 5000000114 done in 0.004 seconds
2017-06-14 23:19:21 PDT INFO remoteWorkerThread_2: SYNC 5000000129 done in 0.004 seconds
2017-06-14 23:19:34 PDT INFO remoteWorkerThread_3: SYNC 5000000115 done in 0.005 seconds
2017-06-14 23:19:34 PDT INFO remoteWorkerThread_2: SYNC 5000000130 done in 0.006 seconds
2017-06-14 23:19:47 PDT INFO remoteWorkerThread_2: SYNC 5000000131 done in 0.005 seconds
2017-06-14 23:19:47 PDT INFO remoteWorkerThread_3: SYNC 5000000116 done in 0.005 seconds
2017-06-14 23:19:48 PDT INFO remoteWorkerThread_3: SYNC 5000000117 done in 0.005 seconds
2017-06-14 23:19:51 PDT INFO remoteWorkerThread_2: SYNC 5000000132 done in 0.005 seconds
2017-06-14 23:20:02 PDT INFO remoteWorkerThread_3: SYNC 5000000118 done in 0.005 seconds
2017-06-14 23:20:02 PDT INFO remoteWorkerThread_2: SYNC 5000000133 done in 0.006 seconds
2017-06-14 23:20:16 PDT INFO remoteWorkerThread_3: SYNC 5000000119 done in 0.005 seconds
2017-06-14 23:20:16 PDT INFO remoteWorkerThread_2: SYNC 5000000134 done in 0.006 seconds
2017-06-14 23:20:19 PDT INFO remoteWorkerThread_3: SYNC 5000000120 done in 0.005 seconds
2017-06-14 23:20:22 PDT INFO remoteWorkerThread_2: SYNC 5000000135 done in 0.005 seconds
2017-06-14 23:20:35 PDT INFO remoteWorkerThread_3: SYNC 5000000121 done in 0.005 seconds
2017-06-14 23:20:35 PDT INFO remoteWorkerThread_2: SYNC 5000000136 done in 0.006 seconds
2017-06-14 23:20:38 PDT INFO remoteWorkerThread_3: SYNC 5000000122 done in 0.005 seconds
2017-06-14 23:20:41 PDT INFO remoteWorkerThread_2: SYNC 5000000137 done in 0.005 seconds
- 辅节点1查看Slony-I日志信息
$ cat /usr/local/pgsql/data/pg_log/node2/region_il-2017-06-14.log
2017-06-14 22:57:41 PDT CONFIG main: slon version 2.2.4 starting up
2017-06-14 22:57:41 PDT INFO slon: watchdog process started
2017-06-14 22:57:41 PDT CONFIG slon: watchdog ready - pid = 15535
2017-06-14 22:57:41 PDT CONFIG slon: worker process created - pid = 15536
2017-06-14 22:57:41 PDT CONFIG main: Integer option vac_frequency = 3
2017-06-14 22:57:41 PDT CONFIG main: Integer option log_level = 0
2017-06-14 22:57:41 PDT CONFIG main: Integer option sync_interval = 1000
2017-06-14 22:57:41 PDT CONFIG main: Integer option sync_interval_timeout = 10000
2017-06-14 22:57:41 PDT CONFIG main: Integer option sync_group_maxsize = 20
2017-06-14 22:57:41 PDT CONFIG main: Integer option syslog = 0
2017-06-14 22:57:41 PDT CONFIG main: Integer option quit_sync_provider = 0
2017-06-14 22:57:41 PDT CONFIG main: Integer option remote_listen_timeout = 300
2017-06-14 22:57:41 PDT CONFIG main: Integer option monitor_interval = 500
2017-06-14 22:57:41 PDT CONFIG main: Integer option explain_interval = 0
2017-06-14 22:57:41 PDT CONFIG main: Integer option tcp_keepalive_idle = 0
2017-06-14 22:57:41 PDT CONFIG main: Integer option tcp_keepalive_interval = 0
2017-06-14 22:57:41 PDT CONFIG main: Integer option tcp_keepalive_count = 0
2017-06-14 22:57:41 PDT CONFIG main: Integer option apply_cache_size = 100
2017-06-14 22:57:41 PDT CONFIG main: Boolean option log_pid = 0
2017-06-14 22:57:41 PDT CONFIG main: Boolean option log_timestamp = 1
2017-06-14 22:57:41 PDT CONFIG main: Boolean option tcp_keepalive = 1
2017-06-14 22:57:41 PDT CONFIG main: Boolean option monitor_threads = 1
2017-06-14 22:57:41 PDT CONFIG main: Real option real_placeholder = 0.000000
2017-06-14 22:57:41 PDT CONFIG main: String option cluster_name = region_il
2017-06-14 22:57:41 PDT CONFIG main: String option conn_info = host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888
2017-06-14 22:57:41 PDT CONFIG main: String option pid_file = /usr/local/pgsql/data/region_il_node2.pid
2017-06-14 22:57:41 PDT CONFIG main: String option log_timestamp_format = %Y-%m-%d %H:%M:%S %Z
2017-06-14 22:57:41 PDT CONFIG main: String option archive_dir = [NULL]
2017-06-14 22:57:41 PDT CONFIG main: String option sql_on_connection = [NULL]
2017-06-14 22:57:41 PDT CONFIG main: String option lag_interval = [NULL]
2017-06-14 22:57:41 PDT CONFIG main: String option command_on_logarchive = [NULL]
2017-06-14 22:57:41 PDT CONFIG main: String option syslog_facility = LOCAL0
2017-06-14 22:57:41 PDT CONFIG main: String option syslog_ident = slon
2017-06-14 22:57:41 PDT CONFIG main: String option cleanup_interval = 10 minutes
2017-06-14 22:57:41 PDT CONFIG main: local node id = 2
2017-06-14 22:57:41 PDT INFO main: main process started
2017-06-14 22:57:41 PDT CONFIG main: launching sched_start_mainloop
2017-06-14 22:57:41 PDT CONFIG main: loading current cluster configuration
2017-06-14 22:57:41 PDT CONFIG storeNode: no_id=1 no_comment='Node 1 - region_il@10.189.102.118'
2017-06-14 22:57:41 PDT CONFIG storeNode: no_id=3 no_comment='<event pending>'
2017-06-14 22:57:41 PDT CONFIG storePath: pa_server=1 pa_client=2 pa_conninfo="host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" pa_connretry=10
2017-06-14 22:57:41 PDT CONFIG storePath: pa_server=3 pa_client=2 pa_conninfo="host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888" pa_connretry=10
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=1 li_receiver=2 li_provider=1
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=3 li_receiver=2 li_provider=3
2017-06-14 22:57:41 PDT CONFIG main: last local event sequence = 5000000002
2017-06-14 22:57:41 PDT CONFIG main: configuration complete - starting threads
2017-06-14 22:57:41 PDT INFO localListenThread: thread starts
2017-06-14 22:57:41 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 22:57:41 PDT CONFIG enableNode: no_id=1
2017-06-14 22:57:41 PDT INFO main: running scheduler mainloop
2017-06-14 22:57:41 PDT CONFIG cleanupThread: thread starts
2017-06-14 22:57:41 PDT INFO syncThread: thread starts
2017-06-14 22:57:41 PDT INFO monitorThread: thread starts
2017-06-14 22:57:41 PDT INFO remoteListenThread_1: thread starts
2017-06-14 22:57:41 PDT INFO remoteWorkerThread_1: thread starts
2017-06-14 22:57:41 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 22:57:41 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 22:57:41 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 22:57:41 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 22:57:41 PDT CONFIG cleanupThread: bias = 60
2017-06-14 22:57:41 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 22:57:41 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=1 li_receiver=2 li_provider=1
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=3 li_receiver=2 li_provider=3
2017-06-14 22:57:41 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=1 li_receiver=2 li_provider=1
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=3 li_receiver=2 li_provider=3
2017-06-14 22:57:41 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 22:57:41 PDT CONFIG storeNode: no_id=3 no_comment='Node 3 - region_il@10.189.100.226' - update node
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=1 li_receiver=2 li_provider=1
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=3 li_receiver=2 li_provider=3
2017-06-14 22:57:41 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 22:57:41 PDT CONFIG enableNode: no_id=3
2017-06-14 22:57:41 PDT INFO remoteListenThread_3: thread starts
2017-06-14 22:57:41 PDT INFO remoteWorkerThread_3: thread starts
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=1 li_receiver=2 li_provider=1
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=3 li_receiver=2 li_provider=3
2017-06-14 22:57:41 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 22:57:41 PDT CONFIG version for "host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 22:57:41 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 22:57:41 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=1 li_receiver=2 li_provider=1
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=3 li_receiver=2 li_provider=3
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=3 li_receiver=2 li_provider=1
2017-06-14 22:57:41 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 22:57:41 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=1 li_receiver=2 li_provider=1
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=3 li_receiver=2 li_provider=3
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=3 li_receiver=2 li_provider=1
2017-06-14 22:57:41 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 22:57:41 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 22:57:41 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 22:57:41 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 22:57:41 PDT INFO remoteWorkerThread_1: SYNC 5000000008 done in 0.015 seconds
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=1 li_receiver=2 li_provider=1
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=3 li_receiver=2 li_provider=3
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=3 li_receiver=2 li_provider=1
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=1 li_receiver=2 li_provider=3
2017-06-14 22:57:41 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 22:57:41 PDT INFO remoteWorkerThread_1: SYNC 5000000010 done in 0.002 seconds
2017-06-14 22:57:41 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 22:57:41 PDT CONFIG remoteWorkerThread_1: connection for provider 1 terminated
2017-06-14 22:57:41 PDT CONFIG remoteWorkerThread_1: disconnecting from data provider 1
2017-06-14 22:57:41 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=1 li_receiver=2 li_provider=1
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=3 li_receiver=2 li_provider=3
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=3 li_receiver=2 li_provider=1
2017-06-14 22:57:41 PDT CONFIG storeListen: li_origin=1 li_receiver=2 li_provider=3
2017-06-14 22:57:41 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 22:57:41 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 22:57:41 PDT INFO remoteWorkerThread_1: SYNC 5000000014 done in 0.011 seconds
2017-06-14 22:57:41 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 22:57:41 PDT CONFIG remoteWorkerThread_1: connection for provider 1 terminated
2017-06-14 22:57:41 PDT CONFIG remoteWorkerThread_1: disconnecting from data provider 1
2017-06-14 22:57:41 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 22:57:41 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 22:57:41 PDT INFO remoteWorkerThread_1: SYNC 5000000022 done in 0.012 seconds
2017-06-14 22:57:41 PDT INFO remoteWorkerThread_1: SYNC 5000000025 done in 0.002 seconds
2017-06-14 22:57:45 PDT INFO remoteWorkerThread_1: SYNC 5000000026 done in 0.003 seconds
2017-06-14 22:57:56 PDT INFO remoteWorkerThread_1: SYNC 5000000027 done in 0.003 seconds
2017-06-14 22:58:10 PDT INFO remoteWorkerThread_1: SYNC 5000000028 done in 0.003 seconds
2017-06-14 22:58:14 PDT INFO remoteWorkerThread_1: SYNC 5000000029 done in 0.003 seconds
2017-06-14 22:58:28 PDT INFO remoteWorkerThread_1: SYNC 5000000030 done in 0.003 seconds
2017-06-14 22:58:39 PDT INFO remoteWorkerThread_1: SYNC 5000000031 done in 0.003 seconds
2017-06-14 22:58:43 PDT INFO remoteWorkerThread_1: SYNC 5000000032 done in 0.003 seconds
2017-06-14 22:59:01 PDT INFO remoteWorkerThread_1: SYNC 5000000033 done in 0.003 seconds
2017-06-14 22:59:05 PDT INFO remoteWorkerThread_1: SYNC 5000000034 done in 0.003 seconds
2017-06-14 22:59:19 PDT INFO remoteWorkerThread_1: SYNC 5000000035 done in 0.003 seconds
2017-06-14 22:59:23 PDT INFO remoteWorkerThread_1: SYNC 5000000036 done in 0.002 seconds
2017-06-14 22:59:41 PDT INFO remoteWorkerThread_1: SYNC 5000000037 done in 0.003 seconds
2017-06-14 22:59:45 PDT INFO remoteWorkerThread_1: SYNC 5000000038 done in 0.002 seconds
2017-06-14 22:59:59 PDT INFO remoteWorkerThread_1: SYNC 5000000039 done in 0.003 seconds
2017-06-14 23:00:03 PDT INFO remoteWorkerThread_1: SYNC 5000000040 done in 0.003 seconds
2017-06-14 23:00:21 PDT INFO remoteWorkerThread_1: SYNC 5000000041 done in 0.003 seconds
2017-06-14 23:00:25 PDT INFO remoteWorkerThread_1: SYNC 5000000042 done in 0.002 seconds
2017-06-14 23:00:39 PDT INFO remoteWorkerThread_1: SYNC 5000000043 done in 0.003 seconds
2017-06-14 23:00:43 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 23:00:43 PDT INFO remoteWorkerThread_1: SYNC 5000000044 done in 0.003 seconds
2017-06-14 23:00:43 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:00:43 PDT INFO remoteWorkerThread_3: SYNC 5000000003 done in 0.014 seconds
2017-06-14 23:00:56 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 23:00:56 PDT CONFIG version for "host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:00:56 PDT INFO remoteWorkerThread_3: SYNC 5000000004 done in 0.022 seconds
2017-06-14 23:01:00 PDT INFO remoteWorkerThread_3: SYNC 5000000005 done in 0.013 seconds
2017-06-14 23:01:01 PDT INFO remoteWorkerThread_1: SYNC 5000000045 done in 0.003 seconds
2017-06-14 23:01:04 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:01:04 PDT CONFIG version for "host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:01:04 PDT INFO remoteWorkerThread_1: SYNC 5000000046 done in 0.014 seconds
2017-06-14 23:01:15 PDT INFO remoteWorkerThread_3: SYNC 5000000006 done in 0.005 seconds
2017-06-14 23:01:15 PDT INFO remoteWorkerThread_1: SYNC 5000000047 done in 0.006 seconds
2017-06-14 23:01:19 PDT INFO remoteWorkerThread_3: SYNC 5000000007 done in 0.004 seconds
2017-06-14 23:01:26 PDT INFO remoteWorkerThread_1: SYNC 5000000048 done in 0.005 seconds
2017-06-14 23:01:30 PDT INFO remoteWorkerThread_3: SYNC 5000000008 done in 0.005 seconds
2017-06-14 23:01:37 PDT INFO remoteWorkerThread_1: SYNC 5000000049 done in 0.005 seconds
2017-06-14 23:01:41 PDT INFO remoteWorkerThread_3: SYNC 5000000009 done in 0.005 seconds
2017-06-14 23:01:49 PDT INFO remoteWorkerThread_1: SYNC 5000000050 done in 0.013 seconds
2017-06-14 23:01:53 PDT INFO remoteWorkerThread_3: SYNC 5000000010 done in 0.005 seconds
2017-06-14 23:01:53 PDT INFO remoteWorkerThread_1: SYNC 5000000051 done in 0.006 seconds
2017-06-14 23:02:01 PDT INFO remoteWorkerThread_3: SYNC 5000000011 done in 0.006 seconds
2017-06-14 23:02:07 PDT INFO remoteWorkerThread_1: SYNC 5000000052 done in 0.005 seconds
2017-06-14 23:02:11 PDT INFO remoteWorkerThread_3: SYNC 5000000012 done in 0.005 seconds
2017-06-14 23:02:15 PDT INFO remoteWorkerThread_1: SYNC 5000000053 done in 0.005 seconds
2017-06-14 23:02:19 PDT INFO remoteWorkerThread_3: SYNC 5000000013 done in 0.005 seconds
2017-06-14 23:02:26 PDT INFO remoteWorkerThread_1: SYNC 5000000054 done in 0.005 seconds
2017-06-14 23:02:30 PDT INFO remoteWorkerThread_3: SYNC 5000000014 done in 0.005 seconds
2017-06-14 23:02:37 PDT INFO remoteWorkerThread_1: SYNC 5000000055 done in 0.005 seconds
2017-06-14 23:02:41 PDT INFO remoteWorkerThread_3: SYNC 5000000015 done in 0.005 seconds
2017-06-14 23:02:45 PDT INFO remoteWorkerThread_1: SYNC 5000000056 done in 0.006 seconds
2017-06-14 23:02:49 PDT INFO remoteWorkerThread_3: SYNC 5000000016 done in 0.006 seconds
2017-06-14 23:02:55 PDT INFO remoteWorkerThread_1: SYNC 5000000057 done in 0.005 seconds
2017-06-14 23:03:00 PDT INFO remoteWorkerThread_3: SYNC 5000000017 done in 0.005 seconds
2017-06-14 23:03:06 PDT INFO remoteWorkerThread_1: SYNC 5000000058 done in 0.009 seconds
2017-06-14 23:03:11 PDT INFO remoteWorkerThread_3: SYNC 5000000018 done in 0.004 seconds
2017-06-14 23:03:17 PDT CONFIG storeSet: set_id=1 set_origin=1 set_comment='Set 1 (il_longlive_schema) for region_il'
2017-06-14 23:03:17 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:03:17 PDT CONFIG remoteWorkerThread_1: connection for provider 1 terminated
2017-06-14 23:03:17 PDT CONFIG remoteWorkerThread_1: disconnecting from data provider 1
2017-06-14 23:03:17 PDT CONFIG remoteWorkerThread_1: connection for provider 3 terminated
2017-06-14 23:03:17 PDT CONFIG remoteWorkerThread_1: disconnecting from data provider 3
2017-06-14 23:03:17 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:03:17 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:03:17 PDT INFO remoteWorkerThread_1: SYNC 5000000060 done in 0.014 seconds
2017-06-14 23:03:17 PDT INFO remoteWorkerThread_1: SYNC 5000000062 done in 0.002 seconds
2017-06-14 23:03:17 PDT INFO remoteWorkerThread_1: SYNC 5000000064 done in 0.002 seconds
2017-06-14 23:03:17 PDT INFO remoteWorkerThread_1: SYNC 5000000066 done in 0.002 seconds
2017-06-14 23:03:17 PDT INFO remoteWorkerThread_1: SYNC 5000000068 done in 0.002 seconds
2017-06-14 23:03:17 PDT INFO remoteWorkerThread_1: SYNC 5000000070 done in 0.002 seconds
2017-06-14 23:03:17 PDT INFO remoteWorkerThread_1: SYNC 5000000072 done in 0.002 seconds
2017-06-14 23:03:17 PDT INFO remoteWorkerThread_1: SYNC 5000000074 done in 0.002 seconds
2017-06-14 23:03:17 PDT INFO remoteWorkerThread_1: SYNC 5000000076 done in 0.002 seconds
2017-06-14 23:03:21 PDT INFO remoteWorkerThread_3: SYNC 5000000019 done in 0.005 seconds
2017-06-14 23:03:25 PDT INFO remoteWorkerThread_1: SYNC 5000000077 done in 0.002 seconds
2017-06-14 23:03:29 PDT INFO remoteWorkerThread_3: SYNC 5000000020 done in 0.005 seconds
2017-06-14 23:03:36 PDT INFO remoteWorkerThread_1: SYNC 5000000078 done in 0.003 seconds
2017-06-14 23:03:40 PDT INFO remoteWorkerThread_3: SYNC 5000000021 done in 0.005 seconds
2017-06-14 23:03:44 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:03:44 PDT CONFIG version for "host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:03:44 PDT INFO remoteWorkerThread_1: SYNC 5000000079 done in 0.015 seconds
2017-06-14 23:03:54 PDT INFO remoteWorkerThread_1: SYNC 5000000080 done in 0.005 seconds
2017-06-14 23:03:58 PDT INFO remoteWorkerThread_3: SYNC 5000000022 done in 0.005 seconds
2017-06-14 23:04:01 PDT INFO remoteWorkerThread_3: SYNC 5000000023 done in 0.005 seconds
2017-06-14 23:04:06 PDT INFO remoteWorkerThread_1: SYNC 5000000081 done in 0.005 seconds
2017-06-14 23:04:12 PDT INFO remoteWorkerThread_3: SYNC 5000000024 done in 0.005 seconds
2017-06-14 23:04:16 PDT INFO remoteWorkerThread_1: SYNC 5000000082 done in 0.005 seconds
2017-06-14 23:04:20 PDT INFO remoteWorkerThread_3: SYNC 5000000025 done in 0.005 seconds
2017-06-14 23:04:24 PDT INFO remoteWorkerThread_1: SYNC 5000000083 done in 0.005 seconds
2017-06-14 23:04:32 PDT INFO remoteWorkerThread_3: SYNC 5000000026 done in 0.005 seconds
2017-06-14 23:04:33 PDT INFO remoteWorkerThread_1: SYNC 5000000084 done in 0.004 seconds
2017-06-14 23:04:44 PDT INFO remoteWorkerThread_3: SYNC 5000000027 done in 0.005 seconds
2017-06-14 23:04:44 PDT INFO remoteWorkerThread_1: SYNC 5000000085 done in 0.006 seconds
2017-06-14 23:04:54 PDT INFO remoteWorkerThread_3: SYNC 5000000028 done in 0.005 seconds
2017-06-14 23:04:58 PDT INFO remoteWorkerThread_1: SYNC 5000000086 done in 0.005 seconds
2017-06-14 23:05:01 PDT INFO remoteWorkerThread_3: SYNC 5000000029 done in 0.006 seconds
2017-06-14 23:05:05 PDT INFO remoteWorkerThread_1: SYNC 5000000087 done in 0.005 seconds
2017-06-14 23:05:13 PDT INFO remoteWorkerThread_3: SYNC 5000000030 done in 0.005 seconds
2017-06-14 23:05:14 PDT INFO remoteWorkerThread_1: SYNC 5000000088 done in 0.005 seconds
2017-06-14 23:05:25 PDT INFO remoteWorkerThread_3: SYNC 5000000031 done in 0.005 seconds
2017-06-14 23:05:25 PDT INFO remoteWorkerThread_1: SYNC 5000000089 done in 0.006 seconds
2017-06-14 23:05:29 PDT INFO remoteWorkerThread_3: SYNC 5000000032 done in 0.005 seconds
2017-06-14 23:05:36 PDT INFO remoteWorkerThread_1: SYNC 5000000090 done in 0.006 seconds
2017-06-14 23:05:40 PDT INFO remoteWorkerThread_3: SYNC 5000000033 done in 0.004 seconds
2017-06-14 23:05:47 PDT INFO remoteWorkerThread_1: SYNC 5000000091 done in 0.005 seconds
2017-06-14 23:05:51 PDT INFO remoteWorkerThread_3: SYNC 5000000034 done in 0.004 seconds
2017-06-14 23:05:58 PDT INFO remoteWorkerThread_1: SYNC 5000000092 done in 0.005 seconds
2017-06-14 23:06:02 PDT INFO remoteWorkerThread_3: SYNC 5000000035 done in 0.005 seconds
2017-06-14 23:06:09 PDT INFO remoteWorkerThread_1: SYNC 5000000093 done in 0.005 seconds
2017-06-14 23:06:13 PDT INFO remoteWorkerThread_1: SYNC 5000000094 done in 0.005 seconds
2017-06-14 23:06:13 PDT INFO remoteWorkerThread_3: SYNC 5000000036 done in 0.004 seconds
2017-06-14 23:06:21 PDT INFO remoteWorkerThread_3: SYNC 5000000037 done in 0.004 seconds
2017-06-14 23:06:27 PDT INFO remoteWorkerThread_1: SYNC 5000000095 done in 0.004 seconds
2017-06-14 23:06:31 PDT INFO remoteWorkerThread_3: SYNC 5000000038 done in 0.005 seconds
2017-06-14 23:06:35 PDT INFO remoteWorkerThread_1: SYNC 5000000096 done in 0.005 seconds
2017-06-14 23:06:39 PDT INFO remoteWorkerThread_3: SYNC 5000000039 done in 0.004 seconds
2017-06-14 23:06:46 PDT INFO remoteWorkerThread_1: SYNC 5000000097 done in 0.005 seconds
2017-06-14 23:06:50 PDT INFO remoteWorkerThread_3: SYNC 5000000040 done in 0.004 seconds
2017-06-14 23:06:57 PDT INFO remoteWorkerThread_1: SYNC 5000000098 done in 0.005 seconds
2017-06-14 23:07:01 PDT INFO remoteWorkerThread_3: SYNC 5000000041 done in 0.005 seconds
2017-06-14 23:07:08 PDT INFO remoteWorkerThread_1: SYNC 5000000099 done in 0.004 seconds
2017-06-14 23:07:12 PDT INFO remoteWorkerThread_3: SYNC 5000000042 done in 0.005 seconds
2017-06-14 23:07:13 PDT INFO remoteWorkerThread_1: SYNC 5000000100 done in 0.004 seconds
2017-06-14 23:07:22 PDT INFO remoteWorkerThread_3: SYNC 5000000043 done in 0.005 seconds
2017-06-14 23:07:27 PDT INFO remoteWorkerThread_1: SYNC 5000000101 done in 0.030 seconds
2017-06-14 23:07:31 PDT INFO remoteWorkerThread_3: SYNC 5000000044 done in 0.005 seconds
2017-06-14 23:07:35 PDT INFO remoteWorkerThread_1: SYNC 5000000102 done in 0.004 seconds
2017-06-14 23:07:40 PDT INFO remoteWorkerThread_3: SYNC 5000000045 done in 0.009 seconds
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=15654
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=15655
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=15658
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: Logswitch to sl_log_2 initiated
CONTEXT: SQL statement "SELECT "_region_il".logswitch_start()"
PL/pgSQL function _region_il.cleanupevent(interval) line 97 at PERFORM
2017-06-14 23:07:42 PDT INFO cleanupThread: 0.019 seconds for cleanupEvent()
2017-06-14 23:07:46 PDT INFO remoteWorkerThread_1: SYNC 5000000103 done in 0.009 seconds
2017-06-14 23:07:51 PDT INFO remoteWorkerThread_3: SYNC 5000000046 done in 0.005 seconds
2017-06-14 23:07:57 PDT INFO remoteWorkerThread_1: SYNC 5000000104 done in 0.010 seconds
2017-06-14 23:08:02 PDT INFO remoteWorkerThread_3: SYNC 5000000047 done in 0.009 seconds
2017-06-14 23:08:08 PDT INFO remoteWorkerThread_1: SYNC 5000000105 done in 0.012 seconds
2017-06-14 23:08:13 PDT INFO remoteWorkerThread_1: SYNC 5000000106 done in 0.008 seconds
2017-06-14 23:08:13 PDT INFO remoteWorkerThread_3: SYNC 5000000048 done in 0.013 seconds
2017-06-14 23:08:24 PDT INFO remoteWorkerThread_3: SYNC 5000000049 done in 0.009 seconds
2017-06-14 23:08:24 PDT INFO remoteWorkerThread_1: SYNC 5000000107 done in 0.010 seconds
2017-06-14 23:08:35 PDT INFO remoteWorkerThread_3: SYNC 5000000050 done in 0.005 seconds
2017-06-14 23:08:39 PDT INFO remoteWorkerThread_1: SYNC 5000000108 done in 0.006 seconds
2017-06-14 23:08:39 PDT INFO remoteWorkerThread_3: SYNC 5000000051 done in 0.005 seconds
2017-06-14 23:08:43 PDT INFO remoteWorkerThread_1: SYNC 5000000109 done in 0.005 seconds
2017-06-14 23:08:54 PDT INFO remoteWorkerThread_3: SYNC 5000000052 done in 0.006 seconds
2017-06-14 23:08:54 PDT INFO remoteWorkerThread_1: SYNC 5000000110 done in 0.006 seconds
2017-06-14 23:09:04 PDT INFO remoteWorkerThread_3: SYNC 5000000053 done in 0.005 seconds
2017-06-14 23:09:08 PDT INFO remoteWorkerThread_1: SYNC 5000000111 done in 0.005 seconds
2017-06-14 23:09:11 PDT INFO remoteWorkerThread_3: SYNC 5000000054 done in 0.005 seconds
2017-06-14 23:09:16 PDT INFO remoteWorkerThread_1: SYNC 5000000112 done in 0.005 seconds
2017-06-14 23:09:22 PDT INFO remoteWorkerThread_3: SYNC 5000000055 done in 0.005 seconds
2017-06-14 23:09:27 PDT INFO remoteWorkerThread_1: SYNC 5000000113 done in 0.005 seconds
2017-06-14 23:09:33 PDT INFO remoteWorkerThread_3: SYNC 5000000056 done in 0.005 seconds
2017-06-14 23:09:34 PDT INFO remoteWorkerThread_1: SYNC 5000000114 done in 0.005 seconds
2017-06-14 23:09:45 PDT INFO remoteWorkerThread_1: SYNC 5000000115 done in 0.005 seconds
2017-06-14 23:09:48 PDT INFO remoteWorkerThread_3: SYNC 5000000057 done in 0.005 seconds
2017-06-14 23:09:49 PDT INFO remoteWorkerThread_3: SYNC 5000000058 done in 0.005 seconds
2017-06-14 23:09:59 PDT INFO remoteWorkerThread_1: SYNC 5000000116 done in 0.005 seconds
2017-06-14 23:10:00 PDT INFO remoteWorkerThread_3: SYNC 5000000059 done in 0.005 seconds
2017-06-14 23:10:04 PDT INFO remoteWorkerThread_1: SYNC 5000000117 done in 0.005 seconds
2017-06-14 23:10:10 PDT INFO remoteWorkerThread_3: SYNC 5000000060 done in 0.005 seconds
2017-06-14 23:10:14 PDT INFO remoteWorkerThread_1: SYNC 5000000118 done in 0.005 seconds
2017-06-14 23:10:22 PDT INFO remoteWorkerThread_3: SYNC 5000000061 done in 0.005 seconds
2017-06-14 23:10:28 PDT INFO remoteWorkerThread_1: SYNC 5000000119 done in 0.005 seconds
2017-06-14 23:10:33 PDT INFO remoteWorkerThread_3: SYNC 5000000062 done in 0.005 seconds
2017-06-14 23:10:39 PDT INFO remoteWorkerThread_1: SYNC 5000000120 done in 0.004 seconds
2017-06-14 23:10:43 PDT INFO remoteWorkerThread_3: SYNC 5000000063 done in 0.005 seconds
2017-06-14 23:10:43 PDT INFO remoteWorkerThread_1: SYNC 5000000121 done in 0.005 seconds
2017-06-14 23:10:52 PDT INFO remoteWorkerThread_3: SYNC 5000000064 done in 0.005 seconds
2017-06-14 23:10:57 PDT INFO remoteWorkerThread_1: SYNC 5000000122 done in 0.004 seconds
2017-06-14 23:11:03 PDT INFO remoteWorkerThread_3: SYNC 5000000065 done in 0.005 seconds
2017-06-14 23:11:08 PDT INFO remoteWorkerThread_1: SYNC 5000000123 done in 0.005 seconds
2017-06-14 23:11:14 PDT INFO remoteWorkerThread_3: SYNC 5000000066 done in 0.005 seconds
2017-06-14 23:11:19 PDT INFO remoteWorkerThread_1: SYNC 5000000124 done in 0.005 seconds
2017-06-14 23:11:25 PDT INFO remoteWorkerThread_3: SYNC 5000000067 done in 0.005 seconds
2017-06-14 23:11:30 PDT INFO remoteWorkerThread_1: SYNC 5000000125 done in 0.005 seconds
2017-06-14 23:11:34 PDT INFO remoteWorkerThread_1: SYNC 5000000126 done in 0.005 seconds
2017-06-14 23:11:36 PDT INFO remoteWorkerThread_3: SYNC 5000000068 done in 0.005 seconds
2017-06-14 23:11:40 PDT INFO remoteWorkerThread_3: SYNC 5000000069 done in 0.004 seconds
2017-06-14 23:11:45 PDT INFO remoteWorkerThread_1: SYNC 5000000127 done in 0.005 seconds
2017-06-14 23:11:51 PDT INFO remoteWorkerThread_3: SYNC 5000000070 done in 0.004 seconds
2017-06-14 23:11:56 PDT CONFIG storeSubscribe: sub_set=1 sub_provider=1 sub_forward='t'
2017-06-14 23:11:56 PDT CONFIG storeListen: li_origin=1 li_receiver=2 li_provider=1
2017-06-14 23:11:56 PDT CONFIG storeListen: li_origin=3 li_receiver=2 li_provider=3
2017-06-14 23:11:56 PDT CONFIG storeListen: li_origin=3 li_receiver=2 li_provider=1
2017-06-14 23:11:56 PDT CONFIG storeListen: li_origin=1 li_receiver=2 li_provider=3
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_1: connection for provider 1 terminated
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_1: disconnecting from data provider 1
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_1: connection for provider 3 terminated
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_1: disconnecting from data provider 3
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_3: connection for provider 1 terminated
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_3: disconnecting from data provider 1
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_3: connection for provider 3 terminated
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_3: disconnecting from data provider 3
2017-06-14 23:11:56 PDT INFO copy_set 1 - omit=f - bool=0
2017-06-14 23:11:56 PDT INFO omit is FALSE
2017-06-14 23:11:56 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_1: connected to provider DB
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_1: prepare to copy table "il_longlive"."entity"
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_1: prepare to copy table "il_longlive"."entity_result"
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_1: prepare to copy table "il_longlive"."result"
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_1: prepare to copy table "il_longlive"."result_logbook"
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_1: all tables for set 1 found on subscriber
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_1: copy sequence "il_longlive"."entity_entity_id_seq"
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_1: copy sequence "il_longlive"."entity_result_id_seq"
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_1: copy sequence "il_longlive"."result_id_seq"
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_1: copy sequence "il_longlive"."result_logbook_record_id_seq"
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_1: copy table "il_longlive"."entity"
2017-06-14 23:11:57 PDT CONFIG remoteWorkerThread_1: Begin COPY of table "il_longlive"."entity"
NOTICE: truncate of "il_longlive"."entity" succeeded
2017-06-14 23:12:02 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 23:12:02 PDT CONFIG version for "host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:12:02 PDT INFO remoteWorkerThread_3: SYNC 5000000071 done in 0.029 seconds
2017-06-14 23:12:13 PDT INFO remoteWorkerThread_3: SYNC 5000000072 done in 0.003 seconds
2017-06-14 23:12:28 PDT INFO remoteWorkerThread_3: SYNC 5000000073 done in 0.016 seconds
2017-06-14 23:12:33 PDT CONFIG remoteWorkerThread_1: 125045870 bytes copied for table "il_longlive"."entity"
2017-06-14 23:12:35 PDT INFO remoteWorkerThread_3: SYNC 5000000074 done in 0.010 seconds
2017-06-14 23:12:43 PDT CONFIG remoteWorkerThread_1: 46.468 seconds to copy table "il_longlive"."entity"
2017-06-14 23:12:43 PDT CONFIG remoteWorkerThread_1: copy table "il_longlive"."entity_result"
2017-06-14 23:12:43 PDT CONFIG remoteWorkerThread_1: Begin COPY of table "il_longlive"."entity_result"
NOTICE: truncate of "il_longlive"."entity_result" succeeded
2017-06-14 23:12:46 PDT INFO remoteWorkerThread_3: SYNC 5000000075 done in 0.003 seconds
2017-06-14 23:12:50 PDT INFO remoteWorkerThread_3: SYNC 5000000076 done in 0.003 seconds
2017-06-14 23:13:12 PDT CONFIG remoteWorkerThread_1: 66996342 bytes copied for table "il_longlive"."entity_result"
2017-06-14 23:13:14 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 23:13:14 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:13:14 PDT INFO remoteWorkerThread_3: SYNC 5000000078 done in 0.025 seconds
2017-06-14 23:13:23 PDT INFO remoteWorkerThread_3: SYNC 5000000079 done in 0.013 seconds
2017-06-14 23:13:32 PDT INFO remoteWorkerThread_3: SYNC 5000000080 done in 0.005 seconds
2017-06-14 23:13:41 PDT INFO remoteWorkerThread_3: SYNC 5000000081 done in 0.005 seconds
2017-06-14 23:13:55 PDT INFO remoteWorkerThread_3: SYNC 5000000082 done in 0.005 seconds
2017-06-14 23:13:57 PDT CONFIG remoteWorkerThread_1: 74.255 seconds to copy table "il_longlive"."entity_result"
2017-06-14 23:13:57 PDT CONFIG remoteWorkerThread_1: copy table "il_longlive"."result"
2017-06-14 23:13:57 PDT CONFIG remoteWorkerThread_1: Begin COPY of table "il_longlive"."result"
NOTICE: truncate of "il_longlive"."result" succeeded
2017-06-14 23:14:07 PDT INFO remoteWorkerThread_3: SYNC 5000000083 done in 0.005 seconds
2017-06-14 23:14:20 PDT INFO remoteWorkerThread_3: SYNC 5000000084 done in 0.020 seconds
2017-06-14 23:14:23 PDT INFO remoteWorkerThread_3: SYNC 5000000085 done in 0.005 seconds
2017-06-14 23:14:29 PDT CONFIG remoteWorkerThread_1: 105961503 bytes copied for table "il_longlive"."result"
2017-06-14 23:14:33 PDT INFO remoteWorkerThread_3: SYNC 5000000086 done in 0.005 seconds
2017-06-14 23:14:41 PDT INFO remoteWorkerThread_3: SYNC 5000000087 done in 0.005 seconds
2017-06-14 23:14:50 PDT CONFIG remoteWorkerThread_1: 52.781 seconds to copy table "il_longlive"."result"
2017-06-14 23:14:50 PDT CONFIG remoteWorkerThread_1: copy table "il_longlive"."result_logbook"
2017-06-14 23:14:50 PDT CONFIG remoteWorkerThread_1: Begin COPY of table "il_longlive"."result_logbook"
NOTICE: truncate of "il_longlive"."result_logbook" succeeded
2017-06-14 23:14:50 PDT CONFIG remoteWorkerThread_1: 79 bytes copied for table "il_longlive"."result_logbook"
2017-06-14 23:14:50 PDT CONFIG remoteWorkerThread_1: 0.322 seconds to copy table "il_longlive"."result_logbook"
2017-06-14 23:14:50 PDT CONFIG remoteWorkerThread_1: set last_value of sequence 103 ("il_longlive"."result_id_seq") to 2611527
2017-06-14 23:14:50 PDT CONFIG remoteWorkerThread_1: set last_value of sequence 102 ("il_longlive"."entity_result_id_seq") to 2611527
2017-06-14 23:14:50 PDT CONFIG remoteWorkerThread_1: set last_value of sequence 104 ("il_longlive"."result_logbook_record_id_seq") to 1
2017-06-14 23:14:50 PDT CONFIG remoteWorkerThread_1: set last_value of sequence 101 ("il_longlive"."entity_entity_id_seq") to 2528612
2017-06-14 23:14:50 PDT INFO remoteWorkerThread_1: 0.002 seconds to copy sequences
2017-06-14 23:14:50 PDT INFO remoteWorkerThread_1: copy_set SYNC found, use event seqno 5000000130.
2017-06-14 23:14:50 PDT INFO remoteWorkerThread_1: 0.005 seconds to build initial setsync status
2017-06-14 23:14:50 PDT INFO copy_set 1 done in 173.913 seconds
2017-06-14 23:14:50 PDT CONFIG enableSubscription: sub_set=1
2017-06-14 23:14:50 PDT CONFIG storeListen: li_origin=3 li_receiver=2 li_provider=3
2017-06-14 23:14:50 PDT CONFIG storeListen: li_origin=3 li_receiver=2 li_provider=1
2017-06-14 23:14:50 PDT CONFIG storeListen: li_origin=1 li_receiver=2 li_provider=1
2017-06-14 23:14:50 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:14:50 PDT CONFIG remoteWorkerThread_1: added active set 1 to provider 1
2017-06-14 23:14:50 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 23:14:50 PDT CONFIG remoteWorkerThread_3: connection for provider 3 terminated
2017-06-14 23:14:50 PDT CONFIG remoteWorkerThread_3: disconnecting from data provider 3
2017-06-14 23:14:50 PDT CONFIG remoteWorkerThread_3: connection for provider 1 terminated
2017-06-14 23:14:50 PDT CONFIG remoteWorkerThread_3: disconnecting from data provider 1
2017-06-14 23:14:50 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:14:50 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:14:50 PDT INFO remoteWorkerThread_1: SYNC 5000000131 done in 0.053 seconds
2017-06-14 23:14:50 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:14:50 PDT CONFIG remoteWorkerThread_1: added active set 1 to provider 1
2017-06-14 23:14:50 PDT CONFIG version for "host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:14:50 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:14:50 PDT INFO remoteWorkerThread_1: SYNC 5000000135 done in 0.016 seconds
2017-06-14 23:14:50 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:14:50 PDT INFO remoteWorkerThread_1: SYNC 5000000143 done in 0.005 seconds
2017-06-14 23:14:50 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:14:50 PDT INFO remoteWorkerThread_1: SYNC 5000000147 done in 0.005 seconds
2017-06-14 23:14:52 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 23:14:52 PDT CONFIG storeListen: li_origin=3 li_receiver=2 li_provider=3
2017-06-14 23:14:52 PDT CONFIG storeListen: li_origin=3 li_receiver=2 li_provider=1
2017-06-14 23:14:52 PDT CONFIG storeListen: li_origin=1 li_receiver=2 li_provider=1
2017-06-14 23:14:52 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:14:52 PDT CONFIG remoteWorkerThread_1: added active set 1 to provider 1
2017-06-14 23:14:52 PDT CONFIG remoteWorkerThread_1: connection for provider 3 terminated
2017-06-14 23:14:52 PDT CONFIG remoteWorkerThread_1: disconnecting from data provider 3
2017-06-14 23:14:52 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:14:52 PDT CONFIG storeListen: li_origin=3 li_receiver=2 li_provider=3
2017-06-14 23:14:52 PDT CONFIG storeListen: li_origin=3 li_receiver=2 li_provider=1
2017-06-14 23:14:52 PDT CONFIG storeListen: li_origin=1 li_receiver=2 li_provider=1
2017-06-14 23:14:52 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:14:52 PDT CONFIG remoteWorkerThread_1: added active set 1 to provider 1
2017-06-14 23:14:52 PDT INFO remoteWorkerThread_3: SYNC 5000000088 done in 0.013 seconds
2017-06-14 23:14:52 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 23:14:52 PDT CONFIG remoteWorkerThread_3: connection for provider 1 terminated
2017-06-14 23:14:52 PDT CONFIG remoteWorkerThread_3: disconnecting from data provider 1
2017-06-14 23:15:00 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:15:00 PDT INFO remoteWorkerThread_1: SYNC 5000000150 done in 0.006 seconds
2017-06-14 23:15:05 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 23:15:05 PDT CONFIG version for "host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:15:05 PDT INFO remoteWorkerThread_3: SYNC 5000000089 done in 0.054 seconds
2017-06-14 23:15:11 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:15:11 PDT INFO remoteWorkerThread_1: SYNC 5000000151 done in 0.004 seconds
2017-06-14 23:15:15 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:15:15 PDT INFO remoteWorkerThread_1: SYNC 5000000152 done in 0.004 seconds
2017-06-14 23:15:16 PDT INFO remoteWorkerThread_3: SYNC 5000000090 done in 0.005 seconds
2017-06-14 23:15:26 PDT CONFIG remoteWorkerThread_3: update provider configuration
2017-06-14 23:15:26 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:15:26 PDT INFO remoteWorkerThread_1: SYNC 5000000153 done in 0.007 seconds
2017-06-14 23:15:26 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:15:26 PDT INFO remoteWorkerThread_3: SYNC 5000000091 done in 0.025 seconds
2017-06-14 23:15:37 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:15:37 PDT INFO remoteWorkerThread_1: SYNC 5000000154 done in 0.005 seconds
2017-06-14 23:15:37 PDT INFO remoteWorkerThread_3: SYNC 5000000092 done in 0.006 seconds
2017-06-14 23:15:47 PDT INFO remoteWorkerThread_3: SYNC 5000000093 done in 0.005 seconds
2017-06-14 23:15:51 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:15:51 PDT INFO remoteWorkerThread_1: SYNC 5000000155 done in 0.004 seconds
2017-06-14 23:15:55 PDT INFO remoteWorkerThread_3: SYNC 5000000094 done in 0.005 seconds
2017-06-14 23:15:59 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:15:59 PDT INFO remoteWorkerThread_1: SYNC 5000000156 done in 0.005 seconds
2017-06-14 23:16:09 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:16:09 PDT INFO remoteWorkerThread_1: SYNC 5000000157 done in 0.004 seconds
2017-06-14 23:16:12 PDT INFO remoteWorkerThread_3: SYNC 5000000095 done in 0.009 seconds
2017-06-14 23:16:13 PDT INFO remoteWorkerThread_3: SYNC 5000000096 done in 0.015 seconds
2017-06-14 23:16:17 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:16:17 PDT INFO remoteWorkerThread_1: SYNC 5000000158 done in 0.004 seconds
2017-06-14 23:16:28 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:16:28 PDT INFO remoteWorkerThread_1: SYNC 5000000159 done in 0.004 seconds
2017-06-14 23:16:31 PDT INFO remoteWorkerThread_3: SYNC 5000000097 done in 0.005 seconds
2017-06-14 23:16:35 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:16:35 PDT INFO remoteWorkerThread_1: SYNC 5000000160 done in 0.004 seconds
2017-06-14 23:16:39 PDT INFO remoteWorkerThread_3: SYNC 5000000098 done in 0.005 seconds
2017-06-14 23:16:46 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:16:46 PDT INFO remoteWorkerThread_1: SYNC 5000000161 done in 0.004 seconds
2017-06-14 23:16:50 PDT INFO remoteWorkerThread_3: SYNC 5000000099 done in 0.005 seconds
2017-06-14 23:16:57 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:16:57 PDT INFO remoteWorkerThread_1: SYNC 5000000162 done in 0.004 seconds
2017-06-14 23:17:01 PDT INFO remoteWorkerThread_3: SYNC 5000000100 done in 0.008 seconds
2017-06-14 23:17:09 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:17:09 PDT INFO remoteWorkerThread_3: SYNC 5000000101 done in 0.006 seconds
2017-06-14 23:17:09 PDT INFO remoteWorkerThread_1: SYNC 5000000163 done in 0.005 seconds
2017-06-14 23:17:22 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:17:22 PDT INFO remoteWorkerThread_1: SYNC 5000000164 done in 0.005 seconds
2017-06-14 23:17:22 PDT INFO remoteWorkerThread_3: SYNC 5000000102 done in 0.005 seconds
2017-06-14 23:17:25 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:17:25 PDT INFO remoteWorkerThread_1: SYNC 5000000165 done in 0.004 seconds
2017-06-14 23:17:30 PDT INFO remoteWorkerThread_3: SYNC 5000000103 done in 0.005 seconds
2017-06-14 23:17:36 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:17:36 PDT INFO remoteWorkerThread_1: SYNC 5000000166 done in 0.004 seconds
2017-06-14 23:17:40 PDT INFO remoteWorkerThread_3: SYNC 5000000104 done in 0.013 seconds
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=15559
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=15656
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=15675
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=15683
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=15825
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=15838
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=15853
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=15939
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=15940
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=15941
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: log switch to sl_log_2 complete - truncate sl_log_1
CONTEXT: PL/pgSQL function _region_il.cleanupevent(interval) line 95 at assignment
2017-06-14 23:17:42 PDT INFO cleanupThread: 0.047 seconds for cleanupEvent()
2017-06-14 23:17:48 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:17:48 PDT INFO remoteWorkerThread_3: SYNC 5000000105 done in 0.005 seconds
2017-06-14 23:17:48 PDT INFO remoteWorkerThread_1: SYNC 5000000167 done in 0.005 seconds
2017-06-14 23:18:01 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:18:01 PDT INFO remoteWorkerThread_3: SYNC 5000000106 done in 0.005 seconds
2017-06-14 23:18:01 PDT INFO remoteWorkerThread_1: SYNC 5000000168 done in 0.005 seconds
2017-06-14 23:18:14 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:18:14 PDT INFO remoteWorkerThread_3: SYNC 5000000107 done in 0.006 seconds
2017-06-14 23:18:14 PDT INFO remoteWorkerThread_1: SYNC 5000000169 done in 0.006 seconds
2017-06-14 23:18:17 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:18:17 PDT INFO remoteWorkerThread_1: SYNC 5000000170 done in 0.005 seconds
2017-06-14 23:18:20 PDT INFO remoteWorkerThread_3: SYNC 5000000108 done in 0.005 seconds
2017-06-14 23:18:28 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:18:28 PDT INFO remoteWorkerThread_3: SYNC 5000000109 done in 0.005 seconds
2017-06-14 23:18:28 PDT INFO remoteWorkerThread_1: SYNC 5000000171 done in 0.005 seconds
2017-06-14 23:18:41 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:18:41 PDT INFO remoteWorkerThread_1: SYNC 5000000172 done in 0.005 seconds
2017-06-14 23:18:41 PDT INFO remoteWorkerThread_3: SYNC 5000000110 done in 0.005 seconds
2017-06-14 23:18:54 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:18:54 PDT INFO remoteWorkerThread_3: SYNC 5000000111 done in 0.005 seconds
2017-06-14 23:18:54 PDT INFO remoteWorkerThread_1: SYNC 5000000173 done in 0.005 seconds
2017-06-14 23:18:57 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:18:57 PDT INFO remoteWorkerThread_1: SYNC 5000000174 done in 0.013 seconds
2017-06-14 23:19:00 PDT INFO remoteWorkerThread_3: SYNC 5000000112 done in 0.009 seconds
2017-06-14 23:19:08 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:19:08 PDT INFO remoteWorkerThread_3: SYNC 5000000113 done in 0.005 seconds
2017-06-14 23:19:08 PDT INFO remoteWorkerThread_1: SYNC 5000000175 done in 0.005 seconds
2017-06-14 23:19:21 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:19:21 PDT INFO remoteWorkerThread_3: SYNC 5000000114 done in 0.006 seconds
2017-06-14 23:19:21 PDT INFO remoteWorkerThread_1: SYNC 5000000176 done in 0.005 seconds
2017-06-14 23:19:34 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:19:34 PDT INFO remoteWorkerThread_3: SYNC 5000000115 done in 0.006 seconds
2017-06-14 23:19:34 PDT INFO remoteWorkerThread_1: SYNC 5000000177 done in 0.006 seconds
2017-06-14 23:19:37 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:19:37 PDT INFO remoteWorkerThread_1: SYNC 5000000178 done in 0.005 seconds
2017-06-14 23:19:40 PDT INFO remoteWorkerThread_3: SYNC 5000000116 done in 0.005 seconds
2017-06-14 23:19:48 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:19:48 PDT INFO remoteWorkerThread_3: SYNC 5000000117 done in 0.005 seconds
2017-06-14 23:19:48 PDT INFO remoteWorkerThread_1: SYNC 5000000179 done in 0.005 seconds
2017-06-14 23:20:01 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:20:01 PDT INFO remoteWorkerThread_1: SYNC 5000000180 done in 0.006 seconds
2017-06-14 23:20:01 PDT INFO remoteWorkerThread_3: SYNC 5000000118 done in 0.006 seconds
2017-06-14 23:20:14 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:20:14 PDT INFO remoteWorkerThread_1: SYNC 5000000181 done in 0.005 seconds
2017-06-14 23:20:14 PDT INFO remoteWorkerThread_3: SYNC 5000000119 done in 0.005 seconds
2017-06-14 23:20:17 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:20:17 PDT INFO remoteWorkerThread_1: SYNC 5000000182 done in 0.005 seconds
2017-06-14 23:20:18 PDT INFO remoteWorkerThread_3: SYNC 5000000120 done in 0.005 seconds
2017-06-14 23:20:27 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:20:27 PDT INFO remoteWorkerThread_1: SYNC 5000000183 done in 0.005 seconds
2017-06-14 23:20:32 PDT INFO remoteWorkerThread_3: SYNC 5000000121 done in 0.006 seconds
2017-06-14 23:20:38 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:20:38 PDT INFO remoteWorkerThread_1: SYNC 5000000184 done in 0.004 seconds
2017-06-14 23:20:39 PDT INFO remoteWorkerThread_3: SYNC 5000000122 done in 0.005 seconds
2017-06-14 23:20:53 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:20:53 PDT INFO remoteWorkerThread_3: SYNC 5000000123 done in 0.006 seconds
2017-06-14 23:20:53 PDT INFO remoteWorkerThread_1: SYNC 5000000185 done in 0.006 seconds
2017-06-14 23:21:01 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:21:01 PDT INFO remoteWorkerThread_3: SYNC 5000000124 done in 0.006 seconds
2017-06-14 23:21:01 PDT INFO remoteWorkerThread_1: SYNC 5000000186 done in 0.006 seconds
2017-06-14 23:21:09 PDT INFO remoteWorkerThread_3: SYNC 5000000125 done in 0.005 seconds
2017-06-14 23:21:14 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:21:14 PDT INFO remoteWorkerThread_1: SYNC 5000000187 done in 0.004 seconds
2017-06-14 23:21:15 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:21:15 PDT INFO remoteWorkerThread_1: SYNC 5000000188 done in 0.004 seconds
2017-06-14 23:21:20 PDT INFO remoteWorkerThread_3: SYNC 5000000126 done in 0.005 seconds
2017-06-14 23:21:26 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:21:26 PDT INFO remoteWorkerThread_1: SYNC 5000000189 done in 0.004 seconds
2017-06-14 23:21:31 PDT INFO remoteWorkerThread_3: SYNC 5000000127 done in 0.005 seconds
2017-06-14 23:21:37 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:21:37 PDT INFO remoteWorkerThread_1: SYNC 5000000190 done in 0.005 seconds
2017-06-14 23:21:42 PDT INFO remoteWorkerThread_3: SYNC 5000000128 done in 0.005 seconds
2017-06-14 23:21:48 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:21:48 PDT INFO remoteWorkerThread_1: SYNC 5000000191 done in 0.008 seconds
2017-06-14 23:21:52 PDT INFO remoteWorkerThread_3: SYNC 5000000129 done in 0.006 seconds
2017-06-14 23:22:00 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:22:00 PDT INFO remoteWorkerThread_1: SYNC 5000000192 done in 0.005 seconds
2017-06-14 23:22:00 PDT INFO remoteWorkerThread_3: SYNC 5000000130 done in 0.006 seconds
2017-06-14 23:22:13 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:22:13 PDT INFO remoteWorkerThread_3: SYNC 5000000131 done in 0.005 seconds
2017-06-14 23:22:13 PDT INFO remoteWorkerThread_1: SYNC 5000000193 done in 0.005 seconds
2017-06-14 23:22:16 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:22:16 PDT INFO remoteWorkerThread_1: SYNC 5000000194 done in 0.004 seconds
2017-06-14 23:22:21 PDT INFO remoteWorkerThread_3: SYNC 5000000132 done in 0.005 seconds
2017-06-14 23:22:27 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:22:27 PDT INFO remoteWorkerThread_1: SYNC 5000000195 done in 0.005 seconds
2017-06-14 23:22:32 PDT INFO remoteWorkerThread_3: SYNC 5000000133 done in 0.005 seconds
2017-06-14 23:22:38 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:22:38 PDT INFO remoteWorkerThread_1: SYNC 5000000196 done in 0.006 seconds
2017-06-14 23:22:42 PDT INFO remoteWorkerThread_3: SYNC 5000000134 done in 0.005 seconds
2017-06-14 23:22:50 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:22:50 PDT INFO remoteWorkerThread_1: SYNC 5000000197 done in 0.005 seconds
2017-06-14 23:22:54 PDT INFO remoteWorkerThread_3: SYNC 5000000135 done in 0.005 seconds
2017-06-14 23:22:55 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:22:55 PDT INFO remoteWorkerThread_1: SYNC 5000000198 done in 0.004 seconds
2017-06-14 23:22:59 PDT INFO remoteWorkerThread_3: SYNC 5000000136 done in 0.005 seconds
2017-06-14 23:23:10 PDT INFO remoteWorkerThread_3: SYNC 5000000137 done in 0.005 seconds
2017-06-14 23:23:13 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:23:13 PDT INFO remoteWorkerThread_1: SYNC 5000000199 done in 0.005 seconds
2017-06-14 23:23:21 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:23:21 PDT INFO remoteWorkerThread_1: SYNC 5000000200 done in 0.004 seconds
2017-06-14 23:23:25 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:23:25 PDT INFO remoteWorkerThread_3: SYNC 5000000138 done in 0.005 seconds
2017-06-14 23:23:25 PDT INFO remoteWorkerThread_1: SYNC 5000000201 done in 0.005 seconds
2017-06-14 23:23:34 PDT INFO remoteWorkerThread_3: SYNC 5000000139 done in 0.005 seconds
2017-06-14 23:23:39 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:23:39 PDT INFO remoteWorkerThread_1: SYNC 5000000202 done in 0.005 seconds
2017-06-14 23:23:43 PDT INFO remoteWorkerThread_3: SYNC 5000000140 done in 0.005 seconds
2017-06-14 23:23:47 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1
2017-06-14 23:23:47 PDT INFO remoteWorkerThread_1: SYNC 5000000203 done in 0.004 seconds
- 辅节点2查看Slony-I日志信息
$ cat /usr/local/pgsql/data/pg_log/node3/region_il-2017-06-14.log
2017-06-14 23:00:38 PDT CONFIG main: slon version 2.2.4 starting up
2017-06-14 23:00:38 PDT INFO slon: watchdog process started
2017-06-14 23:00:38 PDT CONFIG slon: watchdog ready - pid = 19212
2017-06-14 23:00:38 PDT CONFIG slon: worker process created - pid = 19213
2017-06-14 23:00:38 PDT CONFIG main: Integer option vac_frequency = 3
2017-06-14 23:00:38 PDT CONFIG main: Integer option log_level = 0
2017-06-14 23:00:38 PDT CONFIG main: Integer option sync_interval = 1000
2017-06-14 23:00:38 PDT CONFIG main: Integer option sync_interval_timeout = 10000
2017-06-14 23:00:38 PDT CONFIG main: Integer option sync_group_maxsize = 20
2017-06-14 23:00:38 PDT CONFIG main: Integer option syslog = 0
2017-06-14 23:00:38 PDT CONFIG main: Integer option quit_sync_provider = 0
2017-06-14 23:00:38 PDT CONFIG main: Integer option remote_listen_timeout = 300
2017-06-14 23:00:38 PDT CONFIG main: Integer option monitor_interval = 500
2017-06-14 23:00:38 PDT CONFIG main: Integer option explain_interval = 0
2017-06-14 23:00:38 PDT CONFIG main: Integer option tcp_keepalive_idle = 0
2017-06-14 23:00:38 PDT CONFIG main: Integer option tcp_keepalive_interval = 0
2017-06-14 23:00:38 PDT CONFIG main: Integer option tcp_keepalive_count = 0
2017-06-14 23:00:38 PDT CONFIG main: Integer option apply_cache_size = 100
2017-06-14 23:00:38 PDT CONFIG main: Boolean option log_pid = 0
2017-06-14 23:00:38 PDT CONFIG main: Boolean option log_timestamp = 1
2017-06-14 23:00:38 PDT CONFIG main: Boolean option tcp_keepalive = 1
2017-06-14 23:00:38 PDT CONFIG main: Boolean option monitor_threads = 1
2017-06-14 23:00:38 PDT CONFIG main: Real option real_placeholder = 0.000000
2017-06-14 23:00:38 PDT CONFIG main: String option cluster_name = region_il
2017-06-14 23:00:38 PDT CONFIG main: String option conn_info = host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888
2017-06-14 23:00:38 PDT CONFIG main: String option pid_file = /usr/local/pgsql/data/region_il_node3.pid
2017-06-14 23:00:38 PDT CONFIG main: String option log_timestamp_format = %Y-%m-%d %H:%M:%S %Z
2017-06-14 23:00:38 PDT CONFIG main: String option archive_dir = [NULL]
2017-06-14 23:00:38 PDT CONFIG main: String option sql_on_connection = [NULL]
2017-06-14 23:00:38 PDT CONFIG main: String option lag_interval = [NULL]
2017-06-14 23:00:38 PDT CONFIG main: String option command_on_logarchive = [NULL]
2017-06-14 23:00:38 PDT CONFIG main: String option syslog_facility = LOCAL0
2017-06-14 23:00:38 PDT CONFIG main: String option syslog_ident = slon
2017-06-14 23:00:38 PDT CONFIG main: String option cleanup_interval = 10 minutes
2017-06-14 23:00:38 PDT CONFIG main: local node id = 3
2017-06-14 23:00:38 PDT INFO main: main process started
2017-06-14 23:00:38 PDT CONFIG main: launching sched_start_mainloop
2017-06-14 23:00:38 PDT CONFIG main: loading current cluster configuration
2017-06-14 23:00:38 PDT CONFIG storeNode: no_id=1 no_comment='Node 1 - region_il@10.189.102.118'
2017-06-14 23:00:38 PDT CONFIG storeNode: no_id=2 no_comment='Node 2 - region_il@10.189.100.195'
2017-06-14 23:00:38 PDT CONFIG storePath: pa_server=1 pa_client=3 pa_conninfo="host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" pa_connretry=10
2017-06-14 23:00:38 PDT CONFIG storePath: pa_server=2 pa_client=3 pa_conninfo="host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" pa_connretry=10
2017-06-14 23:00:38 PDT CONFIG storeListen: li_origin=1 li_receiver=3 li_provider=1
2017-06-14 23:00:38 PDT CONFIG storeListen: li_origin=2 li_receiver=3 li_provider=2
2017-06-14 23:00:38 PDT CONFIG main: last local event sequence = 5000000002
2017-06-14 23:00:38 PDT CONFIG main: configuration complete - starting threads
2017-06-14 23:00:38 PDT INFO localListenThread: thread starts
2017-06-14 23:00:38 PDT CONFIG version for "host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:00:38 PDT CONFIG enableNode: no_id=1
2017-06-14 23:00:38 PDT CONFIG enableNode: no_id=2
2017-06-14 23:00:38 PDT INFO main: running scheduler mainloop
2017-06-14 23:00:38 PDT INFO remoteWorkerThread_2: thread starts
2017-06-14 23:00:38 PDT INFO remoteListenThread_2: thread starts
2017-06-14 23:00:38 PDT CONFIG cleanupThread: thread starts
2017-06-14 23:00:38 PDT INFO syncThread: thread starts
2017-06-14 23:00:38 PDT INFO monitorThread: thread starts
2017-06-14 23:00:38 PDT INFO remoteListenThread_1: thread starts
2017-06-14 23:00:38 PDT INFO remoteWorkerThread_1: thread starts
2017-06-14 23:00:38 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:00:38 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:00:38 PDT CONFIG version for "host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:00:38 PDT CONFIG version for "host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:00:38 PDT CONFIG version for "host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:00:38 PDT CONFIG version for "host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:00:38 PDT CONFIG version for "host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:00:38 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:00:38 PDT CONFIG cleanupThread: bias = 60
2017-06-14 23:00:38 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:00:38 PDT CONFIG storeListen: li_origin=1 li_receiver=3 li_provider=1
2017-06-14 23:00:38 PDT CONFIG storeListen: li_origin=2 li_receiver=3 li_provider=2
2017-06-14 23:00:38 PDT CONFIG storeListen: li_origin=1 li_receiver=3 li_provider=2
2017-06-14 23:00:38 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:00:38 PDT CONFIG storeListen: li_origin=1 li_receiver=3 li_provider=1
2017-06-14 23:00:38 PDT CONFIG storeListen: li_origin=2 li_receiver=3 li_provider=2
2017-06-14 23:00:38 PDT CONFIG storeListen: li_origin=1 li_receiver=3 li_provider=2
2017-06-14 23:00:38 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:00:38 PDT CONFIG storeListen: li_origin=1 li_receiver=3 li_provider=1
2017-06-14 23:00:38 PDT CONFIG storeListen: li_origin=2 li_receiver=3 li_provider=2
2017-06-14 23:00:38 PDT CONFIG storeListen: li_origin=1 li_receiver=3 li_provider=2
2017-06-14 23:00:38 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:00:38 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:00:38 PDT CONFIG storeListen: li_origin=1 li_receiver=3 li_provider=1
2017-06-14 23:00:38 PDT CONFIG storeListen: li_origin=2 li_receiver=3 li_provider=2
2017-06-14 23:00:38 PDT CONFIG storeListen: li_origin=1 li_receiver=3 li_provider=2
2017-06-14 23:00:38 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:00:38 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:00:38 PDT CONFIG storeListen: li_origin=1 li_receiver=3 li_provider=1
2017-06-14 23:00:38 PDT CONFIG storeListen: li_origin=2 li_receiver=3 li_provider=2
2017-06-14 23:00:38 PDT CONFIG storeListen: li_origin=2 li_receiver=3 li_provider=1
2017-06-14 23:00:38 PDT CONFIG storeListen: li_origin=1 li_receiver=3 li_provider=2
2017-06-14 23:00:38 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:00:38 PDT INFO remoteWorkerThread_2: SYNC 5000000003 done in 0.019 seconds
2017-06-14 23:00:38 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:00:38 PDT CONFIG remoteWorkerThread_2: connection for provider 2 terminated
2017-06-14 23:00:38 PDT CONFIG remoteWorkerThread_2: disconnecting from data provider 2
2017-06-14 23:00:38 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:00:38 PDT CONFIG storeListen: li_origin=1 li_receiver=3 li_provider=1
2017-06-14 23:00:38 PDT CONFIG storeListen: li_origin=2 li_receiver=3 li_provider=2
2017-06-14 23:00:38 PDT CONFIG storeListen: li_origin=2 li_receiver=3 li_provider=1
2017-06-14 23:00:38 PDT CONFIG storeListen: li_origin=1 li_receiver=3 li_provider=2
2017-06-14 23:00:38 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:00:38 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:00:38 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:00:38 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:00:38 PDT INFO remoteWorkerThread_2: SYNC 5000000005 done in 0.011 seconds
2017-06-14 23:00:38 PDT INFO remoteWorkerThread_1: SYNC 5000000008 done in 0.011 seconds
2017-06-14 23:00:38 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:00:38 PDT CONFIG remoteWorkerThread_2: connection for provider 2 terminated
2017-06-14 23:00:38 PDT CONFIG remoteWorkerThread_2: disconnecting from data provider 2
2017-06-14 23:00:38 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:00:38 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:00:38 PDT INFO remoteWorkerThread_1: SYNC 5000000010 done in 0.002 seconds
2017-06-14 23:00:38 PDT INFO remoteWorkerThread_1: SYNC 5000000014 done in 0.002 seconds
2017-06-14 23:00:38 PDT INFO remoteWorkerThread_2: SYNC 5000000009 done in 0.011 seconds
2017-06-14 23:00:38 PDT INFO remoteWorkerThread_1: SYNC 5000000022 done in 0.002 seconds
2017-06-14 23:00:38 PDT INFO remoteWorkerThread_2: SYNC 5000000017 done in 0.002 seconds
2017-06-14 23:00:38 PDT INFO remoteWorkerThread_1: SYNC 5000000038 done in 0.002 seconds
2017-06-14 23:00:38 PDT INFO remoteWorkerThread_2: SYNC 5000000020 done in 0.002 seconds
2017-06-14 23:00:38 PDT INFO remoteWorkerThread_1: SYNC 5000000043 done in 0.002 seconds
2017-06-14 23:00:46 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:00:46 PDT INFO remoteWorkerThread_2: SYNC 5000000021 done in 0.003 seconds
2017-06-14 23:00:46 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:00:46 PDT INFO remoteWorkerThread_1: SYNC 5000000044 done in 0.017 seconds
2017-06-14 23:00:57 PDT INFO remoteWorkerThread_2: SYNC 5000000022 done in 0.002 seconds
2017-06-14 23:01:02 PDT INFO remoteWorkerThread_1: SYNC 5000000045 done in 0.005 seconds
2017-06-14 23:01:03 PDT INFO remoteWorkerThread_1: SYNC 5000000046 done in 0.004 seconds
2017-06-14 23:01:08 PDT INFO remoteWorkerThread_2: SYNC 5000000023 done in 0.003 seconds
2017-06-14 23:01:14 PDT INFO remoteWorkerThread_1: SYNC 5000000047 done in 0.004 seconds
2017-06-14 23:01:19 PDT INFO remoteWorkerThread_2: SYNC 5000000024 done in 0.003 seconds
2017-06-14 23:01:23 PDT INFO remoteWorkerThread_2: SYNC 5000000025 done in 0.003 seconds
2017-06-14 23:01:25 PDT INFO remoteWorkerThread_1: SYNC 5000000048 done in 0.004 seconds
2017-06-14 23:01:34 PDT INFO remoteWorkerThread_2: SYNC 5000000026 done in 0.002 seconds
2017-06-14 23:01:38 PDT INFO remoteWorkerThread_1: SYNC 5000000049 done in 0.004 seconds
2017-06-14 23:01:46 PDT INFO remoteWorkerThread_2: SYNC 5000000027 done in 0.003 seconds
2017-06-14 23:01:50 PDT INFO remoteWorkerThread_1: SYNC 5000000050 done in 0.005 seconds
2017-06-14 23:01:54 PDT INFO remoteWorkerThread_2: SYNC 5000000028 done in 0.002 seconds
2017-06-14 23:01:54 PDT INFO remoteWorkerThread_1: SYNC 5000000051 done in 0.005 seconds
2017-06-14 23:02:05 PDT INFO remoteWorkerThread_2: SYNC 5000000029 done in 0.003 seconds
2017-06-14 23:02:09 PDT INFO remoteWorkerThread_1: SYNC 5000000052 done in 0.005 seconds
2017-06-14 23:02:17 PDT INFO remoteWorkerThread_1: SYNC 5000000053 done in 0.005 seconds
2017-06-14 23:02:21 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:02:21 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:02:21 PDT INFO remoteWorkerThread_2: SYNC 5000000030 done in 0.027 seconds
2017-06-14 23:02:25 PDT INFO remoteWorkerThread_1: SYNC 5000000054 done in 0.007 seconds
2017-06-14 23:02:25 PDT INFO remoteWorkerThread_2: SYNC 5000000031 done in 0.008 seconds
2017-06-14 23:02:36 PDT INFO remoteWorkerThread_1: SYNC 5000000055 done in 0.008 seconds
2017-06-14 23:02:39 PDT INFO remoteWorkerThread_2: SYNC 5000000032 done in 0.005 seconds
2017-06-14 23:02:43 PDT INFO remoteWorkerThread_1: SYNC 5000000056 done in 0.005 seconds
2017-06-14 23:02:47 PDT INFO remoteWorkerThread_2: SYNC 5000000033 done in 0.005 seconds
2017-06-14 23:02:54 PDT INFO remoteWorkerThread_1: SYNC 5000000057 done in 0.004 seconds
2017-06-14 23:02:54 PDT INFO remoteWorkerThread_2: SYNC 5000000034 done in 0.005 seconds
2017-06-14 23:03:05 PDT INFO remoteWorkerThread_1: SYNC 5000000058 done in 0.005 seconds
2017-06-14 23:03:08 PDT INFO remoteWorkerThread_2: SYNC 5000000035 done in 0.005 seconds
2017-06-14 23:03:16 PDT CONFIG storeSet: set_id=1 set_origin=1 set_comment='Set 1 (il_longlive_schema) for region_il'
2017-06-14 23:03:16 PDT INFO remoteWorkerThread_2: SYNC 5000000036 done in 0.005 seconds
2017-06-14 23:03:16 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:03:16 PDT CONFIG remoteWorkerThread_1: connection for provider 1 terminated
2017-06-14 23:03:16 PDT CONFIG remoteWorkerThread_1: disconnecting from data provider 1
2017-06-14 23:03:16 PDT CONFIG remoteWorkerThread_1: connection for provider 2 terminated
2017-06-14 23:03:16 PDT CONFIG remoteWorkerThread_1: disconnecting from data provider 2
2017-06-14 23:03:16 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:03:16 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:03:16 PDT INFO remoteWorkerThread_1: SYNC 5000000060 done in 0.012 seconds
2017-06-14 23:03:16 PDT INFO remoteWorkerThread_1: SYNC 5000000062 done in 0.002 seconds
2017-06-14 23:03:16 PDT INFO remoteWorkerThread_1: SYNC 5000000064 done in 0.002 seconds
2017-06-14 23:03:16 PDT INFO remoteWorkerThread_1: SYNC 5000000066 done in 0.002 seconds
2017-06-14 23:03:16 PDT INFO remoteWorkerThread_1: SYNC 5000000068 done in 0.003 seconds
2017-06-14 23:03:16 PDT INFO remoteWorkerThread_1: SYNC 5000000070 done in 0.002 seconds
2017-06-14 23:03:16 PDT INFO remoteWorkerThread_1: SYNC 5000000072 done in 0.002 seconds
2017-06-14 23:03:16 PDT INFO remoteWorkerThread_1: SYNC 5000000074 done in 0.002 seconds
2017-06-14 23:03:16 PDT INFO remoteWorkerThread_1: SYNC 5000000076 done in 0.002 seconds
2017-06-14 23:03:29 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:03:29 PDT INFO remoteWorkerThread_2: SYNC 5000000037 done in 0.005 seconds
2017-06-14 23:03:29 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:03:29 PDT INFO remoteWorkerThread_1: SYNC 5000000077 done in 0.015 seconds
2017-06-14 23:03:42 PDT INFO remoteWorkerThread_2: SYNC 5000000038 done in 0.013 seconds
2017-06-14 23:03:42 PDT INFO remoteWorkerThread_1: SYNC 5000000078 done in 0.015 seconds
2017-06-14 23:03:43 PDT INFO remoteWorkerThread_1: SYNC 5000000079 done in 0.004 seconds
2017-06-14 23:03:43 PDT INFO remoteWorkerThread_2: SYNC 5000000039 done in 0.005 seconds
2017-06-14 23:03:56 PDT INFO remoteWorkerThread_1: SYNC 5000000080 done in 0.004 seconds
2017-06-14 23:03:56 PDT INFO remoteWorkerThread_2: SYNC 5000000040 done in 0.005 seconds
2017-06-14 23:04:04 PDT INFO remoteWorkerThread_2: SYNC 5000000041 done in 0.005 seconds
2017-06-14 23:04:08 PDT INFO remoteWorkerThread_1: SYNC 5000000081 done in 0.005 seconds
2017-06-14 23:04:16 PDT INFO remoteWorkerThread_1: SYNC 5000000082 done in 0.005 seconds
2017-06-14 23:04:16 PDT INFO remoteWorkerThread_2: SYNC 5000000042 done in 0.006 seconds
2017-06-14 23:04:29 PDT INFO remoteWorkerThread_2: SYNC 5000000043 done in 0.004 seconds
2017-06-14 23:04:29 PDT INFO remoteWorkerThread_1: SYNC 5000000083 done in 0.005 seconds
2017-06-14 23:04:37 PDT INFO remoteWorkerThread_2: SYNC 5000000044 done in 0.004 seconds
2017-06-14 23:04:37 PDT INFO remoteWorkerThread_1: SYNC 5000000084 done in 0.005 seconds
2017-06-14 23:04:48 PDT INFO remoteWorkerThread_2: SYNC 5000000045 done in 0.004 seconds
2017-06-14 23:04:48 PDT INFO remoteWorkerThread_1: SYNC 5000000085 done in 0.005 seconds
2017-06-14 23:05:02 PDT INFO remoteWorkerThread_1: SYNC 5000000086 done in 0.004 seconds
2017-06-14 23:05:02 PDT INFO remoteWorkerThread_2: SYNC 5000000046 done in 0.005 seconds
2017-06-14 23:05:03 PDT INFO remoteWorkerThread_1: SYNC 5000000087 done in 0.005 seconds
2017-06-14 23:05:05 PDT INFO remoteWorkerThread_2: SYNC 5000000047 done in 0.005 seconds
2017-06-14 23:05:14 PDT INFO remoteWorkerThread_1: SYNC 5000000088 done in 0.005 seconds
2017-06-14 23:05:19 PDT INFO remoteWorkerThread_2: SYNC 5000000048 done in 0.004 seconds
2017-06-14 23:05:23 PDT INFO remoteWorkerThread_2: SYNC 5000000049 done in 0.005 seconds
2017-06-14 23:05:25 PDT INFO remoteWorkerThread_1: SYNC 5000000089 done in 0.004 seconds
2017-06-14 23:05:34 PDT INFO remoteWorkerThread_2: SYNC 5000000050 done in 0.006 seconds
2017-06-14 23:05:38 PDT INFO remoteWorkerThread_1: SYNC 5000000090 done in 0.005 seconds
2017-06-14 23:05:46 PDT INFO remoteWorkerThread_1: SYNC 5000000091 done in 0.004 seconds
2017-06-14 23:05:46 PDT INFO remoteWorkerThread_2: SYNC 5000000051 done in 0.005 seconds
2017-06-14 23:05:59 PDT INFO remoteWorkerThread_1: SYNC 5000000092 done in 0.004 seconds
2017-06-14 23:05:59 PDT INFO remoteWorkerThread_2: SYNC 5000000052 done in 0.005 seconds
2017-06-14 23:06:12 PDT INFO remoteWorkerThread_2: SYNC 5000000053 done in 0.004 seconds
2017-06-14 23:06:12 PDT INFO remoteWorkerThread_1: SYNC 5000000093 done in 0.004 seconds
2017-06-14 23:06:15 PDT INFO remoteWorkerThread_2: SYNC 5000000054 done in 0.004 seconds
2017-06-14 23:06:15 PDT INFO remoteWorkerThread_1: SYNC 5000000094 done in 0.005 seconds
2017-06-14 23:06:28 PDT INFO remoteWorkerThread_2: SYNC 5000000055 done in 0.005 seconds
2017-06-14 23:06:28 PDT INFO remoteWorkerThread_1: SYNC 5000000095 done in 0.005 seconds
2017-06-14 23:06:36 PDT INFO remoteWorkerThread_1: SYNC 5000000096 done in 0.004 seconds
2017-06-14 23:06:36 PDT INFO remoteWorkerThread_2: SYNC 5000000056 done in 0.005 seconds
2017-06-14 23:06:47 PDT INFO remoteWorkerThread_1: SYNC 5000000097 done in 0.005 seconds
2017-06-14 23:06:48 PDT INFO remoteWorkerThread_2: SYNC 5000000057 done in 0.004 seconds
2017-06-14 23:06:58 PDT INFO remoteWorkerThread_1: SYNC 5000000098 done in 0.004 seconds
2017-06-14 23:06:58 PDT INFO remoteWorkerThread_2: SYNC 5000000058 done in 0.005 seconds
2017-06-14 23:07:12 PDT INFO remoteWorkerThread_1: SYNC 5000000099 done in 0.004 seconds
2017-06-14 23:07:12 PDT INFO remoteWorkerThread_2: SYNC 5000000059 done in 0.005 seconds
2017-06-14 23:07:15 PDT INFO remoteWorkerThread_2: SYNC 5000000060 done in 0.004 seconds
2017-06-14 23:07:15 PDT INFO remoteWorkerThread_1: SYNC 5000000100 done in 0.005 seconds
2017-06-14 23:07:28 PDT INFO remoteWorkerThread_1: SYNC 5000000101 done in 0.060 seconds
2017-06-14 23:07:28 PDT INFO remoteWorkerThread_2: SYNC 5000000061 done in 0.062 seconds
2017-06-14 23:07:36 PDT INFO remoteWorkerThread_2: SYNC 5000000062 done in 0.004 seconds
2017-06-14 23:07:36 PDT INFO remoteWorkerThread_1: SYNC 5000000102 done in 0.005 seconds
2017-06-14 23:07:47 PDT INFO remoteWorkerThread_1: SYNC 5000000103 done in 0.008 seconds
2017-06-14 23:07:47 PDT INFO remoteWorkerThread_2: SYNC 5000000063 done in 0.009 seconds
2017-06-14 23:07:58 PDT INFO remoteWorkerThread_1: SYNC 5000000104 done in 0.005 seconds
2017-06-14 23:07:58 PDT INFO remoteWorkerThread_2: SYNC 5000000064 done in 0.005 seconds
2017-06-14 23:08:09 PDT INFO remoteWorkerThread_1: SYNC 5000000105 done in 0.007 seconds
2017-06-14 23:08:09 PDT INFO remoteWorkerThread_2: SYNC 5000000065 done in 0.009 seconds
2017-06-14 23:08:13 PDT INFO remoteWorkerThread_1: SYNC 5000000106 done in 0.005 seconds
2017-06-14 23:08:19 PDT INFO remoteWorkerThread_2: SYNC 5000000066 done in 0.010 seconds
2017-06-14 23:08:23 PDT INFO remoteWorkerThread_2: SYNC 5000000067 done in 0.004 seconds
2017-06-14 23:08:24 PDT INFO remoteWorkerThread_1: SYNC 5000000107 done in 0.008 seconds
2017-06-14 23:08:37 PDT INFO remoteWorkerThread_2: SYNC 5000000068 done in 0.006 seconds
2017-06-14 23:08:41 PDT INFO remoteWorkerThread_1: SYNC 5000000108 done in 0.005 seconds
2017-06-14 23:08:45 PDT INFO remoteWorkerThread_1: SYNC 5000000109 done in 0.005 seconds
2017-06-14 23:08:49 PDT INFO remoteWorkerThread_2: SYNC 5000000069 done in 0.005 seconds
2017-06-14 23:08:57 PDT INFO remoteWorkerThread_1: SYNC 5000000110 done in 0.006 seconds
2017-06-14 23:08:57 PDT INFO remoteWorkerThread_2: SYNC 5000000070 done in 0.006 seconds
2017-06-14 23:09:08 PDT INFO remoteWorkerThread_2: SYNC 5000000071 done in 0.005 seconds
2017-06-14 23:09:08 PDT INFO remoteWorkerThread_1: SYNC 5000000111 done in 0.005 seconds
2017-06-14 23:09:19 PDT INFO remoteWorkerThread_1: SYNC 5000000112 done in 0.004 seconds
2017-06-14 23:09:19 PDT INFO remoteWorkerThread_2: SYNC 5000000072 done in 0.005 seconds
2017-06-14 23:09:29 PDT INFO remoteWorkerThread_2: SYNC 5000000073 done in 0.004 seconds
2017-06-14 23:09:29 PDT INFO remoteWorkerThread_1: SYNC 5000000113 done in 0.005 seconds
2017-06-14 23:09:33 PDT INFO remoteWorkerThread_2: SYNC 5000000074 done in 0.005 seconds
2017-06-14 23:09:34 PDT INFO remoteWorkerThread_1: SYNC 5000000114 done in 0.005 seconds
2017-06-14 23:09:43 PDT INFO remoteWorkerThread_2: SYNC 5000000075 done in 0.005 seconds
2017-06-14 23:09:47 PDT INFO remoteWorkerThread_1: SYNC 5000000115 done in 0.004 seconds
2017-06-14 23:09:55 PDT INFO remoteWorkerThread_2: SYNC 5000000076 done in 0.005 seconds
2017-06-14 23:10:01 PDT INFO remoteWorkerThread_1: SYNC 5000000116 done in 0.005 seconds
2017-06-14 23:10:05 PDT INFO remoteWorkerThread_1: SYNC 5000000117 done in 0.005 seconds
2017-06-14 23:10:06 PDT INFO remoteWorkerThread_2: SYNC 5000000077 done in 0.005 seconds
2017-06-14 23:10:14 PDT INFO remoteWorkerThread_1: SYNC 5000000118 done in 0.005 seconds
2017-06-14 23:10:18 PDT INFO remoteWorkerThread_2: SYNC 5000000078 done in 0.005 seconds
2017-06-14 23:10:26 PDT INFO remoteWorkerThread_2: SYNC 5000000079 done in 0.005 seconds
2017-06-14 23:10:30 PDT INFO remoteWorkerThread_1: SYNC 5000000119 done in 0.005 seconds
2017-06-14 23:10:34 PDT INFO remoteWorkerThread_2: SYNC 5000000080 done in 0.005 seconds
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=19256
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: Logswitch to sl_log_2 initiated
CONTEXT: SQL statement "SELECT "_region_il".logswitch_start()"
PL/pgSQL function _region_il.cleanupevent(interval) line 97 at PERFORM
2017-06-14 23:10:39 PDT INFO cleanupThread: 0.021 seconds for cleanupEvent()
2017-06-14 23:10:39 PDT INFO remoteWorkerThread_1: SYNC 5000000120 done in 0.005 seconds
2017-06-14 23:10:45 PDT INFO remoteWorkerThread_1: SYNC 5000000121 done in 0.005 seconds
2017-06-14 23:10:45 PDT INFO remoteWorkerThread_2: SYNC 5000000081 done in 0.005 seconds
2017-06-14 23:10:56 PDT INFO remoteWorkerThread_2: SYNC 5000000082 done in 0.005 seconds
2017-06-14 23:10:57 PDT INFO remoteWorkerThread_1: SYNC 5000000122 done in 0.004 seconds
2017-06-14 23:11:07 PDT INFO remoteWorkerThread_1: SYNC 5000000123 done in 0.005 seconds
2017-06-14 23:11:08 PDT INFO remoteWorkerThread_2: SYNC 5000000083 done in 0.004 seconds
2017-06-14 23:11:18 PDT INFO remoteWorkerThread_2: SYNC 5000000084 done in 0.005 seconds
2017-06-14 23:11:19 PDT INFO remoteWorkerThread_1: SYNC 5000000124 done in 0.004 seconds
2017-06-14 23:11:23 PDT INFO remoteWorkerThread_2: SYNC 5000000085 done in 0.004 seconds
2017-06-14 23:11:29 PDT INFO remoteWorkerThread_1: SYNC 5000000125 done in 0.005 seconds
2017-06-14 23:11:34 PDT INFO remoteWorkerThread_1: SYNC 5000000126 done in 0.005 seconds
2017-06-14 23:11:34 PDT INFO remoteWorkerThread_2: SYNC 5000000086 done in 0.005 seconds
2017-06-14 23:11:45 PDT INFO remoteWorkerThread_1: SYNC 5000000127 done in 0.004 seconds
2017-06-14 23:11:45 PDT INFO remoteWorkerThread_2: SYNC 5000000087 done in 0.004 seconds
2017-06-14 23:11:56 PDT INFO remoteWorkerThread_2: SYNC 5000000088 done in 0.005 seconds
2017-06-14 23:11:56 PDT CONFIG storeListen: li_origin=1 li_receiver=3 li_provider=1
2017-06-14 23:11:56 PDT CONFIG storeListen: li_origin=2 li_receiver=3 li_provider=2
2017-06-14 23:11:56 PDT CONFIG storeListen: li_origin=2 li_receiver=3 li_provider=1
2017-06-14 23:11:56 PDT CONFIG storeListen: li_origin=1 li_receiver=3 li_provider=2
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_1: connection for provider 1 terminated
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_1: disconnecting from data provider 1
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_1: connection for provider 2 terminated
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_1: disconnecting from data provider 2
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_2: connection for provider 2 terminated
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_2: disconnecting from data provider 2
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_2: connection for provider 1 terminated
2017-06-14 23:11:56 PDT CONFIG remoteWorkerThread_2: disconnecting from data provider 1
2017-06-14 23:12:00 PDT CONFIG storeListen: li_origin=1 li_receiver=3 li_provider=1
2017-06-14 23:12:00 PDT CONFIG storeListen: li_origin=2 li_receiver=3 li_provider=2
2017-06-14 23:12:00 PDT CONFIG storeListen: li_origin=2 li_receiver=3 li_provider=1
2017-06-14 23:12:00 PDT CONFIG storeListen: li_origin=1 li_receiver=3 li_provider=2
2017-06-14 23:12:00 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:12:00 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:12:00 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:12:00 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:12:00 PDT INFO remoteWorkerThread_1: SYNC 5000000130 done in 0.012 seconds
2017-06-14 23:12:08 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:12:08 PDT INFO remoteWorkerThread_1: SYNC 5000000131 done in 0.003 seconds
2017-06-14 23:12:08 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:12:08 PDT INFO remoteWorkerThread_2: SYNC 5000000089 done in 0.014 seconds
2017-06-14 23:12:19 PDT INFO remoteWorkerThread_1: SYNC 5000000132 done in 0.002 seconds
2017-06-14 23:12:30 PDT INFO remoteWorkerThread_1: SYNC 5000000133 done in 0.003 seconds
2017-06-14 23:12:34 PDT INFO remoteWorkerThread_1: SYNC 5000000134 done in 0.003 seconds
2017-06-14 23:12:34 PDT INFO remoteWorkerThread_2: SYNC 5000000090 done in 0.003 seconds
2017-06-14 23:12:38 PDT INFO remoteWorkerThread_2: SYNC 5000000091 done in 0.003 seconds
2017-06-14 23:12:49 PDT INFO remoteWorkerThread_1: SYNC 5000000135 done in 0.002 seconds
2017-06-14 23:12:53 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:12:53 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:12:56 PDT INFO remoteWorkerThread_1: SYNC 5000000136 done in 0.002 seconds
2017-06-14 23:13:02 PDT INFO remoteWorkerThread_2: SYNC 5000000092 done in 8.409 seconds
2017-06-14 23:13:07 PDT INFO remoteWorkerThread_1: SYNC 5000000137 done in 0.003 seconds
2017-06-14 23:13:07 PDT INFO remoteWorkerThread_2: SYNC 5000000093 done in 0.005 seconds
2017-06-14 23:13:18 PDT INFO remoteWorkerThread_1: SYNC 5000000138 done in 0.003 seconds
2017-06-14 23:13:18 PDT INFO remoteWorkerThread_2: SYNC 5000000094 done in 0.005 seconds
2017-06-14 23:13:29 PDT INFO remoteWorkerThread_1: SYNC 5000000139 done in 0.003 seconds
2017-06-14 23:13:29 PDT INFO remoteWorkerThread_2: SYNC 5000000095 done in 0.005 seconds
2017-06-14 23:13:40 PDT INFO remoteWorkerThread_1: SYNC 5000000140 done in 0.003 seconds
2017-06-14 23:13:40 PDT INFO remoteWorkerThread_2: SYNC 5000000096 done in 0.006 seconds
2017-06-14 23:13:44 PDT INFO remoteWorkerThread_1: SYNC 5000000141 done in 0.003 seconds
2017-06-14 23:13:48 PDT INFO remoteWorkerThread_2: SYNC 5000000097 done in 0.006 seconds
2017-06-14 23:13:58 PDT INFO remoteWorkerThread_2: SYNC 5000000098 done in 0.005 seconds
2017-06-14 23:14:02 PDT INFO remoteWorkerThread_1: SYNC 5000000142 done in 0.003 seconds
2017-06-14 23:14:06 PDT INFO remoteWorkerThread_1: SYNC 5000000143 done in 0.002 seconds
2017-06-14 23:14:16 PDT INFO remoteWorkerThread_2: SYNC 5000000099 done in 0.005 seconds
2017-06-14 23:14:20 PDT INFO remoteWorkerThread_2: SYNC 5000000100 done in 0.007 seconds
2017-06-14 23:14:20 PDT INFO remoteWorkerThread_1: SYNC 5000000144 done in 0.003 seconds
2017-06-14 23:14:28 PDT INFO remoteWorkerThread_1: SYNC 5000000145 done in 0.003 seconds
2017-06-14 23:14:34 PDT INFO remoteWorkerThread_2: SYNC 5000000101 done in 0.015 seconds
2017-06-14 23:14:39 PDT INFO remoteWorkerThread_1: SYNC 5000000146 done in 0.003 seconds
2017-06-14 23:14:50 PDT INFO remoteWorkerThread_1: SYNC 5000000147 done in 0.003 seconds
2017-06-14 23:14:51 PDT CONFIG storeSubscribe: sub_set=1 sub_provider=2 sub_forward='t'
2017-06-14 23:14:51 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:14:51 PDT CONFIG remoteWorkerThread_2: connection for provider 1 terminated
2017-06-14 23:14:51 PDT CONFIG remoteWorkerThread_2: disconnecting from data provider 1
2017-06-14 23:14:51 PDT CONFIG remoteWorkerThread_2: connection for provider 2 terminated
2017-06-14 23:14:51 PDT CONFIG remoteWorkerThread_2: disconnecting from data provider 2
2017-06-14 23:14:51 PDT CONFIG storeListen: li_origin=1 li_receiver=3 li_provider=1
2017-06-14 23:14:51 PDT CONFIG storeListen: li_origin=2 li_receiver=3 li_provider=2
2017-06-14 23:14:51 PDT CONFIG storeListen: li_origin=2 li_receiver=3 li_provider=1
2017-06-14 23:14:51 PDT CONFIG storeListen: li_origin=1 li_receiver=3 li_provider=2
2017-06-14 23:14:51 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:14:51 PDT CONFIG remoteWorkerThread_1: connection for provider 1 terminated
2017-06-14 23:14:51 PDT CONFIG remoteWorkerThread_1: disconnecting from data provider 1
2017-06-14 23:14:51 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:14:51 PDT WARN remoteWorkerThread_1: copy set: data provider 2 only on sync 5000000128 - sleep 5 seconds
2017-06-14 23:14:55 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:14:55 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:14:55 PDT INFO remoteWorkerThread_2: SYNC 5000000102 done in 0.012 seconds
2017-06-14 23:14:56 PDT INFO copy_set 1 - omit=f - bool=0
2017-06-14 23:14:56 PDT INFO omit is FALSE
2017-06-14 23:14:56 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:14:56 PDT CONFIG remoteWorkerThread_1: connected to provider DB
2017-06-14 23:14:56 PDT CONFIG remoteWorkerThread_1: prepare to copy table "il_longlive"."entity"
2017-06-14 23:14:56 PDT CONFIG remoteWorkerThread_1: prepare to copy table "il_longlive"."entity_result"
2017-06-14 23:14:56 PDT CONFIG remoteWorkerThread_1: prepare to copy table "il_longlive"."result"
2017-06-14 23:14:56 PDT CONFIG remoteWorkerThread_1: prepare to copy table "il_longlive"."result_logbook"
2017-06-14 23:14:56 PDT CONFIG remoteWorkerThread_1: all tables for set 1 found on subscriber
2017-06-14 23:14:56 PDT CONFIG remoteWorkerThread_1: copy sequence "il_longlive"."entity_entity_id_seq"
2017-06-14 23:14:56 PDT CONFIG remoteWorkerThread_1: copy sequence "il_longlive"."entity_result_id_seq"
2017-06-14 23:14:56 PDT CONFIG remoteWorkerThread_1: copy sequence "il_longlive"."result_id_seq"
2017-06-14 23:14:56 PDT CONFIG remoteWorkerThread_1: copy sequence "il_longlive"."result_logbook_record_id_seq"
2017-06-14 23:14:56 PDT CONFIG remoteWorkerThread_1: copy table "il_longlive"."entity"
2017-06-14 23:14:56 PDT CONFIG remoteWorkerThread_1: Begin COPY of table "il_longlive"."entity"
NOTICE: truncate of "il_longlive"."entity" succeeded
2017-06-14 23:15:03 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:15:03 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:15:03 PDT INFO remoteWorkerThread_2: SYNC 5000000103 done in 0.033 seconds
2017-06-14 23:15:17 PDT CONFIG remoteWorkerThread_1: 125045870 bytes copied for table "il_longlive"."entity"
2017-06-14 23:15:19 PDT INFO remoteWorkerThread_2: SYNC 5000000104 done in 0.006 seconds
2017-06-14 23:15:22 PDT INFO remoteWorkerThread_2: SYNC 5000000105 done in 0.005 seconds
2017-06-14 23:15:25 PDT CONFIG remoteWorkerThread_1: 28.669 seconds to copy table "il_longlive"."entity"
2017-06-14 23:15:25 PDT CONFIG remoteWorkerThread_1: copy table "il_longlive"."entity_result"
2017-06-14 23:15:25 PDT CONFIG remoteWorkerThread_1: Begin COPY of table "il_longlive"."entity_result"
NOTICE: truncate of "il_longlive"."entity_result" succeeded
2017-06-14 23:15:34 PDT INFO remoteWorkerThread_2: SYNC 5000000106 done in 0.004 seconds
2017-06-14 23:15:37 PDT CONFIG remoteWorkerThread_1: 66996342 bytes copied for table "il_longlive"."entity_result"
2017-06-14 23:15:45 PDT INFO remoteWorkerThread_2: SYNC 5000000107 done in 0.012 seconds
2017-06-14 23:15:56 PDT INFO remoteWorkerThread_2: SYNC 5000000108 done in 0.004 seconds
2017-06-14 23:16:07 PDT INFO remoteWorkerThread_2: SYNC 5000000109 done in 0.005 seconds
2017-06-14 23:16:10 PDT CONFIG remoteWorkerThread_1: 45.106 seconds to copy table "il_longlive"."entity_result"
2017-06-14 23:16:10 PDT CONFIG remoteWorkerThread_1: copy table "il_longlive"."result"
2017-06-14 23:16:10 PDT CONFIG remoteWorkerThread_1: Begin COPY of table "il_longlive"."result"
NOTICE: truncate of "il_longlive"."result" succeeded
2017-06-14 23:16:11 PDT INFO remoteWorkerThread_2: SYNC 5000000110 done in 0.015 seconds
2017-06-14 23:16:27 PDT CONFIG remoteWorkerThread_1: 105961503 bytes copied for table "il_longlive"."result"
2017-06-14 23:16:29 PDT INFO remoteWorkerThread_2: SYNC 5000000111 done in 0.005 seconds
2017-06-14 23:16:32 PDT INFO remoteWorkerThread_2: SYNC 5000000112 done in 0.005 seconds
2017-06-14 23:16:35 PDT CONFIG remoteWorkerThread_1: 25.058 seconds to copy table "il_longlive"."result"
2017-06-14 23:16:35 PDT CONFIG remoteWorkerThread_1: copy table "il_longlive"."result_logbook"
2017-06-14 23:16:35 PDT CONFIG remoteWorkerThread_1: Begin COPY of table "il_longlive"."result_logbook"
NOTICE: truncate of "il_longlive"."result_logbook" succeeded
2017-06-14 23:16:35 PDT CONFIG remoteWorkerThread_1: 79 bytes copied for table "il_longlive"."result_logbook"
2017-06-14 23:16:35 PDT CONFIG remoteWorkerThread_1: 0.079 seconds to copy table "il_longlive"."result_logbook"
2017-06-14 23:16:35 PDT CONFIG remoteWorkerThread_1: set last_value of sequence 101 ("il_longlive"."entity_entity_id_seq") to 2528612
2017-06-14 23:16:35 PDT CONFIG remoteWorkerThread_1: set last_value of sequence 103 ("il_longlive"."result_id_seq") to 2611527
2017-06-14 23:16:35 PDT CONFIG remoteWorkerThread_1: set last_value of sequence 102 ("il_longlive"."entity_result_id_seq") to 2611527
2017-06-14 23:16:35 PDT CONFIG remoteWorkerThread_1: set last_value of sequence 104 ("il_longlive"."result_logbook_record_id_seq") to 1
2017-06-14 23:16:35 PDT INFO remoteWorkerThread_1: 0.002 seconds to copy sequences
2017-06-14 23:16:35 PDT INFO remoteWorkerThread_1: copy_set SYNC found, use event seqno 5000000147.
2017-06-14 23:16:35 PDT INFO remoteWorkerThread_1: 0.015 seconds to build initial setsync status
2017-06-14 23:16:35 PDT INFO copy_set 1 done in 98.961 seconds
2017-06-14 23:16:35 PDT CONFIG enableSubscription: sub_set=1
2017-06-14 23:16:35 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:16:35 PDT CONFIG remoteWorkerThread_2: connection for provider 2 terminated
2017-06-14 23:16:35 PDT CONFIG remoteWorkerThread_2: disconnecting from data provider 2
2017-06-14 23:16:35 PDT CONFIG remoteWorkerThread_2: connection for provider 1 terminated
2017-06-14 23:16:35 PDT CONFIG remoteWorkerThread_2: disconnecting from data provider 1
2017-06-14 23:16:35 PDT CONFIG storeListen: li_origin=2 li_receiver=3 li_provider=2
2017-06-14 23:16:35 PDT CONFIG storeListen: li_origin=2 li_receiver=3 li_provider=1
2017-06-14 23:16:35 PDT CONFIG storeListen: li_origin=1 li_receiver=3 li_provider=2
2017-06-14 23:16:35 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:16:35 PDT CONFIG remoteWorkerThread_1: added active set 1 to provider 2
2017-06-14 23:16:35 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:16:35 PDT CONFIG remoteWorkerThread_1: update provider configuration
2017-06-14 23:16:35 PDT CONFIG remoteWorkerThread_1: added active set 1 to provider 2
2017-06-14 23:16:35 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:16:35 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:16:35 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:16:35 PDT INFO remoteWorkerThread_1: SYNC 5000000151 done in 0.042 seconds
2017-06-14 23:16:35 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:16:35 PDT INFO remoteWorkerThread_1: SYNC 5000000155 done in 0.006 seconds
2017-06-14 23:16:35 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:16:35 PDT INFO remoteWorkerThread_1: SYNC 5000000159 done in 0.005 seconds
2017-06-14 23:16:37 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:16:37 PDT INFO remoteWorkerThread_1: SYNC 5000000160 done in 0.008 seconds
2017-06-14 23:16:41 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:16:41 PDT CONFIG version for "host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:16:41 PDT INFO remoteWorkerThread_2: SYNC 5000000113 done in 0.013 seconds
2017-06-14 23:16:55 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:16:55 PDT INFO remoteWorkerThread_2: SYNC 5000000114 done in 0.005 seconds
2017-06-14 23:16:55 PDT INFO remoteWorkerThread_1: SYNC 5000000161 done in 0.008 seconds
2017-06-14 23:16:59 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:16:59 PDT INFO remoteWorkerThread_1: SYNC 5000000162 done in 0.007 seconds
2017-06-14 23:17:03 PDT INFO remoteWorkerThread_2: SYNC 5000000115 done in 0.003 seconds
2017-06-14 23:17:14 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:17:14 PDT INFO remoteWorkerThread_2: SYNC 5000000116 done in 0.004 seconds
2017-06-14 23:17:14 PDT INFO remoteWorkerThread_1: SYNC 5000000163 done in 0.008 seconds
2017-06-14 23:17:28 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:17:28 PDT INFO remoteWorkerThread_2: SYNC 5000000117 done in 0.004 seconds
2017-06-14 23:17:28 PDT INFO remoteWorkerThread_1: SYNC 5000000165 done in 0.008 seconds
2017-06-14 23:17:32 PDT INFO remoteWorkerThread_2: SYNC 5000000118 done in 0.003 seconds
2017-06-14 23:17:36 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:17:36 PDT INFO remoteWorkerThread_1: SYNC 5000000166 done in 0.007 seconds
2017-06-14 23:17:47 PDT INFO remoteWorkerThread_2: SYNC 5000000119 done in 0.002 seconds
2017-06-14 23:17:48 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:17:48 PDT INFO remoteWorkerThread_1: SYNC 5000000167 done in 0.007 seconds
2017-06-14 23:17:52 PDT INFO remoteWorkerThread_2: SYNC 5000000120 done in 0.003 seconds
2017-06-14 23:18:03 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:18:03 PDT INFO remoteWorkerThread_2: SYNC 5000000121 done in 0.004 seconds
2017-06-14 23:18:03 PDT INFO remoteWorkerThread_1: SYNC 5000000168 done in 0.008 seconds
2017-06-14 23:18:14 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:18:14 PDT INFO remoteWorkerThread_2: SYNC 5000000122 done in 0.004 seconds
2017-06-14 23:18:14 PDT INFO remoteWorkerThread_1: SYNC 5000000169 done in 0.007 seconds
2017-06-14 23:18:18 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:18:18 PDT INFO remoteWorkerThread_1: SYNC 5000000170 done in 0.022 seconds
2017-06-14 23:18:26 PDT INFO remoteWorkerThread_2: SYNC 5000000123 done in 0.003 seconds
2017-06-14 23:18:30 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:18:30 PDT INFO remoteWorkerThread_1: SYNC 5000000171 done in 0.007 seconds
2017-06-14 23:18:31 PDT INFO remoteWorkerThread_2: SYNC 5000000124 done in 0.002 seconds
2017-06-14 23:18:44 PDT CONFIG remoteWorkerThread_2: update provider configuration
2017-06-14 23:18:44 PDT CONFIG version for "host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888" is 90505
2017-06-14 23:18:44 PDT INFO remoteWorkerThread_2: SYNC 5000000125 done in 0.015 seconds
2017-06-14 23:18:49 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:18:49 PDT INFO remoteWorkerThread_1: SYNC 5000000172 done in 0.007 seconds
2017-06-14 23:18:53 PDT INFO remoteWorkerThread_2: SYNC 5000000126 done in 0.004 seconds
2017-06-14 23:18:54 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:18:54 PDT INFO remoteWorkerThread_1: SYNC 5000000173 done in 0.006 seconds
2017-06-14 23:18:58 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:18:58 PDT INFO remoteWorkerThread_1: SYNC 5000000174 done in 0.006 seconds
2017-06-14 23:19:02 PDT INFO remoteWorkerThread_2: SYNC 5000000127 done in 0.012 seconds
2017-06-14 23:19:10 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:19:10 PDT INFO remoteWorkerThread_1: SYNC 5000000175 done in 0.015 seconds
2017-06-14 23:19:11 PDT INFO remoteWorkerThread_2: SYNC 5000000128 done in 0.004 seconds
2017-06-14 23:19:22 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:19:22 PDT INFO remoteWorkerThread_2: SYNC 5000000129 done in 0.005 seconds
2017-06-14 23:19:22 PDT INFO remoteWorkerThread_1: SYNC 5000000176 done in 0.007 seconds
2017-06-14 23:19:36 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:19:36 PDT INFO remoteWorkerThread_2: SYNC 5000000130 done in 0.006 seconds
2017-06-14 23:19:36 PDT INFO remoteWorkerThread_1: SYNC 5000000177 done in 0.007 seconds
2017-06-14 23:19:37 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:19:37 PDT INFO remoteWorkerThread_1: SYNC 5000000178 done in 0.006 seconds
2017-06-14 23:19:41 PDT INFO remoteWorkerThread_2: SYNC 5000000131 done in 0.005 seconds
2017-06-14 23:19:55 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:19:55 PDT INFO remoteWorkerThread_2: SYNC 5000000132 done in 0.006 seconds
2017-06-14 23:19:55 PDT INFO remoteWorkerThread_1: SYNC 5000000179 done in 0.007 seconds
2017-06-14 23:20:06 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:20:06 PDT INFO remoteWorkerThread_2: SYNC 5000000133 done in 0.005 seconds
2017-06-14 23:20:06 PDT INFO remoteWorkerThread_1: SYNC 5000000180 done in 0.007 seconds
2017-06-14 23:20:16 PDT INFO remoteWorkerThread_2: SYNC 5000000134 done in 0.007 seconds
2017-06-14 23:20:20 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:20:20 PDT INFO remoteWorkerThread_1: SYNC 5000000182 done in 0.007 seconds
2017-06-14 23:20:21 PDT INFO remoteWorkerThread_2: SYNC 5000000135 done in 0.005 seconds
2017-06-14 23:20:35 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:20:35 PDT INFO remoteWorkerThread_2: SYNC 5000000136 done in 0.006 seconds
2017-06-14 23:20:35 PDT INFO remoteWorkerThread_1: SYNC 5000000183 done in 0.008 seconds
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=19234
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=19239
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=19244
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=19348
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=19566
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=19567
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=19568
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=19663
CONTEXT: SQL statement "SELECT "_region_il".cleanupNodelock()"
PL/pgSQL function _region_il.cleanupevent(interval) line 82 at PERFORM
NOTICE: Slony-I: log switch to sl_log_2 complete - truncate sl_log_1
CONTEXT: PL/pgSQL function _region_il.cleanupevent(interval) line 95 at assignment
2017-06-14 23:20:39 PDT INFO cleanupThread: 0.077 seconds for cleanupEvent()
2017-06-14 23:20:39 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:20:39 PDT INFO remoteWorkerThread_1: SYNC 5000000184 done in 0.008 seconds
2017-06-14 23:20:43 PDT INFO remoteWorkerThread_2: SYNC 5000000137 done in 0.005 seconds
2017-06-14 23:20:54 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:20:54 PDT INFO remoteWorkerThread_2: SYNC 5000000138 done in 0.005 seconds
2017-06-14 23:20:54 PDT INFO remoteWorkerThread_1: SYNC 5000000185 done in 0.007 seconds
2017-06-14 23:21:04 PDT INFO remoteWorkerThread_2: SYNC 5000000139 done in 0.009 seconds
2017-06-14 23:21:08 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:21:08 PDT INFO remoteWorkerThread_1: SYNC 5000000186 done in 0.006 seconds
2017-06-14 23:21:11 PDT INFO remoteWorkerThread_2: SYNC 5000000140 done in 0.005 seconds
2017-06-14 23:21:16 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:21:16 PDT INFO remoteWorkerThread_1: SYNC 5000000188 done in 0.007 seconds
2017-06-14 23:21:27 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:21:27 PDT INFO remoteWorkerThread_2: SYNC 5000000141 done in 0.006 seconds
2017-06-14 23:21:27 PDT INFO remoteWorkerThread_1: SYNC 5000000189 done in 0.007 seconds
2017-06-14 23:21:31 PDT INFO remoteWorkerThread_2: SYNC 5000000142 done in 0.005 seconds
2017-06-14 23:21:45 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:21:45 PDT INFO remoteWorkerThread_2: SYNC 5000000143 done in 0.005 seconds
2017-06-14 23:21:45 PDT INFO remoteWorkerThread_1: SYNC 5000000190 done in 0.007 seconds
2017-06-14 23:21:49 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:21:49 PDT INFO remoteWorkerThread_1: SYNC 5000000191 done in 0.008 seconds
2017-06-14 23:21:52 PDT INFO remoteWorkerThread_2: SYNC 5000000144 done in 0.005 seconds
2017-06-14 23:22:05 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:22:05 PDT INFO remoteWorkerThread_2: SYNC 5000000145 done in 0.006 seconds
2017-06-14 23:22:05 PDT INFO remoteWorkerThread_1: SYNC 5000000192 done in 0.008 seconds
2017-06-14 23:22:18 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:22:18 PDT INFO remoteWorkerThread_2: SYNC 5000000146 done in 0.005 seconds
2017-06-14 23:22:18 PDT INFO remoteWorkerThread_1: SYNC 5000000194 done in 0.007 seconds
2017-06-14 23:22:22 PDT INFO remoteWorkerThread_2: SYNC 5000000147 done in 0.005 seconds
2017-06-14 23:22:30 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:22:30 PDT INFO remoteWorkerThread_1: SYNC 5000000195 done in 0.016 seconds
2017-06-14 23:22:31 PDT INFO remoteWorkerThread_2: SYNC 5000000148 done in 0.005 seconds
2017-06-14 23:22:45 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:22:45 PDT INFO remoteWorkerThread_2: SYNC 5000000149 done in 0.006 seconds
2017-06-14 23:22:45 PDT INFO remoteWorkerThread_1: SYNC 5000000196 done in 0.008 seconds
2017-06-14 23:22:56 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:22:56 PDT INFO remoteWorkerThread_2: SYNC 5000000150 done in 0.006 seconds
2017-06-14 23:22:56 PDT INFO remoteWorkerThread_1: SYNC 5000000198 done in 0.008 seconds
2017-06-14 23:23:10 PDT INFO remoteWorkerThread_2: SYNC 5000000151 done in 0.005 seconds
2017-06-14 23:23:13 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:23:13 PDT INFO remoteWorkerThread_2: SYNC 5000000152 done in 0.017 seconds
2017-06-14 23:23:13 PDT INFO remoteWorkerThread_1: SYNC 5000000199 done in 0.019 seconds
2017-06-14 23:23:26 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:23:26 PDT INFO remoteWorkerThread_2: SYNC 5000000153 done in 0.005 seconds
2017-06-14 23:23:26 PDT INFO remoteWorkerThread_1: SYNC 5000000201 done in 0.007 seconds
2017-06-14 23:23:39 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:23:39 PDT INFO remoteWorkerThread_2: SYNC 5000000154 done in 0.006 seconds
2017-06-14 23:23:39 PDT INFO remoteWorkerThread_1: SYNC 5000000202 done in 0.007 seconds
2017-06-14 23:23:43 PDT INFO remoteWorkerThread_2: SYNC 5000000155 done in 0.004 seconds
2017-06-14 23:23:51 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:23:51 PDT INFO remoteWorkerThread_2: SYNC 5000000156 done in 0.005 seconds
2017-06-14 23:23:51 PDT INFO remoteWorkerThread_1: SYNC 5000000203 done in 0.007 seconds
2017-06-14 23:23:59 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:23:59 PDT INFO remoteWorkerThread_1: SYNC 5000000204 done in 0.007 seconds
2017-06-14 23:24:07 PDT INFO remoteWorkerThread_2: SYNC 5000000157 done in 0.005 seconds
2017-06-14 23:24:10 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:24:10 PDT INFO remoteWorkerThread_1: SYNC 5000000205 done in 0.008 seconds
2017-06-14 23:24:13 PDT INFO remoteWorkerThread_2: SYNC 5000000158 done in 0.005 seconds
2017-06-14 23:24:16 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:24:16 PDT INFO remoteWorkerThread_1: SYNC 5000000206 done in 0.006 seconds
2017-06-14 23:24:24 PDT INFO remoteWorkerThread_2: SYNC 5000000159 done in 0.005 seconds
2017-06-14 23:24:37 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:24:37 PDT INFO remoteWorkerThread_2: SYNC 5000000160 done in 0.006 seconds
2017-06-14 23:24:37 PDT INFO remoteWorkerThread_1: SYNC 5000000207 done in 0.008 seconds
2017-06-14 23:24:50 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:24:50 PDT INFO remoteWorkerThread_2: SYNC 5000000161 done in 0.005 seconds
2017-06-14 23:24:50 PDT INFO remoteWorkerThread_1: SYNC 5000000209 done in 0.007 seconds
2017-06-14 23:24:53 PDT INFO remoteWorkerThread_2: SYNC 5000000162 done in 0.005 seconds
2017-06-14 23:25:06 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:25:06 PDT INFO remoteWorkerThread_2: SYNC 5000000163 done in 0.013 seconds
2017-06-14 23:25:06 PDT INFO remoteWorkerThread_1: SYNC 5000000210 done in 0.014 seconds
2017-06-14 23:25:19 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:25:19 PDT INFO remoteWorkerThread_2: SYNC 5000000164 done in 0.005 seconds
2017-06-14 23:25:19 PDT INFO remoteWorkerThread_1: SYNC 5000000211 done in 0.007 seconds
2017-06-14 23:25:22 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:25:22 PDT INFO remoteWorkerThread_2: SYNC 5000000165 done in 0.006 seconds
2017-06-14 23:25:22 PDT INFO remoteWorkerThread_1: SYNC 5000000212 done in 0.008 seconds
2017-06-14 23:25:30 PDT INFO remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 2
2017-06-14 23:25:30 PDT INFO remoteWorkerThread_1: SYNC 5000000213 done in 0.006 seconds
2017-06-14 23:25:31 PDT INFO remoteWorkerThread_2: SYNC 5000000166 done in 0.004 seconds
13. 查看数据同步状态
$ psql -h 10.189.100.226 -U slonyadmin -d region_il -c "\d il_longlive.entity" -W
Password for user slonyadmin:
Table "il_longlive.entity"
Column | Type | Modifiers
--------------+------------------------+------------------------------------------------------------------------
entity_id | integer | not null default nextval('il_longlive.entity_entity_id_seq'::regclass)
country_code | character varying(3) |
state_code | character varying(50) |
name | character varying(128) |
islive | boolean |
Indexes:
"entity_pkey" PRIMARY KEY, btree (entity_id)
Triggers: #可以看到Slony-I配置完成后原表多了触发器
_region_il_denyaccess BEFORE INSERT OR DELETE OR UPDATE ON il_longlive.entity FOR EACH ROW EXECUTE PROCEDURE _region_il.denyaccess('_region_il')
_region_il_truncatedeny BEFORE TRUNCATE ON il_longlive.entity FOR EACH STATEMENT EXECUTE PROCEDURE _region_il.deny_truncate()
Disabled user triggers:
_region_il_logtrigger AFTER INSERT OR DELETE OR UPDATE ON il_longlive.entity FOR EACH ROW EXECUTE PROCEDURE _region_il.logtrigger('_region_il', '', 'k')
_region_il_truncatetrigger BEFORE TRUNCATE ON il_longlive.entity FOR EACH STATEMENT EXECUTE PROCEDURE _region_il.log_truncate('')
$ psql -h 10.189.102.118 -U slonyadmin -d region_il -c "select count(*) from il_longlive.entity;" -W
Password for user slonyadmin:
count
---------
2528612
(1 row)
$ psql -h 10.189.100.195 -U slonyadmin -d region_il -c "select count(*) from il_longlive.entity;" -W
Password for user slonyadmin:
count
---------
2528612 #可以看到数据已经同步到辅节点1了
(1 row)
$ psql -h 10.189.100.226 -U slonyadmin -d region_il -c "select count(*) from il_longlive.entity;" -W
Password for user slonyadmin:
count
---------
2528612 #可以看到数据已经同步到辅节点2了
(1 row)
14. Slony-I相关表或者视图查看
- sl_apply_stats: 每个节点本地的复制表的DML统计状态
- sl_components: slon/slonik组件等当前的工作状态
region_il=# select * from sl_components;
co_actor | co_pid | co_node | co_connection_pid | co_activity | co_starttime | co_event | co_eventtype
----------------------+--------+---------+-------------------+------------------+------------------------+------------+--------------
local_monitor | 2455 | 0 | 2470 | thread main loop | 2017-06-14 22:54:42-07 | | n/a
local_cleanup | 2455 | 0 | 2468 | cleanupEvent | 2017-06-15 00:44:48-07 | | n/a
remoteWorkerThread_2 | 2455 | 2 | 2471 | SYNC | 2017-06-15 00:49:14-07 | 5000000668 | SYNC
local_listen | 2455 | 1 | 2459 | thread main loop | 2017-06-15 00:49:20-07 | | n/a
remoteWorkerThread_3 | 2455 | 3 | 2467 | SYNC | 2017-06-15 00:49:17-07 | 5000000653 | SYNC
remote listener | 2455 | 3 | 19059 | thread main loop | 2017-06-15 00:49:18-07 | | n/a
local_sync | 2455 | 0 | 2469 | thread main loop | 2017-06-15 00:49:21-07 | | n/a
(7 rows)
- sl_table: 当前集群中的表信息
region_il=# select * from sl_table ;
tab_id | tab_reloid | tab_relname | tab_nspname | tab_set | tab_idxname | tab_altered | tab_comment
--------+------------+----------------+-------------+---------+---------------------+-------------+---------------------------------------------------
101 | 19350 | entity | il_longlive | 1 | entity_pkey | f | Table il_longlive.entity with primary key
102 | 19355 | entity_result | il_longlive | 1 | entity_result_pkey | f | Table il_longlive.entity_result with primary key
103 | 19360 | result | il_longlive | 1 | result_pkey | f | Table il_longlive.result with primary key
104 | 19365 | result_logbook | il_longlive | 1 | result_logbook_pkey | f | Table il_longlive.result_logbook with primary key
(4 rows)
- sl_set: 当前集群中的集合信息
region_il=# select * from sl_set;
set_id | set_origin | set_locked | set_comment
--------+------------+------------+------------------------------------------
1 | 1 | | Set 1 (il_longlive_schema) for region_il
(1 row)
- sl_set: 当前集群中的节点信息
region_il=# select * from sl_node;
no_id | no_active | no_comment | no_failed
-------+-----------+-----------------------------------+-----------
1 | t | Node 1 - region_il@10.189.102.118 | f
2 | t | Node 2 - region_il@10.189.100.195 | f
3 | t | Node 3 - region_il@10.189.100.226 | f
(3 rows)
- sl_status: 显示远程节点落后主节点的延迟
region_il=# select * from sl_status;
-[ RECORD 1 ]-------------+------------------------------
st_origin | 1
st_received | 3
st_last_event | 5000000800
st_last_event_ts | 2017-06-15 01:03:19.585161-07
st_last_received | 5000000799
st_last_received_ts | 2017-06-15 01:03:14.281766-07
st_last_received_event_ts | 2017-06-15 01:03:09.57357-07
st_lag_num_events | 1
st_lag_time | 00:00:11.414753
-[ RECORD 2 ]-------------+------------------------------
st_origin | 1
st_received | 2
st_last_event | 5000000800
st_last_event_ts | 2017-06-15 01:03:19.585161-07
st_last_received | 5000000799
st_last_received_ts | 2017-06-15 01:03:14.193743-07
st_last_received_event_ts | 2017-06-15 01:03:09.57357-07
st_lag_num_events | 1
st_lag_time | 00:00:11.414753
- sl_subscribe: 显示当前集群中的订阅者
region_il=# select * from sl_subscribe;
sub_set | sub_provider | sub_receiver | sub_forward | sub_active
---------+--------------+--------------+-------------+------------
1 | 1 | 2 | t | t
1 | 2 | 3 | t | t
(2 rows)
Slony-I日常维护
1. 更改主表的表结构结构
1.1. 查看要修改的表结构信息
region_il=# \d result_logbook;
Table "il_longlive.result_logbook"
Column | Type | Modifiers
------------------+-----------------------------+--------------------------------------------------------------------
record_id | integer | not null default nextval('result_logbook_record_id_seq'::regclass)
schema | character varying(255) | not null
vendor_code | character varying(255) |
tag | character varying(255) |
total_result_cnt | integer |
exist_result_cnt | integer |
new_result_cnt | integer |
max_result_id | integer |
islive | boolean | default true
create_time | timestamp without time zone | default now()
update_time | timestamp without time zone |
Indexes:
"result_logbook_pkey" PRIMARY KEY, btree (record_id)
1.2. 创建修改表字段的ddl语句文件
$ cat /home/postgres/alter_table_result_logbook.sql
alter table il_longlive.result_logbook add column address varchar(40);
1.3. 通过执行slonik_execute_script命令
$ /usr/local/pgsql/bin/slonik_execute_script /home/postgres/alter_table_result_logbook.sql
cluster name = region_il;
node admin conninfo='host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888';
execute script (
set id = ,
filename = '/home/postgres/alter_table_result_logbook.sql',
event node =
);
$ cat /home/postgres/alter_table_result_logbook.sh
#!/bin/sh
slonik << __EOF__
cluster name = region_il;
node admin conninfo='host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888';
execute script (
filename = '/home/postgres/alter_table_result_logbook.sql',
event node =
);
__EOF__
$ chmod +x /home/postgres/alter_table_result_logbook.sh
$ /home/postgres/alter_table_result_logbook.sh
1.4. 验证所有节点上的表结构修改是否成功
$ psql -h 10.189.102.118 -U slonyadmin -d region_il -c "\d il_longlive.result_logbook" | grep address
Password for user slonyadmin:
address | character varying()
$ psql -h 10.189.100.195 -U slonyadmin -d region_il -c "\d il_longlive.result_logbook" | grep address
Password for user slonyadmin:
address | character varying()
$ psql -h 10.189.100.226 -U slonyadmin -d region_il -c "\d il_longlive.result_logbook" | grep address
Password for user slonyadmin:
address | character varying()
2. 向现有集群中增加一个复制表
2.1. 查看主库的的表结构信息,确认符合Slony-I的要求(主键)
region_il=# \d snapshot
Table "il_longlive.snapshot"
Column | Type | Modifiers
-----------------+--------------------------------+-------------------------------------------------------
id | integer | not null default nextval('snapshot_id_seq'::regclass)
feed_schema | character varying(255) |
job | character varying(255) |
table_name | character varying(255) |
max_primary_key | bigint |
create_time | timestamp(0) without time zone |
update_time | timestamp(0) without time zone |
Indexes:
"snapshot_pkey" PRIMARY KEY, btree (id)
2.2. 将主表结构导入到所有辅节点
$ pg_dump -U slonyadmin -s -t il_longlive.snapshot region_il | psql -h 10.189.100.195 -U slonyadmin region_il
$ pg_dump -U slonyadmin -s -t il_longlive.snapshot region_il | psql -h 10.189.100.226 -U slonyadmin region_il
2.3. 修改Slony-I配置文件slon_tools_conf,增加新复制集,并复制到所有节点(不是必须,为了各节点配置文件完整性)
$ cat /usr/local/pgsql/etc/slon_tools.conf
if ($ENV{"SLONYNODES"}) {
require $ENV{"SLONYNODES"};
} else {
$CLUSTER_NAME = 'region_il';
$PIDFILE_DIR = '/usr/local/pgsql/data';
$LOGDIR = '/usr/local/pgsql/data/pg_log';
$MASTERNODE = ;
$DEBUGLEVEL = ;
$START_WATCHDOG = ;
$SLEEP_TIME = ;
$WATCHDOG_VERBOSE = ; add_node(node => ,
host => '10.189.102.118',
dbname => 'region_il',
port => ,
user => 'slonyadmin',
password => '',); add_node(node => ,
parent => ,
host => '10.189.100.195',
dbname => 'region_il',
port => ,
user => 'slonyadmin',
password => '',); add_node(node => ,
parent => ,
host => '10.189.100.226',
dbname => 'region_il',
port => ,
user => 'slonyadmin',
password => '',);
} $SLONY_SETS = {
"il_longlive_schema" => {
"set_id" => ,
"table_id" => ,
"sequence_id" => ,
"pkeyedtables" => [
'il_longlive.entity',
'il_longlive.entity_result',
'il_longlive.result',
'il_longlive.result_logbook',
], "keyedtables" => {}, "sequences" => ['il_longlive.entity_entity_id_seq',
'il_longlive.entity_result_id_seq',
'il_longlive.result_id_seq',
'il_longlive.result_logbook_record_id_seq',
],
}, "il_longlive_schema1" => {
"set_id" => ,
"table_id" => ,
"sequence_id" => ,
"pkeyedtables" => [
'il_longlive.snapshot',
], "keyedtables" => {}, "sequences" => ['il_longlive.snapshot_id_seq'
],
}, }; if ($ENV{"SLONYSET"}) {
require $ENV{"SLONYSET"};
} # Please do not add or change anything below this point.
;
$ scp /usr/local/pgsql/etc/slon_tools.conf postgres@10.189.100.195:/usr/local/pgsql/etc/slon_tools.conf
$ scp /usr/local/pgsql/etc/slon_tools.conf postgres@10.189.100.226:/usr/local/pgsql/etc/slon_tools.conf
2.4. 在主节点创建新复制集
$ /usr/local/pgsql/bin/slonik_create_set --config /usr/local/pgsql/etc/slon_tools.conf
cluster name = region_il;
node admin conninfo='host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888'; # TABLE ADD KEY # CREATE SET
create set (id = , origin = , comment = 'Set 2 (il_longlive_schema1) for region_il'); # SET ADD TABLE
echo 'Subscription set 2 (il_longlive_schema1) created';
echo 'Adding tables to the subscription set';
set add table (set id = , origin = , id = ,
full qualified name = 'il_longlive.snapshot',
comment = 'Table il_longlive.snapshot with primary key');
echo 'Add primary keyed table il_longlive.snapshot'; # SET ADD SEQUENCE
echo 'Adding sequences to the subscription set';
set add sequence (set id = , origin = , id = ,
full qualified name = 'il_longlive.snapshot_id_seq',
comment = 'Sequence il_longlive.snapshot_id_seq');
echo 'Add sequence il_longlive.snapshot_id_seq';
echo 'All tables added';
$ /usr/local/pgsql/bin/slonik_create_set --config /usr/local/pgsql/etc/slon_tools.conf | slonik
<stdin>:: Subscription set (il_longlive_schema1) created
<stdin>:: Adding tables to the subscription set
<stdin>:: Add primary keyed table il_longlive.snapshot
<stdin>:: Adding sequences to the subscription set
<stdin>:: Add sequence il_longlive.snapshot_id_seq
<stdin>:: All tables added
2.5. 在主节点创建新复制集的订阅者
$ /usr/local/pgsql/bin/slonik_subscribe_set --config /usr/local/pgsql/etc/slon_tools.conf
cluster name = region_il;
node admin conninfo='host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888'; subscribe set (id = , provider = , receiver = , forward = yes);
echo 'Subscribed nodes to set 2';
$ /usr/local/pgsql/bin/slonik_subscribe_set --config /usr/local/pgsql/etc/slon_tools.conf
cluster name = region_il;
node admin conninfo='host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888'; subscribe set (id = , provider = , receiver = , forward = yes);
echo 'Subscribed nodes to set 2';
$ /usr/local/pgsql/bin/slonik_subscribe_set --config /usr/local/pgsql/etc/slon_tools.conf | slonik
<stdin>:: Subscribed nodes to set
$ /usr/local/pgsql/bin/slonik_subscribe_set --config /usr/local/pgsql/etc/slon_tools.conf | slonik
<stdin>:: Subscribed nodes to set
2.6. 查看所有辅节点数据同步状态
$ psql -h 10.189.102.118 -U slonyadmin -d region_il -c "select count(*) from il_longlive.snapshot;" #主节点
Password for user slonyadmin:
count
------- ( row) $ psql -h 10.189.100.196 -U slonyadmin -d region_il -c "select count(*) from il_longlive.snapshot;" #辅节点1
^C
[postgres@ec2t-pgtest- bin]$ psql -h 10.189.100.195 -U slonyadmin -d region_il -c "select count(*) from il_longlive.snapshot;"
Password for user slonyadmin:
count
------- ( row) $ psql -h 10.189.100.226 -U slonyadmin -d region_il -c "select count(*) from il_longlive.snapshot;" #辅节点2
Password for user slonyadmin:
count
------- ( row)
2.7. 查看Slony-I系统表信息
region_il=# select * from sl_set;
set_id | set_origin | set_locked | set_comment
--------+------------+------------+-------------------------------------------
1 | 1 | | Set 1 (il_longlive_schema) for region_il
2 | 1 | | Set 2 (il_longlive_schema1) for region_il
(2 rows) region_il=# select * from sl_table;
tab_id | tab_reloid | tab_relname | tab_nspname | tab_set | tab_idxname | tab_altered | tab_comment
--------+------------+----------------+-------------+---------+---------------------+-------------+---------------------------------------------------
101 | 19350 | entity | il_longlive | 1 | entity_pkey | f | Table il_longlive.entity with primary key
102 | 19355 | entity_result | il_longlive | 1 | entity_result_pkey | f | Table il_longlive.entity_result with primary key
103 | 19360 | result | il_longlive | 1 | result_pkey | f | Table il_longlive.result with primary key
104 | 19365 | result_logbook | il_longlive | 1 | result_logbook_pkey | f | Table il_longlive.result_logbook with primary key
201 | 19380 | snapshot | il_longlive | 2 | snapshot_pkey | f | Table il_longlive.snapshot with primary key
(5 rows)
2.8. 合并复制集
$ /usr/local/pgsql/bin/slonik_merge_sets --config /usr/local/pgsql/etc/slon_tools.conf
cluster name = region_il;
node admin conninfo='host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888';
merge set (id = , add id = , origin = );
echo 'Replication set 2 merged into 1 on origin 1. Set 2 no longer exists.';
$ /usr/local/pgsql/bin/slonik_merge_sets --config /usr/local/pgsql/etc/slon_tools.conf | slonik
<stdin>:: Replication set merged into on origin . Set no longer exists.
2.9. 查看Slony-I系统表信息
region_il=# select * from sl_set;
set_id | set_origin | set_locked | set_comment
--------+------------+------------+------------------------------------------
1 | 1 | | Set 1 (il_longlive_schema) for region_il
(1 row) region_il=# select * from sl_table;
tab_id | tab_reloid | tab_relname | tab_nspname | tab_set | tab_idxname | tab_altered | tab_comment
--------+------------+----------------+-------------+---------+---------------------+-------------+---------------------------------------------------
101 | 19350 | entity | il_longlive | 1 | entity_pkey | f | Table il_longlive.entity with primary key
102 | 19355 | entity_result | il_longlive | 1 | entity_result_pkey | f | Table il_longlive.entity_result with primary key
103 | 19360 | result | il_longlive | 1 | result_pkey | f | Table il_longlive.result with primary key
104 | 19365 | result_logbook | il_longlive | 1 | result_logbook_pkey | f | Table il_longlive.result_logbook with primary key
201 | 19380 | snapshot | il_longlive | 1 | snapshot_pkey | f | Table il_longlive.snapshot with primary key
(5 rows)
3. 向现有集群中移除一个复制表
3.1. 查看Slony-I系统视图确认要移除的表
region_il=# select * from sl_table;
tab_id | tab_reloid | tab_relname | tab_nspname | tab_set | tab_idxname | tab_altered | tab_comment
--------+------------+----------------+-------------+---------+---------------------+-------------+---------------------------------------------------
101 | 19350 | entity | il_longlive | 1 | entity_pkey | f | Table il_longlive.entity with primary key
102 | 19355 | entity_result | il_longlive | 1 | entity_result_pkey | f | Table il_longlive.entity_result with primary key
103 | 19360 | result | il_longlive | 1 | result_pkey | f | Table il_longlive.result with primary key
104 | 19365 | result_logbook | il_longlive | 1 | result_logbook_pkey | f | Table il_longlive.result_logbook with primary key
201 | 19380 | snapshot | il_longlive | 1 | snapshot_pkey | f | Table il_longlive.snapshot with primary key
(5 rows)
3.2. 移除集群中指定的表
$ /usr/local/pgsql/bin/slonik_drop_table --config /usr/local/pgsql/etc/slon_tools.conf
cluster name = region_il;
node admin conninfo='host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888'; # DROP TABLE
try {
SET DROP TABLE (id = , origin = );
} on error {
echo 'Could not drop table 201 for region_il!';
exit ;
} $ /usr/local/pgsql/bin/slonik_drop_table --config /usr/local/pgsql/etc/slon_tools.conf | slonik
3.3. 验证集群中指定的表是否移除
region_il=# select * from sl_table;
tab_id | tab_reloid | tab_relname | tab_nspname | tab_set | tab_idxname | tab_altered | tab_comment
--------+------------+----------------+-------------+---------+---------------------+-------------+---------------------------------------------------
101 | 19350 | entity | il_longlive | 1 | entity_pkey | f | Table il_longlive.entity with primary key
102 | 19355 | entity_result | il_longlive | 1 | entity_result_pkey | f | Table il_longlive.entity_result with primary key
103 | 19360 | result | il_longlive | 1 | result_pkey | f | Table il_longlive.result with primary key
104 | 19365 | result_logbook | il_longlive | 1 | result_logbook_pkey | f | Table il_longlive.result_logbook with primary key
(4 rows) region_il=# \d il_longlive.snapshot #可以看到对应的表上的触发器也被移除了
Table "il_longlive.snapshot"
Column | Type | Modifiers
-----------------+--------------------------------+-------------------------------------------------------------------
id | integer | not null default nextval('il_longlive.snapshot_id_seq'::regclass)
feed_schema | character varying(255) |
job | character varying(255) |
table_name | character varying(255) |
max_primary_key | bigint |
create_time | timestamp(0) without time zone |
update_time | timestamp(0) without time zone |
Indexes:
"snapshot_pkey" PRIMARY KEY, btree (id)
4. 向现有集群中移除一个节点
4.1. 查看Slony-I系统视图确认要移除的节点
region_il=# select * from sl_node;
no_id | no_active | no_comment | no_failed
-------+-----------+-----------------------------------+-----------
1 | t | Node 1 - region_il@10.189.102.118 | f
2 | t | Node 2 - region_il@10.189.100.195 | f
3 | t | Node 3 - region_il@10.189.100.226 | f
(3 rows)
4.2. 移除集群中指定的节点
$ /usr/local/pgsql/bin/slonik_drop_node --config /usr/local/pgsql/etc/slon_tools.conf
cluster name = region_il;
node admin conninfo='host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888';
drop node (id = , event node = );
echo 'dropped node 3 cluster';
$ /usr/local/pgsql/bin/slonik_drop_node --config /usr/local/pgsql/etc/slon_tools.conf | slonik
debug: waiting for , on
debug: waiting for , on
<stdin>:: dropped node cluster
4.3. 验证集群中指定的节点是否移除(此时被移除节点的Slony-I系统表等信息已经被移除)
region_il=# select * from sl_node;
no_id | no_active | no_comment | no_failed
-------+-----------+-----------------------------------+-----------
1 | t | Node 1 - region_il@10.189.102.118 | f
2 | t | Node 2 - region_il@10.189.100.195 | f
(2 rows)
4.4. 停止被关闭节点的slon进程
$ ps -ef | grep slon
postgres Jun02 ? :: /usr/local/pgsql/bin/slon -p /usr/local/pgsql/data/region_il_node2.pid -s -d0 region_il host=ec2t-pgtest- dbname=region_il user=slonyadmin port= password=mypna123
postgres Jun02 ? :: /usr/bin/perl /usr/local/pgsql/bin/slon_watchdog --config=/usr/local/pgsql/etc/slon_tools.conf node2
postgres : ? :: /usr/local/pgsql/bin/slon -p /usr/local/pgsql/data/region_il_node2.pid -s -d0 region_il host=ec2t-pgtest- dbname=region_il user=slonyadmin port= password=mypna123
postgres : ? :: /usr/local/pgsql/bin/slon -p /usr/local/pgsql/data/region_il_node3.pid -s -d0 region_il host=10.189.100.226 dbname=region_il user=slonyadmin port= password=
postgres : ? :: postgres: slonyadmin region_il 10.189.100.226() idle
postgres : pts/ :: grep slon
postgres Jun14 ? :: /usr/local/pgsql/bin/slon -p /usr/local/pgsql/data/region_il_node3.pid -s -d0 region_il host=10.189.100.226 dbname=region_il user=slonyadmin port= password=
postgres Jun14 ? :: /usr/bin/perl /usr/local/pgsql/bin/slon_watchdog --config=/usr/local/pgsql/etc/slon_tools.conf node3
$ slon_status
Slon is running for the 'region_il' cluster on node3.
$ slon_kill
slon_kill.pl... Killing all slon and slon_watchdog instances for the cluster region_il
. Kill slon watchdogs
slon_watchdog for cluster region_il node killed - PID []
slon_watchdog for cluster region_il node killed - PID []
$ ps -ef | grep slon
postgres : pts/ :: grep slon
5. 向现有集群中增加一个节点
5.1. 在新节点安装配置Slony-I(参考之前步骤)
5.2. 查看当街Slony-I系统表节点信息
region_il=# select * from sl_node;
no_id | no_active | no_comment | no_failed
-------+-----------+-----------------------------------+-----------
1 | t | Node 1 - region_il@10.189.102.118 | f
2 | t | Node 2 - region_il@10.189.100.195 | f
(2 rows)
5.3. 向Slony-I配置文件slon_tools.conf 增加新节点信息,并将新配置文件更新到所有节点
$ cat /usr/local/pgsql/etc/slon_tools.conf
...........................................
add_node(node => ,
parent => ,
host => '10.189.100.226',
dbname => 'region_il',
port => ,
user => 'slonyadmin',
password => '',);
............................................
5.4. 在主节点上增加向集群新节点信息
$ /usr/local/pgsql/bin/slonik_add_node --config /usr/local/pgsql/etc/slon_tools.conf # ADD NODE
cluster name = region_il;
node admin conninfo='host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888';
try {
store node (id = , event node = , comment = 'Node 3 - region_il@10.189.100.226');
} on error {
echo 'Failed to add node to cluster';
exit ;
} # STORE PATHS
store path (server = , client = , conninfo = 'host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888');
store path (server = , client = , conninfo = 'host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888');
store path (server = , client = , conninfo = 'host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888');
store path (server = , client = , conninfo = 'host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888');
echo 'added node 3 to cluster';
echo 'Please start a slon replication daemon for node 3';
$ /usr/local/pgsql/bin/slonik_add_node --config /usr/local/pgsql/etc/slon_tools.conf | slonik
<stdin>:: Possible unsupported PostgreSQL version () 9.5, defaulting to 8.4 support
<stdin>:: added node to cluster
<stdin>:: Please start a slon replication daemon for node
5.5. 在新节点启动slon进程
$ slon_start
Invoke slon for node - /usr/local/pgsql/bin/slon -p /usr/local/pgsql/data/region_il_node3.pid -s -d0 region_il 'host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888' > /usr/local/pgsql/data/pg_log/node3/region_il---.log >& &
Slon successfully started for cluster region_il, node node3
PID []
Start the watchdog process as well...
5.6. 在主节点添加新节点的复制订阅信息
$ /usr/local/pgsql/bin/slonik_subscribe_set --config /usr/local/pgsql/etc/etc/slon_tools.conf
Can't locate /usr/local/pgsql/etc/etc/slon_tools.conf in @INC (@INC contains: /usr/local/lib64/perl5 /usr/local/share/perl5 /usr/lib64/perl5/vendor_perl /usr/share/perl5/vendor_perl /usr/lib64/perl5 /usr/share/perl5 .) at /usr/local/pgsql/bin/slonik_subscribe_set line 29.
[postgres@ec2t-pgtest- bin]$ /usr/local/pgsql/bin/slonik_subscribe_set --config /usr/local/pgsql/etc/slon_tools.conf
cluster name = region_il;
node admin conninfo='host=10.189.102.118 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.195 dbname=region_il user=slonyadmin port=5432 password=888888';
node admin conninfo='host=10.189.100.226 dbname=region_il user=slonyadmin port=5432 password=888888'; subscribe set (id = , provider = , receiver = , forward = yes);
echo 'Subscribed nodes to set 1';
[postgres@ec2t-pgtest- bin]$ /usr/local/pgsql/bin/slonik_subscribe_set --config /usr/local/pgsql/etc/slon_tools.conf | slonik
<stdin>:: Subscribed nodes to set
5.7. 验证数据同步(略)