CS DBA cs_dba at consistentstate.com
Tue Aug 21 14:41:26 PDT 2012
Hi all;

I have an initial sync in progress.  It's currently syncing a 32GB table. I'd like to be able to verify that some progress is happening.  Here's what I see:



log1 (the master slon log) has this:

NOTICE:  Slony-I: log switch to sl_log_2 still in progress - sl_log_1 not truncated
CONTEXT:  PL/pgSQL function "cleanupevent" line 99 at assignment
2012-08-21 14:40:26 MDTINFO   cleanupThread:    0.043 seconds for cleanupEvent()
NOTICE:  Slony-I: log switch to sl_log_2 still in progress - sl_log_1 not truncated
CONTEXT:  PL/pgSQL function "cleanupevent" line 99 at assignment
2012-08-21 14:51:48 MDTINFO   cleanupThread:    0.054 seconds for cleanupEvent()
2012-08-21 14:51:48 MDTINFO   cleanupThread:    0.006 seconds for vacuuming
NOTICE:  Slony-I: log switch to sl_log_2 still in progress - sl_log_1 not truncated
CONTEXT:  PL/pgSQL function "cleanupevent" line 99 at assignment
2012-08-21 15:03:06 MDTINFO   cleanupThread:    0.043 seconds for cleanupEvent()


log2 (the slave slon log) has this:
$ tail slon.node2.log
2012-08-21 04:24:05 MDTCONFIG remoteWorkerThread_1: 92441 bytes copied for table "sch1"."rtl_pkg"
2012-08-21 04:24:05 MDTCONFIG remoteWorkerThread_1: 0.377 seconds to copy table "sch1"."rtl_pkg"
2012-08-21 04:24:05 MDTCONFIG remoteWorkerThread_1: copy table "sch1"."std_contr"
2012-08-21 04:24:05 MDTCONFIG remoteWorkerThread_1: Begin COPY of table "sch1"."std_contr"
NOTICE:  truncate of "sch1"."std_contr" succeeded
2012-08-21 04:24:05 MDTCONFIG remoteWorkerThread_1: 35072 bytes copied for table "sch1"."std_contr"
2012-08-21 04:24:05 MDTCONFIG remoteWorkerThread_1: 0.345 seconds to copy table "sch1"."std_contr"
2012-08-21 04:24:05 MDTCONFIG remoteWorkerThread_1: copy table "sch2"."order_hist"
2012-08-21 04:24:05 MDTCONFIG remoteWorkerThread_1: Begin COPY of table "sch2"."order_hist"
NOTICE:  truncate of "sch2"."order_hist" succeeded


The system time is now :
Tue Aug 21 15:24:30 MDT 2012

So the sch2.order_hist table has been syncing for 11 hours.  The sch2.order_hist table is 32GB and I did expect it to take some time but I would like to be able to validate some progress.  A select * from pg_stat_activity on the master shows me that this sql is running:

 copy "sch1"."order_hist" ("id","order_hist_id","cust_name","order_id","order_type", "order_dt","sw_version") to stdout;


And, a select * from pg_stat_activity on the slave shows me that this sql is running:
select "_custdb_slony".prepareTableForCopy(186); copy "sch2"."order_hist" ("id","order_hist_id","cust_name","order_id","order_type", "order_dt","sw_version")  from stdin; 


Other than this I see no "verifiable" activity, plus the lag_events in sl_status on the master continue to grow, currently we're at 57,355 lag events.


Thoughts?


Thanks in advance




More information about the Slony1-general mailing list