Hi All,

I have Slony 1.1.0 beta 5 working fine in a 3 node configuration with nodes
2 and 3 subscribing directly to node 1.

When I try to enable log shipping on node 3 using the following slon command
the slon process quits unexpectedly.

/usr/local/pgsql/bin/slon -a/usr/local/pgsql/data my_cluster
"dbname=mydbslave user=postgres host=<node 3 ip>"

The slon command is run as the postgres user who has write permission on
/usr/local/pgsql/data.

Some log files are created but they all seem to be empty of any SQL
statements:

-- Slony-I log shipping archive
-- Node 2, Event 416
-- at... Fri Jun  3 11:51:12 2005

start transaction;

------------------------------------------------------------------
-- End Of Archive Log
------------------------------------------------------------------
commit;


I'm using PostgreSQL 8.0.3 built from source.

Here is the output from slon:

2005-06-03 11:51:12 BST CONFIG main: slon version 1.1.0 starting up
2005-06-03 11:51:12 BST CONFIG main: local node id = 3
2005-06-03 11:51:12 BST DEBUG2 slon: watchdog process started
2005-06-03 11:51:12 BST DEBUG2 slon: begin signal handler setup
2005-06-03 11:51:12 BST DEBUG2 slon: end signal handler setup
2005-06-03 11:51:12 BST DEBUG2 slon: wait for main child process
2005-06-03 11:51:12 BST DEBUG2 main: main process started
2005-06-03 11:51:12 BST DEBUG2 main: begin signal handler setup
2005-06-03 11:51:12 BST DEBUG2 main: end signal handler setup
2005-06-03 11:51:12 BST CONFIG main: launching sched_start_mainloop
2005-06-03 11:51:12 BST CONFIG main: loading current cluster configuration
2005-06-03 11:51:12 BST CONFIG storeNode: no_id=1 no_comment='Master Node'
2005-06-03 11:51:12 BST DEBUG2 setNodeLastEvent: no_id=1 event_seq=571
2005-06-03 11:51:12 BST CONFIG storeNode: no_id=2 no_comment='Slave node
db2'
2005-06-03 11:51:12 BST DEBUG2 setNodeLastEvent: no_id=2 event_seq=413
2005-06-03 11:51:12 BST CONFIG storePath: pa_server=1 pa_client=3
pa_conninfo="dbname=mydb host=<node 1 ip> user=postgres" pa_connretry=10
2005-06-03 11:51:12 BST CONFIG storePath: pa_server=2 pa_client=3
pa_conninfo="dbname=mydbslave ho
st=<node 2 ip> user=postgres" pa_connretry=10
2005-06-03 11:51:12 BST CONFIG storeListen: li_origin=1 li_receiver=3
li_provider=1
2005-06-03 11:51:12 BST CONFIG storeListen: li_origin=2 li_receiver=3
li_provider=2
2005-06-03 11:51:12 BST CONFIG storeSet: set_id=1 set_origin=1
set_comment='MYDB Replication Set'
2005-06-03 11:51:12 BST WARN   remoteWorker_wakeup: node 1 - no worker
thread
2005-06-03 11:51:12 BST DEBUG2 sched_wakeup_node(): no_id=1 (0 threads +
worker signaled)
2005-06-03 11:51:12 BST CONFIG storeSubscribe: sub_set=1 sub_provider=1
sub_forward='f'
2005-06-03 11:51:12 BST WARN   remoteWorker_wakeup: node 1 - no worker
thread
2005-06-03 11:51:12 BST DEBUG2 sched_wakeup_node(): no_id=1 (0 threads +
worker signaled)
2005-06-03 11:51:12 BST CONFIG enableSubscription: sub_set=1
2005-06-03 11:51:12 BST WARN   remoteWorker_wakeup: node 1 - no worker
thread
2005-06-03 11:51:12 BST DEBUG2 sched_wakeup_node(): no_id=1 (0 threads +
worker signaled)
2005-06-03 11:51:12 BST DEBUG2 main: last local event sequence = 395
2005-06-03 11:51:12 BST CONFIG main: configuration complete - starting
threads
2005-06-03 11:51:12 BST DEBUG1 localListenThread: thread starts
2005-06-03 11:51:12 BST CONFIG enableNode: no_id=1
2005-06-03 11:51:12 BST DEBUG1 remoteWorkerThread_1: thread starts
2005-06-03 11:51:12 BST DEBUG1 remoteListenThread_1: thread starts
2005-06-03 11:51:12 BST CONFIG enableNode: no_id=2
2005-06-03 11:51:12 BST DEBUG1 remoteWorkerThread_2: thread starts
2005-06-03 11:51:12 BST DEBUG1 remoteListenThread_2: thread starts
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: start listening for
event origin 1
2005-06-03 11:51:12 BST DEBUG1 cleanupThread: thread starts
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_2: start listening for
event origin 2
2005-06-03 11:51:12 BST DEBUG1 syncThread: thread starts
2005-06-03 11:51:12 BST DEBUG4 cleanupThread: bias = 35383
2005-06-03 11:51:12 BST DEBUG1 main: running scheduler mainloop
2005-06-03 11:51:12 BST DEBUG4 remoteWorkerThread_1: update provider
configuration
2005-06-03 11:51:12 BST DEBUG1 remoteWorkerThread_1: helper thread for
provider 1 created
2005-06-03 11:51:12 BST DEBUG4 remoteWorkerThread_1: added active set 1 to
provider 1
2005-06-03 11:51:12 BST DEBUG4 remoteHelperThread_1_1: waiting for work
2005-06-03 11:51:12 BST DEBUG4 remoteWorkerThread_2: update provider
configuration
2005-06-03 11:51:12 BST DEBUG1 remoteListenThread_1: connected to
'dbname=mydb host=<node 1 ip> user
=postgres'
2005-06-03 11:51:12 BST DEBUG1 remoteListenThread_2: connected to
'dbname=mydbslave host=<node 2 ip>
 user=postgres'
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,572 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,573 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,574 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteWorkerThread_1: Received event 1,572
SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,575 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,576 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,577 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,578 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,579 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,580 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,581 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,582 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,583 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,584 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteWorkerThread_1: SYNC 572 processing
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,585 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,586 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,587 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,588 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,589 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,590 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,591 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,592 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,593 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,594 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_1: queue event 1,595 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_2: queue event 2,414 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_2: queue event 2,415 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteWorkerThread_2: Received event 2,414
SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_2: queue event 2,416 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_2: queue event 2,417 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_2: queue event 2,418 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_2: queue event 2,419 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_2: queue event 2,420 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_2: queue event 2,421 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_2: queue event 2,422 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_2: queue event 2,423 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_2: queue event 2,424 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_2: queue event 2,425 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_2: queue event 2,426 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteWorkerThread_2: SYNC 414 processing
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_2: queue event 2,427 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_2: queue event 2,428 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteListenThread_2: queue event 2,429 SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteWorkerThread_2: no sets need syncing
for this event
2005-06-03 11:51:12 BST DEBUG1 remoteWorkerThread_1: connected to data
provider 1 on 'dbname=mydb
host=<node 1 ip> user=postgres'
2005-06-03 11:51:12 BST DEBUG2 remoteWorkerThread_2: Received event 2,415
SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteWorkerThread_2: SYNC 415 processing
2005-06-03 11:51:12 BST DEBUG2 remoteWorkerThread_2: no sets need syncing
for this event
2005-06-03 11:51:12 BST DEBUG2 remoteWorkerThread_1: syncing set 1 with 15
table(s) from provider 1
2005-06-03 11:51:12 BST DEBUG3 remoteWorkerThread_1: activate helper 1
2005-06-03 11:51:12 BST DEBUG4 remoteWorkerThread_1: waiting for log data
2005-06-03 11:51:12 BST DEBUG2 remoteWorkerThread_2: Received event 2,416
SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteWorkerThread_2: SYNC 416 processing
2005-06-03 11:51:12 BST DEBUG2 remoteWorkerThread_2: no sets need syncing
for this event
2005-06-03 11:51:12 BST DEBUG4 remoteHelperThread_1_1: allocate lines
2005-06-03 11:51:12 BST DEBUG4 remoteHelperThread_1_1: have 10 line buffers
2005-06-03 11:51:12 BST DEBUG2 remoteHelperThread_1_1: 0.005 seconds delay
for first row
2005-06-03 11:51:12 BST DEBUG3 remoteHelperThread_1_1: got 1 log rows
2005-06-03 11:51:12 BST DEBUG3 remoteHelperThread_1_1: 1 log buffers
delivered
2005-06-03 11:51:12 BST DEBUG4 remoteHelperThread_1_1: no more log rows
2005-06-03 11:51:12 BST DEBUG2 remoteHelperThread_1_1: 0.005 seconds until
close cursor
2005-06-03 11:51:12 BST DEBUG4 remoteHelperThread_1_1: change helper thread
status
2005-06-03 11:51:12 BST DEBUG4 remoteHelperThread_1_1: send DONE/ERROR line
to worker
2005-06-03 11:51:12 BST DEBUG3 remoteHelperThread_1_1: waiting for workgroup
to finish
2005-06-03 11:51:12 BST DEBUG2 remoteWorkerThread_2: Received event 2,417
SYNC
2005-06-03 11:51:12 BST DEBUG2 remoteWorkerThread_2: SYNC 417 processing
2005-06-03 11:51:12 BST DEBUG1 slon: done
2005-06-03 11:51:12 BST DEBUG2 slon: exit(0)

Nothing unusual in data/serverlog

Does anybody have any ideas?

Thanks,

Chris