Hello. I am using the following configuration: 2 slaves, directly subscribed to one master. The problem sometimes shows up during controlled failover with 'move set' (doing it as described in [1]). When doing move set from one node to another the third node sometimes does not follow the configuration changes. It keeps complaining about: 'ACCEPT_SET - MOVE_SET or FAILOVER_SET not received yet - sleep' and still points to the wrong origin. This happens nearly in every third case.
Sending 'restart node 3' or killing slon processes directly helps _sometimes_. I've played with 'sync' and 'wait for event' a bit in different places to make the third node receive that 'move set', but with no luck. Script-generated input for slon, which is used to setup, and failover is attached. Set is a sample pgbench database with one additional explicit primary key added. Any suggestions? What can trigger such a behavior? Here is a piece of log with successful 'move set': 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_2: SYNC 134 done in 0.018 seconds 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_2: Received event 2,135 ACCEPT_SET 2008-02-14 09:45:21 EET DEBUG2 start processing ACCEPT_SET 2008-02-14 09:45:21 EET DEBUG2 ACCEPT: set=1 2008-02-14 09:45:21 EET DEBUG2 ACCEPT: old origin=1 2008-02-14 09:45:21 EET DEBUG2 ACCEPT: new origin=2 2008-02-14 09:45:21 EET DEBUG2 ACCEPT: move set seq=147 2008-02-14 09:45:21 EET DEBUG2 got parms ACCEPT_SET 2008-02-14 09:45:21 EET DEBUG2 ACCEPT_SET - node not origin 2008-02-14 09:45:21 EET DEBUG2 ACCEPT_SET - MOVE_SET or FAILOVER_SET exists - adjusting setsync status 2008-02-14 09:45:21 EET DEBUG2 ACCEPT_SET - done 2008-02-14 09:45:21 EET DEBUG2 slon_retry() from pid=18367 2008-02-14 09:45:21 EET DEBUG1 slon: retry requested 2008-02-14 09:45:21 EET DEBUG2 slon: notify worker process to shutdown 2008-02-14 09:45:21 EET INFO remoteListenThread_2: disconnecting from 'dbname=pgb host=192.168.0.202 port=5432 user=slony password=sln' 2008-02-14 09:45:21 EET DEBUG1 localListenThread: thread done 2008-02-14 09:45:21 EET DEBUG1 remoteListenThread_2: thread done 2008-02-14 09:45:21 EET INFO remoteListenThread_1: disconnecting from 'dbname=pgb host=192.168.0.201 port=5432 user=slony password=sln' 2008-02-14 09:45:21 EET DEBUG1 cleanupThread: thread done 2008-02-14 09:45:21 EET DEBUG1 remoteListenThread_1: thread done 2008-02-14 09:45:21 EET DEBUG1 syncThread: thread done 2008-02-14 09:45:21 EET DEBUG1 main: scheduler mainloop returned 2008-02-14 09:45:21 EET DEBUG2 main: wait for remote threads Here is unsuccessful one: 2008-02-14 09:58:24 EET DEBUG2 remoteWorkerThread_2: SYNC 11 processing 2008-02-14 09:58:24 EET DEBUG2 remoteWorkerThread_2: no sets need syncing for this event 2008-02-14 09:58:24 EET DEBUG2 remoteWorkerThread_2: Received event 2,12 ACCEPT_SET 2008-02-14 09:58:24 EET DEBUG2 start processing ACCEPT_SET 2008-02-14 09:58:24 EET DEBUG2 ACCEPT: set=1 2008-02-14 09:58:24 EET DEBUG2 ACCEPT: old origin=1 2008-02-14 09:58:24 EET DEBUG2 ACCEPT: new origin=2 2008-02-14 09:58:24 EET DEBUG2 ACCEPT: move set seq=24 2008-02-14 09:58:24 EET DEBUG2 got parms ACCEPT_SET 2008-02-14 09:58:24 EET DEBUG2 ACCEPT_SET - node not origin 2008-02-14 09:58:24 EET DEBUG2 ACCEPT_SET - MOVE_SET or FAILOVER_SET not received yet - sleep 2008-02-14 09:58:26 EET DEBUG2 remoteListenThread_1: queue event 1,23 SYNC 2008-02-14 09:58:26 EET DEBUG2 remoteWorkerThread_1: Received event 1,23 SYNC 2008-02-14 09:58:26 EET DEBUG2 remoteListenThread_1: queue event 1,24 MOVE_SET 2008-02-14 09:58:26 EET DEBUG2 remoteListenThread_1: queue event 1,25 SYNC 2008-02-14 09:58:26 EET DEBUG2 calc sync size - last time: 1 last length: 2001 ideal: 29 proposed size: 3 2008-02-14 09:58:26 EET DEBUG2 remoteWorkerThread_1: SYNC 23 processing 2008-02-14 09:58:26 EET DEBUG2 remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1 2008-02-14 09:58:26 EET DEBUG4 ssy_action_list value: 2008-02-14 09:58:26 EET DEBUG2 ssy_action_list length: 0 2008-02-14 09:58:26 EET DEBUG2 remoteWorkerThread_1: current local log_status is 0 2008-02-14 09:58:26 EET DEBUG3 remoteWorkerThread_1: activate helper 1 2008-02-14 09:58:26 EET DEBUG4 remoteWorkerThread_1: waiting for log data More logs attached. Slony-I 1.2.13 postgresql 8.2.6 # gcc -v Using built-in specs. Target: i686-pc-linux-gnu Configured with: ../gcc-4.1.2/configure --prefix=/usr --libexecdir=/usr/lib --enable-languages=c,c++,objc --enable-threads=posix --enable-__cxa_atexit --enable-clocale=gnu --enable-shared --disable-nls --with-x=no Thread model: posix gcc version 4.1.2 (CRUX) [1] http://slony.info/adminguide/current/doc/adminguide/failover.html -- Mikhail Kolesnik ICQ: 260259143 IRC: mike_k at freenode/#crux, rusnet/#yalta
2008-02-14 09:58:22 EET DEBUG2 remoteListenThread_2: UNLISTEN 2008-02-14 09:58:22 EET DEBUG2 remoteWorkerThread_2: Received event 2,10 SYNC 2008-02-14 09:58:22 EET DEBUG2 calc sync size - last time: 1 last length: 7999 ideal: 7 proposed size: 3 2008-02-14 09:58:22 EET DEBUG2 remoteWorkerThread_2: SYNC 10 processing 2008-02-14 09:58:22 EET DEBUG2 remoteWorkerThread_2: no sets need syncing for this event 2008-02-14 09:58:24 EET DEBUG2 syncThread: new sl_action_seq 1 - SYNC 10 2008-02-14 09:58:24 EET DEBUG2 remoteListenThread_2: queue event 2,11 SYNC 2008-02-14 09:58:24 EET DEBUG2 remoteListenThread_2: queue event 2,12 ACCEPT_SET 2008-02-14 09:58:24 EET DEBUG2 remoteWorkerThread_2: Received event 2,11 SYNC 2008-02-14 09:58:24 EET DEBUG2 calc sync size - last time: 1 last length: 2006 ideal: 29 proposed size: 3 2008-02-14 09:58:24 EET DEBUG2 remoteWorkerThread_2: SYNC 11 processing 2008-02-14 09:58:24 EET DEBUG2 remoteWorkerThread_2: no sets need syncing for this event 2008-02-14 09:58:24 EET DEBUG2 remoteWorkerThread_2: Received event 2,12 ACCEPT_SET 2008-02-14 09:58:24 EET DEBUG2 start processing ACCEPT_SET 2008-02-14 09:58:24 EET DEBUG2 ACCEPT: set=1 2008-02-14 09:58:24 EET DEBUG2 ACCEPT: old origin=1 2008-02-14 09:58:24 EET DEBUG2 ACCEPT: new origin=2 2008-02-14 09:58:24 EET DEBUG2 ACCEPT: move set seq=24 2008-02-14 09:58:24 EET DEBUG2 got parms ACCEPT_SET 2008-02-14 09:58:24 EET DEBUG2 ACCEPT_SET - node not origin 2008-02-14 09:58:24 EET DEBUG2 ACCEPT_SET - MOVE_SET or FAILOVER_SET not received yet - sleep 2008-02-14 09:58:26 EET DEBUG2 remoteListenThread_1: queue event 1,23 SYNC 2008-02-14 09:58:26 EET DEBUG2 remoteWorkerThread_1: Received event 1,23 SYNC 2008-02-14 09:58:26 EET DEBUG2 remoteListenThread_1: queue event 1,24 MOVE_SET 2008-02-14 09:58:26 EET DEBUG2 remoteListenThread_1: queue event 1,25 SYNC 2008-02-14 09:58:26 EET DEBUG2 calc sync size - last time: 1 last length: 2001 ideal: 29 proposed size: 3 2008-02-14 09:58:26 EET DEBUG2 remoteWorkerThread_1: SYNC 23 processing 2008-02-14 09:58:26 EET DEBUG2 remoteWorkerThread_1: syncing set 1 with 4 table(s) from provider 1 2008-02-14 09:58:26 EET DEBUG4 ssy_action_list value: 2008-02-14 09:58:26 EET DEBUG2 ssy_action_list length: 0 2008-02-14 09:58:26 EET DEBUG2 remoteWorkerThread_1: current local log_status is 0 2008-02-14 09:58:26 EET DEBUG3 remoteWorkerThread_1: activate helper 1 2008-02-14 09:58:26 EET DEBUG4 remoteWorkerThread_1: waiting for log data 2008-02-14 09:58:26 EET DEBUG4 remoteHelperThread_1_1: got work to do 2008-02-14 09:58:26 EET DEBUG2 remoteWorkerThread_1_1: current remote log_status = 0 2008-02-14 09:58:26 EET DEBUG4 remoteHelperThread_1_1: allocate line buffers 2008-02-14 09:58:26 EET DEBUG4 remoteHelperThread_1_1: fetch from cursor 2008-02-14 09:58:26 EET DEBUG2 remoteHelperThread_1_1: 0.001 seconds delay for first row 2008-02-14 09:58:26 EET DEBUG4 remoteHelperThread_1_1: fetched 0 log rows 2008-02-14 09:58:26 EET DEBUG4 remoteHelperThread_1_1: return 10 unused line buffers 2008-02-14 09:58:26 EET DEBUG2 remoteHelperThread_1_1: 0.002 seconds until close cursor 2008-02-14 09:58:26 EET DEBUG2 remoteHelperThread_1_1: inserts=0 updates=0 deletes=0 2008-02-14 09:58:26 EET DEBUG4 remoteHelperThread_1_1: change helper thread status 2008-02-14 09:58:26 EET DEBUG4 remoteHelperThread_1_1: send DONE/ERROR line to worker 2008-02-14 09:58:26 EET DEBUG3 remoteWorkerThread_1: helper 1 finished 2008-02-14 09:58:26 EET DEBUG4 remoteWorkerThread_1: returning lines to pool 2008-02-14 09:58:26 EET DEBUG3 remoteWorkerThread_1: all helpers done. 2008-02-14 09:58:26 EET DEBUG4 remoteWorkerThread_1: changing helper 1 to IDLE 2008-02-14 09:58:26 EET DEBUG4 remoteWorkerThread_1: cleanup 2008-02-14 09:58:26 EET DEBUG4 remoteHelperThread_1_1: waiting for work 2008-02-14 09:58:26 EET DEBUG2 remoteWorkerThread_1: new sl_rowid_seq value: 1000000000000000 2008-02-14 09:58:26 EET DEBUG2 remoteWorkerThread_1: SYNC 23 done in 0.007 seconds 2008-02-14 09:58:26 EET DEBUG2 remoteWorkerThread_1: Received event 1,24 MOVE_SET 2008-02-14 09:58:28 EET DEBUG2 localListenThread: Received event 3,10 SYNC 2008-02-14 09:58:28 EET DEBUG2 remoteListenThread_2: LISTEN 2008-02-14 09:58:32 EET DEBUG2 remoteListenThread_2: queue event 2,13 SYNC 2008-02-14 09:58:32 EET DEBUG2 remoteListenThread_2: UNLISTEN 2008-02-14 09:58:32 EET DEBUG2 remoteListenThread_1: UNLISTEN 2008-02-14 09:58:34 EET DEBUG2 syncThread: new sl_action_seq 1 - SYNC 11 2008-02-14 09:58:34 EET DEBUG2 ACCEPT_SET - MOVE_SET or FAILOVER_SET not received yet - sleep 2008-02-14 09:58:34 EET DEBUG2 remoteListenThread_1: LISTEN 2008-02-14 09:58:34 EET DEBUG2 remoteListenThread_1: queue event 1,26 SYNC 2008-02-14 09:58:34 EET DEBUG2 remoteListenThread_1: UNLISTEN 2008-02-14 09:58:40 EET DEBUG2 localListenThread: Received event 3,11 SYNC 2008-02-14 09:58:42 EET DEBUG2 remoteListenThread_1: LISTEN
2008-02-14 09:45:18 EET DEBUG2 remoteWorkerThread_2: forward confirm 1,148 received by 2 2008-02-14 09:45:18 EET DEBUG2 syncThread: new sl_action_seq 1 - SYNC 134 2008-02-14 09:45:18 EET DEBUG2 remoteWorkerThread_2: forward confirm 3,134 received by 2 2008-02-14 09:45:21 EET DEBUG2 remoteListenThread_1: queue event 2,134 SYNC 2008-02-14 09:45:21 EET DEBUG2 remoteListenThread_1: queue event 2,135 ACCEPT_SET 2008-02-14 09:45:21 EET DEBUG2 remoteListenThread_1: queue event 2,136 SYNC 2008-02-14 09:45:21 EET DEBUG2 remoteListenThread_1: UNLISTEN 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_2: Received event 2,134 SYNC 2008-02-14 09:45:21 EET DEBUG2 calc sync size - last time: 1 last length: 8006 ideal: 7 proposed size: 3 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_2: SYNC 134 processing 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_1: forward confirm 2,136 received by 1 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_1: forward confirm 3,134 received by 1 2008-02-14 09:45:21 EET DEBUG4 version for "dbname=pgb host=192.168.0.201 port=5432 user=slony password=sln" is 80206 2008-02-14 09:45:21 EET DEBUG1 remoteWorkerThread_2: connected to data provider 1 on 'dbname=pgb host=192.168.0.201 port=5432 user=slony password=sln' 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_2: syncing set 1 with 4 table(s) from provider 1 2008-02-14 09:45:21 EET DEBUG4 ssy_action_list value: 2008-02-14 09:45:21 EET DEBUG2 ssy_action_list length: 0 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_2: current local log_status is 0 2008-02-14 09:45:21 EET DEBUG3 remoteWorkerThread_2: activate helper 1 2008-02-14 09:45:21 EET DEBUG4 remoteWorkerThread_2: waiting for log data 2008-02-14 09:45:21 EET DEBUG4 remoteHelperThread_2_1: got work to do 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_2_1: current remote log_status = 0 2008-02-14 09:45:21 EET DEBUG4 remoteHelperThread_2_1: allocate line buffers 2008-02-14 09:45:21 EET DEBUG4 remoteHelperThread_2_1: fetch from cursor 2008-02-14 09:45:21 EET DEBUG2 remoteHelperThread_2_1: 0.003 seconds delay for first row 2008-02-14 09:45:21 EET DEBUG4 remoteHelperThread_2_1: fetched 0 log rows 2008-02-14 09:45:21 EET DEBUG4 remoteHelperThread_2_1: return 10 unused line buffers 2008-02-14 09:45:21 EET DEBUG2 remoteHelperThread_2_1: 0.004 seconds until close cursor 2008-02-14 09:45:21 EET DEBUG2 remoteHelperThread_2_1: inserts=0 updates=0 deletes=0 2008-02-14 09:45:21 EET DEBUG4 remoteHelperThread_2_1: change helper thread status 2008-02-14 09:45:21 EET DEBUG4 remoteHelperThread_2_1: send DONE/ERROR line to worker 2008-02-14 09:45:21 EET DEBUG3 remoteWorkerThread_2: helper 1 finished 2008-02-14 09:45:21 EET DEBUG4 remoteWorkerThread_2: returning lines to pool 2008-02-14 09:45:21 EET DEBUG3 remoteWorkerThread_2: all helpers done. 2008-02-14 09:45:21 EET DEBUG4 remoteWorkerThread_2: changing helper 1 to IDLE 2008-02-14 09:45:21 EET DEBUG4 remoteWorkerThread_2: cleanup 2008-02-14 09:45:21 EET DEBUG4 remoteHelperThread_2_1: waiting for work 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_2: new sl_rowid_seq value: 2000000000000000 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_2: SYNC 134 done in 0.018 seconds 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_2: Received event 2,135 ACCEPT_SET 2008-02-14 09:45:21 EET DEBUG2 start processing ACCEPT_SET 2008-02-14 09:45:21 EET DEBUG2 ACCEPT: set=1 2008-02-14 09:45:21 EET DEBUG2 ACCEPT: old origin=1 2008-02-14 09:45:21 EET DEBUG2 ACCEPT: new origin=2 2008-02-14 09:45:21 EET DEBUG2 ACCEPT: move set seq=147 2008-02-14 09:45:21 EET DEBUG2 got parms ACCEPT_SET 2008-02-14 09:45:21 EET DEBUG2 ACCEPT_SET - node not origin 2008-02-14 09:45:21 EET DEBUG2 ACCEPT_SET - MOVE_SET or FAILOVER_SET exists - adjusting setsync status 2008-02-14 09:45:21 EET DEBUG2 ACCEPT_SET - done 2008-02-14 09:45:21 EET DEBUG2 slon_retry() from pid=18367 2008-02-14 09:45:21 EET DEBUG1 slon: retry requested 2008-02-14 09:45:21 EET DEBUG2 slon: notify worker process to shutdown 2008-02-14 09:45:21 EET INFO remoteListenThread_2: disconnecting from 'dbname=pgb host=192.168.0.202 port=5432 user=slony password=sln' 2008-02-14 09:45:21 EET DEBUG1 localListenThread: thread done 2008-02-14 09:45:21 EET DEBUG1 remoteListenThread_2: thread done 2008-02-14 09:45:21 EET INFO remoteListenThread_1: disconnecting from 'dbname=pgb host=192.168.0.201 port=5432 user=slony password=sln' 2008-02-14 09:45:21 EET DEBUG1 cleanupThread: thread done 2008-02-14 09:45:21 EET DEBUG1 remoteListenThread_1: thread done 2008-02-14 09:45:21 EET DEBUG1 syncThread: thread done 2008-02-14 09:45:21 EET DEBUG1 main: scheduler mainloop returned 2008-02-14 09:45:21 EET DEBUG2 main: wait for remote threads 2008-02-14 09:45:21 EET DEBUG2 sched_wakeup_node(): no_id=1 (0 threads + worker signaled) 2008-02-14 09:45:21 EET DEBUG4 remoteWorkerThread_1: update provider configuration 2008-02-14 09:45:21 EET DEBUG1 remoteWorkerThread_1: thread done 2008-02-14 09:45:21 EET DEBUG2 sched_wakeup_node(): no_id=2 (0 threads + worker signaled) 2008-02-14 09:45:21 EET DEBUG1 main: done 2008-02-14 09:45:21 EET DEBUG2 slon: child terminated status: 0; pid: 18367, current worker pid: 18367 2008-02-14 09:45:21 EET DEBUG1 slon: restart of worker 2008-02-14 09:45:21 EET CONFIG main: slon version 1.2.13 starting up 2008-02-14 09:45:21 EET DEBUG2 slon: watchdog process started 2008-02-14 09:45:21 EET DEBUG2 slon: watchdog ready - pid = 18366 2008-02-14 09:45:21 EET DEBUG2 slon: worker process created - pid = 18398 2008-02-14 09:45:21 EET CONFIG main: local node id = 3 2008-02-14 09:45:21 EET DEBUG2 main: main process started 2008-02-14 09:45:21 EET CONFIG main: launching sched_start_mainloop 2008-02-14 09:45:21 EET CONFIG main: loading current cluster configuration 2008-02-14 09:45:21 EET CONFIG storeNode: no_id=1 no_comment='Node 1' 2008-02-14 09:45:21 EET DEBUG2 setNodeLastEvent: no_id=1 event_seq=148 2008-02-14 09:45:21 EET CONFIG storeNode: no_id=2 no_comment='Node 2' 2008-02-14 09:45:21 EET DEBUG2 setNodeLastEvent: no_id=2 event_seq=135 2008-02-14 09:45:21 EET CONFIG storePath: pa_server=1 pa_client=3 pa_conninfo="dbname=pgb host=192.168.0.201 port=5432 user=slony password=sln" pa_connretry=10 2008-02-14 09:45:21 EET CONFIG storePath: pa_server=2 pa_client=3 pa_conninfo="dbname=pgb host=192.168.0.202 port=5432 user=slony password=sln" pa_connretry=10 2008-02-14 09:45:21 EET CONFIG storeListen: li_origin=1 li_receiver=3 li_provider=1 2008-02-14 09:45:21 EET CONFIG storeListen: li_origin=1 li_receiver=3 li_provider=2 2008-02-14 09:45:21 EET CONFIG storeListen: li_origin=2 li_receiver=3 li_provider=1 2008-02-14 09:45:21 EET CONFIG storeSet: set_id=1 set_origin=2 set_comment='set 1' 2008-02-14 09:45:21 EET WARN remoteWorker_wakeup: node 2 - no worker thread 2008-02-14 09:45:21 EET DEBUG2 sched_wakeup_node(): no_id=2 (0 threads + worker signaled) 2008-02-14 09:45:21 EET CONFIG storeSubscribe: sub_set=1 sub_provider=1 sub_forward='t' 2008-02-14 09:45:21 EET WARN remoteWorker_wakeup: node 1 - no worker thread 2008-02-14 09:45:21 EET DEBUG2 sched_wakeup_node(): no_id=1 (0 threads + worker signaled) 2008-02-14 09:45:21 EET CONFIG enableSubscription: sub_set=1 2008-02-14 09:45:21 EET WARN remoteWorker_wakeup: node 1 - no worker thread 2008-02-14 09:45:21 EET DEBUG2 sched_wakeup_node(): no_id=1 (0 threads + worker signaled) 2008-02-14 09:45:21 EET DEBUG2 main: last local event sequence = 134 2008-02-14 09:45:21 EET CONFIG main: configuration complete - starting threads 2008-02-14 09:45:21 EET DEBUG1 localListenThread: thread starts 2008-02-14 09:45:21 EET DEBUG4 version for "host=127.0.0.1 port=5432 user=slony dbname=pgb" is 80206 NOTICE: Slony-I: cleanup stale sl_nodelock entry for pid=18371 2008-02-14 09:45:21 EET CONFIG enableNode: no_id=1 2008-02-14 09:45:21 EET DEBUG1 remoteWorkerThread_1: thread starts 2008-02-14 09:45:21 EET DEBUG1 remoteListenThread_1: thread starts 2008-02-14 09:45:21 EET CONFIG enableNode: no_id=2 2008-02-14 09:45:21 EET DEBUG1 remoteWorkerThread_2: thread starts 2008-02-14 09:45:21 EET DEBUG1 remoteListenThread_2: thread starts 2008-02-14 09:45:21 EET DEBUG1 cleanupThread: thread starts 2008-02-14 09:45:21 EET DEBUG4 cleanupThread: bias = 35383 2008-02-14 09:45:21 EET DEBUG1 syncThread: thread starts 2008-02-14 09:45:21 EET DEBUG1 main: running scheduler mainloop 2008-02-14 09:45:21 EET DEBUG2 remoteListenThread_1: start listening for event origin 1 2008-02-14 09:45:21 EET DEBUG2 remoteListenThread_1: start listening for event origin 2 2008-02-14 09:45:21 EET DEBUG2 remoteListenThread_2: start listening for event origin 1 2008-02-14 09:45:21 EET DEBUG4 version for "host=127.0.0.1 port=5432 user=slony dbname=pgb" is 80206 2008-02-14 09:45:21 EET DEBUG4 version for "host=127.0.0.1 port=5432 user=slony dbname=pgb" is 80206 2008-02-14 09:45:21 EET DEBUG4 version for "host=127.0.0.1 port=5432 user=slony dbname=pgb" is 80206 2008-02-14 09:45:21 EET DEBUG4 version for "dbname=pgb host=192.168.0.201 port=5432 user=slony password=sln" is 80206 2008-02-14 09:45:21 EET DEBUG4 version for "dbname=pgb host=192.168.0.202 port=5432 user=slony password=sln" is 80206 2008-02-14 09:45:21 EET DEBUG4 version for "host=127.0.0.1 port=5432 user=slony dbname=pgb" is 80206 2008-02-14 09:45:21 EET DEBUG4 remoteWorkerThread_1: update provider configuration 2008-02-14 09:45:21 EET DEBUG4 remoteWorkerThread_2: update provider configuration 2008-02-14 09:45:21 EET DEBUG1 remoteWorkerThread_2: helper thread for provider 1 created 2008-02-14 09:45:21 EET DEBUG4 remoteWorkerThread_2: added active set 1 to provider 1 2008-02-14 09:45:21 EET DEBUG4 remoteHelperThread_2_1: waiting for work 2008-02-14 09:45:21 EET DEBUG1 remoteListenThread_1: connected to 'dbname=pgb host=192.168.0.201 port=5432 user=slony password=sln' 2008-02-14 09:45:21 EET DEBUG1 remoteListenThread_2: connected to 'dbname=pgb host=192.168.0.202 port=5432 user=slony password=sln' 2008-02-14 09:45:21 EET DEBUG2 remoteListenThread_1: queue event 2,136 SYNC 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_2: Received event 2,136 SYNC 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_2: SYNC 136 processing 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_1: forward confirm 1,148 received by 2 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_1: forward confirm 3,134 received by 2 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_1: forward confirm 2,136 received by 1 2008-02-14 09:45:21 EET DEBUG4 version for "dbname=pgb host=192.168.0.201 port=5432 user=slony password=sln" is 80206 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_1: forward confirm 3,134 received by 1 2008-02-14 09:45:21 EET DEBUG1 remoteWorkerThread_2: connected to data provider 1 on 'dbname=pgb host=192.168.0.201 port=5432 user=slony password=sln' 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_2: syncing set 1 with 4 table(s) from provider 1 2008-02-14 09:45:21 EET DEBUG4 ssy_action_list value: 2008-02-14 09:45:21 EET DEBUG2 ssy_action_list length: 0 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_2: current local log_status is 0 2008-02-14 09:45:21 EET DEBUG3 remoteWorkerThread_2: activate helper 1 2008-02-14 09:45:21 EET DEBUG4 remoteWorkerThread_2: waiting for log data 2008-02-14 09:45:21 EET DEBUG4 remoteHelperThread_2_1: got work to do 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_2_1: current remote log_status = 0 2008-02-14 09:45:21 EET DEBUG4 remoteHelperThread_2_1: allocate line buffers 2008-02-14 09:45:21 EET DEBUG4 remoteHelperThread_2_1: fetch from cursor 2008-02-14 09:45:21 EET DEBUG2 remoteHelperThread_2_1: 0.003 seconds delay for first row 2008-02-14 09:45:21 EET DEBUG4 remoteHelperThread_2_1: fetched 0 log rows 2008-02-14 09:45:21 EET DEBUG4 remoteHelperThread_2_1: return 10 unused line buffers 2008-02-14 09:45:21 EET DEBUG2 remoteHelperThread_2_1: 0.003 seconds until close cursor 2008-02-14 09:45:21 EET DEBUG2 remoteHelperThread_2_1: inserts=0 updates=0 deletes=0 2008-02-14 09:45:21 EET DEBUG4 remoteHelperThread_2_1: change helper thread status 2008-02-14 09:45:21 EET DEBUG4 remoteHelperThread_2_1: send DONE/ERROR line to worker 2008-02-14 09:45:21 EET DEBUG3 remoteHelperThread_2_1: waiting for workgroup to finish 2008-02-14 09:45:21 EET DEBUG3 remoteWorkerThread_2: helper 1 finished 2008-02-14 09:45:21 EET DEBUG4 remoteWorkerThread_2: returning lines to pool 2008-02-14 09:45:21 EET DEBUG3 remoteWorkerThread_2: all helpers done. 2008-02-14 09:45:21 EET DEBUG4 remoteWorkerThread_2: changing helper 1 to IDLE 2008-02-14 09:45:21 EET DEBUG4 remoteWorkerThread_2: cleanup 2008-02-14 09:45:21 EET DEBUG4 remoteHelperThread_2_1: waiting for work 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_2: new sl_rowid_seq value: 2000000000000000 2008-02-14 09:45:21 EET DEBUG2 remoteWorkerThread_2: SYNC 136 done in 0.020 seconds 2008-02-14 09:45:23 EET DEBUG2 remoteListenThread_1: queue event 1,149 SYNC 2008-02-14 09:45:23 EET DEBUG2 remoteWorkerThread_1: Received event 1,149 SYNC 2008-02-14 09:45:23 EET DEBUG2 calc sync size - last time: 1 last length: 1987 ideal: 30 proposed size: 3 2008-02-14 09:45:23 EET DEBUG2 remoteListenThread_2: queue event 1,149 SYNC 2008-02-14 09:45:23 EET DEBUG2 remoteWorker_event: event 1,149 ignored - duplicate 2008-02-14 09:45:23 EET DEBUG2 remoteWorkerThread_1: SYNC 149 processing 2008-02-14 09:45:23 EET DEBUG2 remoteWorkerThread_1: no sets need syncing for this event 2008-02-14 09:45:23 EET DEBUG2 remoteWorkerThread_2: forward confirm 1,149 received by 2 2008-02-14 09:45:23 EET DEBUG2 syncThread: new sl_action_seq 1 - SYNC 135 2008-02-14 09:45:27 EET DEBUG2 localListenThread: Received event 3,135 SYNC 2008-02-14 09:45:29 EET DEBUG2 remoteListenThread_1: LISTEN 2008-02-14 09:45:29 EET DEBUG2 remoteListenThread_2: LISTEN 2008-02-14 09:45:29 EET DEBUG2 remoteWorkerThread_1: forward confirm 3,135 received by 1 2008-02-14 09:45:29 EET DEBUG2 remoteWorkerThread_2: forward confirm 3,135 received by 2 2008-02-14 09:45:30 EET DEBUG2 remoteListenThread_1: queue event 2,137 SYNC 2008-02-14 09:45:30 EET DEBUG2 remoteListenThread_1: UNLISTEN
initial_setup.slonik
Description: Binary data
controlled_failover.slonik
Description: Binary data
_______________________________________________ Slony1-general mailing list [email protected] http://lists.slony.info/mailman/listinfo/slony1-general
