> OK, there's still some slowness to getting all the ACCEPT_SET events to > flow through, but it's flowing...
Unfortunately I'm seeing some problems downstream. We have Node 1 -> Node 2 -> Node 3. subscribe set (id = 1001, provider = 2, receiver = 3, forward = yes); subscribe set (id = 2001, provider = 2, receiver = 3, forward = yes); etc. ... 10 sets total With the patches for remote_worker.c and local_listener.c, on switchover of the 10 sets from lock set (id = 1001, origin = 1); move set (id = 1001, old origin = 1, new origin = 2); lock set (id = 2001, origin = 1); move set (id = 2001, old origin = 1, new origin = 2); etc. ... I'm getting problems with slon on Node 3 now. Logs below. Why does slon on Node 3 shutdown? Thanks Julian -------------------------------------- 2006-08-25 07:19:46 UTC DEBUG2 localListenThread: Received event 3,6043 SYNC 2006-08-25 07:19:46 UTC DEBUG2 remoteListenThread_2: queue event 1,16260 SYNC 2006-08-25 07:19:46 UTC DEBUG2 remoteListenThread_2: queue event 1,16261 MOVE_SET 2006-08-25 07:19:46 UTC DEBUG2 remoteListenThread_2: queue event 1,16262 SYNC 2006-08-25 07:19:46 UTC DEBUG2 remoteListenThread_2: queue event 1,16263 MOVE_SET ... 8 more SYNC, MOVE_SET entries... 2006-08-25 07:19:46 UTC DEBUG2 remoteListenThread_2: queue event 2,9129 SYNC 2006-08-25 07:19:46 UTC DEBUG2 remoteListenThread_2: queue event 2,9130 ACCEPT_SET 2006-08-25 07:19:46 UTC DEBUG2 remoteListenThread_2: queue event 2,9131 ACCEPT_SET ... 8 more ACCEPT SET entries... 2006-08-25 07:19:46 UTC DEBUG2 remoteListenThread_2: queue event 2,9140 SYNC 2006-08-25 07:19:46 UTC DEBUG2 remoteListenThread_2: queue event 2,9141 MOVE_SET 2006-08-25 07:19:46 UTC DEBUG2 remoteListenThread_2: queue event 2,9142 SYNC 2006-08-25 07:19:46 UTC DEBUG2 remoteListenThread_2: queue event 2,9143 MOVE_SET 2006-08-25 07:19:46 UTC DEBUG2 remoteListenThread_2: queue event 2,9144 SYNC 2006-08-25 07:19:46 UTC DEBUG2 remoteListenThread_2: queue event 2,9145 MOVE_SET 2006-08-25 07:19:46 UTC DEBUG2 remoteListenThread_2: UNLISTEN 2006-08-25 07:19:46 UTC DEBUG2 remoteWorkerThread_1: Received event 1,16260 SYNC 2006-08-25 07:19:46 UTC DEBUG3 calc sync size - last time: 1 last length: 16873 ideal: 3 proposed size: 3 2006-08-25 07:19:46 UTC DEBUG2 remoteWorkerThread_2: Received event 2,9129 SYNC 2006-08-25 07:19:46 UTC DEBUG2 remoteWorkerThread_1: SYNC 16260 processing 2006-08-25 07:19:46 UTC DEBUG2 remoteWorkerThread_2: SYNC 9129 processing 2006-08-25 07:19:46 UTC DEBUG2 remoteWorkerThread_2: no sets need syncing for this event 2006-08-25 07:19:46 UTC DEBUG2 remoteWorkerThread_1: syncing set 4001 with 4 table(s) from provider 2 2006-08-25 07:19:46 UTC DEBUG2 ssy_action_list value: length: 0 2006-08-25 07:19:46 UTC DEBUG2 remoteWorkerThread_2: Received event 2,9130 ACCEPT_SET 2006-08-25 07:19:46 UTC DEBUG2 start processing ACCEPT_SET 2006-08-25 07:19:46 UTC DEBUG2 ACCEPT: set=1001 2006-08-25 07:19:46 UTC DEBUG2 ACCEPT: old origin=1 2006-08-25 07:19:46 UTC DEBUG2 ACCEPT: new origin=2 2006-08-25 07:19:46 UTC DEBUG2 ACCEPT: move set seq=9130 2006-08-25 07:19:46 UTC DEBUG2 got parms ACCEPT_SET 2006-08-25 07:19:46 UTC DEBUG2 ACCEPT_SET - node not origin 2006-08-25 07:19:46 UTC DEBUG2 remoteWorkerThread_1: syncing set 5001 with 11 table(s) from provider 2 2006-08-25 07:19:46 UTC DEBUG2 ssy_action_list value: length: 0 2006-08-25 07:19:46 UTC DEBUG2 remoteWorkerThread_1: syncing set 6001 with 3 table(s) from provider 2 2006-08-25 07:19:46 UTC DEBUG2 ssy_action_list value: length: 0 2006-08-25 07:19:46 UTC DEBUG2 ACCEPT_SET - MOVE_SET or FAILOVER_SET exists - done 2006-08-25 07:19:46 UTC DEBUG2 remoteWorkerThread_1: syncing set 7001 with 7 table(s) from provider 2 2006-08-25 07:19:46 UTC DEBUG2 ssy_action_list value: length: 0 2006-08-25 07:19:46 UTC DEBUG2 remoteWorkerThread_1: syncing set 8001 with 2 table(s) from provider 2 2006-08-25 07:19:46 UTC DEBUG2 ssy_action_list value: length: 0 2006-08-25 07:19:46 UTC DEBUG2 slon_retry() from pid=21034 2006-08-25 07:19:46 UTC DEBUG2 remoteWorkerThread_1: syncing set 9001 with 4 table(s) from provider 2 2006-08-25 07:19:46 UTC DEBUG2 ssy_action_list value: length: 0 2006-08-25 07:19:46 UTC INFO localListenThread: got restart notification 2006-08-25 07:19:46 UTC DEBUG1 slon: retry requested 2006-08-25 07:19:46 UTC DEBUG2 slon: notify worker process to shutdown 2006-08-25 07:19:46 UTC INFO remoteListenThread_2: disconnecting from ... 2006-08-25 07:19:46 UTC DEBUG1 remoteListenThread_2: thread done 2006-08-25 07:19:46 UTC DEBUG1 syncThread: thread done 2006-08-25 07:19:46 UTC INFO remoteListenThread_1: disconnecting from ... 2006-08-25 07:19:46 UTC DEBUG1 remoteListenThread_1: thread done 2006-08-25 07:19:46 UTC DEBUG1 cleanupThread: thread done 2006-08-25 07:19:46 UTC DEBUG1 main: scheduler mainloop returned 2006-08-25 07:19:46 UTC DEBUG2 main: wait for remote threads 2006-08-25 07:19:46 UTC DEBUG2 sched_wakeup_node(): no_id=1 (0 threads + worker signaled) 2006-08-25 07:19:46 UTC DEBUG2 remoteWorkerThread_1: syncing set 10001 with 4 table(s) from provider 2 2006-08-25 07:19:46 UTC DEBUG2 ssy_action_list value: length: 0 2006-08-25 07:19:46 UTC DEBUG2 remoteWorkerThread_1: syncing set 1001 with 10 table(s) from provider 2 2006-08-25 07:19:46 UTC DEBUG2 ssy_action_list value: length: 0 2006-08-25 07:19:46 UTC DEBUG2 remoteWorkerThread_1: syncing set 2001 with 6 table(s) from provider 2 2006-08-25 07:19:46 UTC DEBUG2 ssy_action_list value: length: 0 2006-08-25 07:19:46 UTC DEBUG2 remoteWorkerThread_1: syncing set 3001 with 3 table(s) from provider 2 2006-08-25 07:19:46 UTC DEBUG2 ssy_action_list value: length: 0 2006-08-25 07:19:46 UTC DEBUG2 remoteWorkerThread_1: current local log_status is 0 2006-08-25 07:19:46 UTC DEBUG3 remoteWorkerThread_1: activate helper 2 2006-08-25 07:19:46 UTC DEBUG4 remoteWorkerThread_1: waiting for log data 2006-08-25 07:19:46 UTC DEBUG4 remoteHelperThread_1_2: got work to do 2006-08-25 07:19:46 UTC DEBUG2 remoteWorkerThread_1_2: current remote log_status = 0 2006-08-25 07:19:46 UTC DEBUG4 remoteHelperThread_1_2: allocate line buffers 2006-08-25 07:19:46 UTC DEBUG4 remoteHelperThread_1_2: fetch from cursor 2006-08-25 07:19:46 UTC DEBUG2 remoteHelperThread_1_2: 0.002 seconds delay for first row 2006-08-25 07:19:46 UTC DEBUG4 remoteHelperThread_1_2: fetched 0 log rows 2006-08-25 07:19:46 UTC DEBUG4 remoteHelperThread_1_2: return 10 unused line buffers 2006-08-25 07:19:46 UTC DEBUG2 remoteHelperThread_1_2: 0.002 seconds until close cursor 2006-08-25 07:19:46 UTC DEBUG2 remoteHelperThread_1_2: inserts=0 updates=0 deletes=0 2006-08-25 07:19:46 UTC DEBUG4 remoteHelperThread_1_2: change helper thread status 2006-08-25 07:19:46 UTC DEBUG4 remoteHelperThread_1_2: send DONE/ERROR line to worker 2006-08-25 07:19:46 UTC DEBUG3 remoteHelperThread_1_2: waiting for workgroup to finish 2006-08-25 07:19:46 UTC DEBUG3 remoteWorkerThread_1: helper 2 finished 2006-08-25 07:19:46 UTC DEBUG4 remoteWorkerThread_1: returning lines to pool 2006-08-25 07:19:46 UTC DEBUG3 remoteWorkerThread_1: all helpers done. 2006-08-25 07:19:46 UTC DEBUG4 remoteWorkerThread_1: changing helper 2 to IDLE 2006-08-25 07:19:46 UTC DEBUG4 remoteWorkerThread_1: cleanup 2006-08-25 07:19:46 UTC DEBUG4 remoteHelperThread_1_2: waiting for work 2006-08-25 07:19:46 UTC DEBUG2 remoteWorkerThread_1: new sl_rowid_seq value: 1000000000000000 2006-08-25 07:19:46 UTC DEBUG2 remoteWorkerThread_1: SYNC 16260 done in 0.043 seconds 2006-08-25 07:19:46 UTC DEBUG4 remoteWorkerThread_1: update provider configuration 2006-08-25 07:19:46 UTC DEBUG1 remoteWorkerThread_1: helper thread for provider 2 terminated 2006-08-25 07:19:46 UTC DEBUG1 remoteWorkerThread_1: disconnecting from data provider 2 2006-08-25 07:19:46 UTC DEBUG1 remoteWorkerThread_1: thread done 2006-08-25 07:19:46 UTC DEBUG2 sched_wakeup_node(): no_id=2 (0 threads + worker signaled) 2006-08-25 07:19:46 UTC DEBUG1 main: done 2006-08-25 07:19:46 UTC DEBUG2 slon: child terminated status: 0; pid: 21034, current worker pid: 21034 2006-08-25 07:19:46 UTC DEBUG1 slon: restart of worker in 10 seconds 2006-08-25 07:19:56 UTC CONFIG main: slon version 1.2.0 starting up 2006-08-25 07:19:56 UTC DEBUG2 slon: watchdog process started 2006-08-25 07:19:56 UTC DEBUG2 slon: watchdog ready - pid = 15701 2006-08-25 07:19:56 UTC DEBUG2 slon: worker process created - pid = 22351 ... _______________________________________________ Slony1-general mailing list [email protected] http://gborg.postgresql.org/mailman/listinfo/slony1-general
