Very slow connection times with DB2

I’m having a big performance problem with Clover and DB2.

DB2 is pretty slow at creating its first connection (~5 sec) for a new client, but subsequent connections from the same client (ie same JVM) should be fast (<30 ms).

I know that Clover doesn’t pool or reuse database connections, but for some reason every connection to DB2 appears to be a “first connection”, which takes a relatively long time. This is crippling my graph performance and just seems wrong.

I’ve written Groovy and Java tests client that shows how the driver generally performs. Here’s the Groovy version:

DriverManager.registerDriver(new com.ibm.db2.jcc.DB2Driver())
for (i in 1…20) {
start = System.currentTimeMillis()
url = “jdbc:db2://192.168.120.134:50001/airs”
c = DriverManager.getConnection(url, “db2user”, “secret”)
c.close()
println(i + ": " + (System.currentTimeMillis() - start))
}

When run I this test script, I get output like this:

1: 4875
2: 16
3: 0
4: 16
5: 0
6: 15
7: 0
8: 0

But as you can see from the Clover trace below, every connection attempt from within Clover takes 5+ seconds each time! When the graph finally executes it takes only six seconds to complete. (In this case I ran with threadSafeConnections=false, if I set this to true then another 3+ minutes of time is spent connecting in the execute phase as well.)

Any thoughts on this issue would be greatly appreciated! I’m hoping there’s something simple I’m missing that can make this performant with DB2.

[java] Graph definition file: db-util/clover/graph/core_enum.grf
[java] Fri 16:15:41 INFO [main] GraphExecutor | Checking graph configuration…
[java] Fri 16:15:41 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_sc_asgmt.csv
[java] Fri 16:15:41 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_sc_asgmt.csv
[java] Fri 16:15:41 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_typ_ctlg_mth_prc.csv
[java] Fri 16:15:41 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_typ_ctlg_mth_prc.csv
[java] Fri 16:15:41 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_typ_ctlg_sn.csv
[java] Fri 16:15:41 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_typ_ctlg_sn.csv
[java] Fri 16:15:41 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_typ_ctlg_sts.csv
[java] Fri 16:15:41 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_typ_ctlg_sts.csv
[java] Fri 16:15:41 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_typ_ctlg.csv
[java] Fri 16:15:41 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_typ_ctlg.csv
[java] Fri 16:15:41 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_ty_inf_ct.csv
[java] Fri 16:15:41 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_ty_inf_ct.csv
[java] Fri 16:15:41 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_ty_ct_lfc.csv
[java] Fri 16:15:41 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_ty_ct_lfc.csv
[java] Fri 16:15:41 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_sc_ct.csv
[java] Fri 16:15:41 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_sc_ct.csv

Now spend ~3 minutes making connections (each of these is a DBOutputTable node)…
During this time the CPU is idle, not doing any work on the DB2 side!

[java] Fri 16:15:41 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:15:51 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:15:55 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:16:00 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:16:04 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:16:09 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:16:13 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:16:18 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:16:23 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:16:27 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:16:32 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:16:36 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:16:41 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:16:45 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:16:50 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:16:54 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:16:59 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:17:04 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:17:08 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:17:13 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:17:17 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:17:22 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:17:26 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:17:31 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:17:35 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:17:40 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:17:44 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:17:49 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:17:54 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:17:58 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:18:03 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:18:07 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:18:12 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:18:16 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:18:21 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:18:25 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database
[java] Fri 16:18:30 DEBUG [main] DBConnection | DBConnection (Connection0), component [main] attempts to connect to the database

[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//taxauthority_type_enum.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//taxauthority_type_enum.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common/co_uom.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common/co_uom.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common/lu_lb_itm_styl.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common/lu_lb_itm_styl.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common/lu_edc_lv.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common/lu_edc_lv.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//lu_mth_enr.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//lu_mth_enr.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_cc_ev.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_cc_ev.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_sts_ev.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_sts_ev.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_typ_ev.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_typ_ev.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_itm_nm_val.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_itm_nm_val.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_sc_orgn_lg.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_sc_orgn_lg.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_sc_mrtl.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_sc_mrtl.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_dsc_mxm_mth.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_dsc_mxm_mth.csv
[java] Fri 16:18:34 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_ty_prty_opr.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_ty_prty_opr.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//lu_org_lg.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//lu_org_lg.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_subty_prty.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_subty_prty.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_ty_prty.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_ty_prty.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_typ_prm.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_typ_prm.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_typ_ltm_trn_rtl.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_typ_ltm_trn_rtl.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_sls_ascte_actn.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_sls_ascte_actn.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_ty_wrkr.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_ty_wrkr.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common/lu_dl_mth_acrl.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common/lu_dl_mth_acrl.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common/lu_dl_mth_cln.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common/lu_dl_mth_cln.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//customer_order_state_enum.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//customer_order_state_enum.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//customer_order_type_enum.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//customer_order_type_enum.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//fin_ledger_acct_type_enum.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//fin_ledger_acct_type_enum.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//lineitemassociation_type_enum.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//lineitemassociation_type_enum.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//tender_class_enum.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//tender_class_enum.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//total_type_enum.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//total_type_enum.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//transaction_type_enum.csv
[java] Fri 16:18:35 DEBUG [main] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//transaction_type_enum.csv
[java] Fri 16:18:35 INFO [WatchDog] WatchDog | Thread started.
[java] Fri 16:18:35 INFO [WatchDog] WatchDog | Running on 2 CPU(s) max available memory for JVM 914560 KB
[java] Fri 16:18:35 INFO [WatchDog] Phase | [Clover] Initializing phase: 0
[java] Fri 16:18:35 DEBUG [WatchDog] Phase | initializing edges:
[java] Fri 16:18:35 DEBUG [WatchDog] Phase | all edges initialized successfully…
[java] Fri 16:18:35 DEBUG [WatchDog] Phase | initializing nodes:
[java] Fri 16:18:35 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_sc_asgmt.csv
[java] Fri 16:18:35 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_sc_asgmt.csv
[java] Fri 16:18:35 DEBUG [WatchDog] Phase | AssignmentStatus …OK
[java] Fri 16:18:35 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_typ_ctlg_mth_prc.csv
[java] Fri 16:18:35 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_typ_ctlg_mth_prc.csv
[java] Fri 16:18:35 DEBUG [WatchDog] Phase | CatalogMethodType …OK
[java] Fri 16:18:35 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_typ_ctlg_sn.csv
[java] Fri 16:18:35 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_typ_ctlg_sn.csv
[java] Fri 16:18:35 DEBUG [WatchDog] Phase | CatalogSeasonType …OK
[java] Fri 16:18:35 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_typ_ctlg_sts.csv
[java] Fri 16:18:35 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_typ_ctlg_sts.csv
[java] Fri 16:18:35 DEBUG [WatchDog] Phase | CatalogStatusType …OK
[java] Fri 16:18:35 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_typ_ctlg.csv
[java] Fri 16:18:35 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_typ_ctlg.csv
[java] Fri 16:18:35 DEBUG [WatchDog] Phase | CatalogType …OK
[java] Fri 16:18:35 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_ty_inf_ct.csv
[java] Fri 16:18:35 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_ty_inf_ct.csv
[java] Fri 16:18:35 DEBUG [WatchDog] Phase | CustomerInformationType …OK
[java] Fri 16:18:35 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_ty_ct_lfc.csv
[java] Fri 16:18:35 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_ty_ct_lfc.csv
[java] Fri 16:18:35 DEBUG [WatchDog] Phase | CustomerLifecycleType …OK
[java] Fri 16:18:35 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_sc_ct.csv
[java] Fri 16:18:35 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_sc_ct.csv
[java] Fri 16:18:35 DEBUG [WatchDog] Phase | CustomerStatus …OK
[java] Fri 16:18:35 DEBUG [WatchDog] DBConnection | DBConnection (Connection0), component [WatchDog] attempts to connect to the database
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_SC_ASGMT …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE1 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE10 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE11 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE12 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE13 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE14 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE15 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE16 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE17 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE18 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE19 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE2 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE20 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE21 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE22 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE23 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE24 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE25 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE26 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE27 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE28 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE29 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE3 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE30 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE4 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE5 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE6 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE7 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE8 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE9 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE_EntryMethod …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE_ItemNameValue …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE_ItemNameValue3 …OK
[java] Fri 16:18:39 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE_MaxDiscount …OK
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE_RetailTransLineItemType …OK
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | DB_OUTPUT_TABLE_SalesAssociationAction …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//taxauthority_type_enum.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//taxauthority_type_enum.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | DELIMITED_DATA_READER0 …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common/co_uom.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common/co_uom.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | DELIMITED_DATA_READER1 …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common/lu_lb_itm_styl.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common/lu_lb_itm_styl.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | DELIMITED_DATA_READER2 …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common/lu_edc_lv.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common/lu_edc_lv.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | EducationLevel …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//lu_mth_enr.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//lu_mth_enr.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | EntryMethodLookup …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_cc_ev.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_cc_ev.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | EventControl …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_sts_ev.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_sts_ev.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | EventStatus …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_typ_ev.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_typ_ev.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | EventType …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_itm_nm_val.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_itm_nm_val.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | ItemNameValueType …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_sc_orgn_lg.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_sc_orgn_lg.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | LegalStatus …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_sc_mrtl.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_sc_mrtl.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | MaritalStatus …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_dsc_mxm_mth.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_dsc_mxm_mth.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | MaxDiscountMethod …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_ty_prty_opr.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_ty_prty_opr.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | OperatingPartyType …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//lu_org_lg.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//lu_org_lg.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | OrganizationType …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_subty_prty.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_subty_prty.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | PartySubType …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_ty_prty.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_ty_prty.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | PartyType …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_typ_prm.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_typ_prm.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | PromotionType …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_typ_ltm_trn_rtl.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_typ_ltm_trn_rtl.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | RetailTransactionLineItemType …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_sls_ascte_actn.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_sls_ascte_actn.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | SalesAssociateAction …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//co_ty_wrkr.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//co_ty_wrkr.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | WorkerType …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common/lu_dl_mth_acrl.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common/lu_dl_mth_acrl.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | accrual_method_enum …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common/lu_dl_mth_cln.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common/lu_dl_mth_cln.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | collection_method_enum …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//customer_order_state_enum.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//customer_order_state_enum.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | customer_order_state_enum …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//customer_order_type_enum.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//customer_order_type_enum.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | customer_order_type_enum …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//fin_ledger_acct_type_enum.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//fin_ledger_acct_type_enum.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | fin_ledger_acct_type_enum …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//lineitemassociation_type_enum.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//lineitemassociation_type_enum.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | lineitemassociation …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//tender_class_enum.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//tender_class_enum.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | tender_class_enum …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//total_type_enum.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//total_type_enum.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | total_type_enum …OK
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Opening input file C:/alphabay/service/db-util/data/csv/common//transaction_type_enum.csv
[java] Fri 16:18:40 DEBUG [WatchDog] DelimitedDataReader | Reading input file C:/alphabay/service/db-util/data/csv/common//transaction_type_enum.csv
[java] Fri 16:18:40 DEBUG [WatchDog] Phase | transaction_type_enum …OK
[java] Fri 16:18:40 INFO [WatchDog] Phase | [Clover] phase: 0 initialized successfully.
[java] Fri 16:18:40 INFO [WatchDog] WatchDog | Starting up all nodes in phase [0]
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | AssignmentStatus … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | CatalogMethodType … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | CatalogSeasonType … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | CatalogStatusType … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | CatalogType … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | CustomerInformationType … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | CustomerLifecycleType … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | CustomerStatus … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_SC_ASGMT … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE1 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE10 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE11 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE12 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE13 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE14 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE15 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE16 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE17 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE18 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE19 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE2 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE20 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE21 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE22 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE23 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE24 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE25 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE26 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE27 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE28 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE29 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE3 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE30 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE4 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE5 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE6 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE7 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE8 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE9 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE_EntryMethod … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE_ItemNameValue … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE_ItemNameValue3 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE_MaxDiscount … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE_RetailTransLineItemType … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DB_OUTPUT_TABLE_SalesAssociationAction … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DELIMITED_DATA_READER0 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DELIMITED_DATA_READER1 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | DELIMITED_DATA_READER2 … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | EducationLevel … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | EntryMethodLookup … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | EventControl … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | EventStatus … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | EventType … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | ItemNameValueType … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | LegalStatus … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | MaritalStatus … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | MaxDiscountMethod … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | OperatingPartyType … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | OrganizationType … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | PartySubType … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | PartyType … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | PromotionType … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | RetailTransactionLineItemType … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | SalesAssociateAction … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | WorkerType … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | accrual_method_enum … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | collection_method_enum … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | customer_order_state_enum … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | customer_order_type_enum … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | fin_ledger_acct_type_enum … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | lineitemassociation … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | tender_class_enum … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | total_type_enum … started
[java] Fri 16:18:40 DEBUG [WatchDog] WatchDog | transaction_type_enum … started
[java] Fri 16:18:40 INFO [WatchDog] WatchDog | Sucessfully started all nodes in phase!
[java] Fri 16:18:40 INFO [WatchDog] WatchDog | Execution of phase [0] successfully finished - elapsed time(sec): 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | ----------------------** Final tracking Log for phase [0] **---------------------
[java] Fri 16:18:40 INFO [WatchDog] Tracking | Time: 25/04/08 16:18:40
[java] Fri 16:18:40 INFO [WatchDog] Tracking | Node Status Port #Records #KB Rec/s KB/s
[java] Fri 16:18:40 INFO [WatchDog] Tracking | ----------------------------------------------------------------------------------
[java] Fri 16:18:40 INFO [WatchDog] Tracking | AssignmentStatus FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… Out:0 2 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | CatalogMethodType FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… Out:0 4 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | CatalogSeasonType FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:0.02 Out:0 6 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | CatalogStatusType FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:0.05 Out:0 4 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | CatalogType FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:0.02 Out:0 3 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | CustomerInformationTypeFINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… Out:0 7 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | CustomerLifecycleType FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… Out:0 4 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | CustomerStatus FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… Out:0 4 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_SC_ASGMT FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… In:0 2 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE1 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… In:0 5 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE10 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… In:0 3 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE11 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:0.02 In:0 1 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE12 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:0.02 In:0 4 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE13 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:0.02 In:0 3 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE14 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… In:0 5 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE15 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… In:0 3 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE16 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… In:0 6 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE17 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:0.02 In:0 16 1 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE18 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… In:0 2 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE19 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… In:0 2 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE2 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… In:0 4 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE20 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… In:0 2 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE21 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… In:0 4 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE22 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:0.13 In:0 4 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE23 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… In:0 13 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE24 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… In:0 4 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE25 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… In:0 2 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE26 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… In:0 14 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE27 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:0.05 In:0 2 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE28 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:0.02 In:0 10 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE29 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… In:0 7 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE3 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… In:0 7 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE30 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… In:0 5 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE4 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:0.13 In:0 15 0 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE5 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:0.05 In:0 34 1 0 0
[java] Fri 16:18:40 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE6 FINISHED_OK
[java] Fri 16:18:40 INFO [WatchDog] Tracking | %cpu:… In:0 19 1 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE7 FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:0.02 In:0 2 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE8 FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:0.02 In:0 4 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE9 FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:0.02 In:0 6 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE_EntryMeFINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… In:0 7 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE_ItemNamFINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:0.02 In:0 5 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE_ItemNamFINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:0.02 In:0 7 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE_MaxDiscFINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:0.02 In:0 6 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE_RetailTFINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:0.05 In:0 22 1 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | DB_OUTPUT_TABLE_SalesAsFINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:0.02 In:0 8 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | DELIMITED_DATA_READER0 FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 6 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | DELIMITED_DATA_READER1 FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 16 1 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | DELIMITED_DATA_READER2 FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 2 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | EducationLevel FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 10 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | EntryMethodLookup FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 7 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | EventControl FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 3 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | EventStatus FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 5 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | EventType FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 3 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | ItemNameValueType FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 5 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | LegalStatus FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 7 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | MaritalStatus FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 4 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | MaxDiscountMethod FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:0.02 Out:0 6 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | OperatingPartyType FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 2 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | OrganizationType FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 14 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | PartySubType FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 5 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | PartyType FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 13 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | PromotionType FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:0.02 Out:0 1 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | RetailTransactionLineItFINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 22 1 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | SalesAssociateAction FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 8 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | WorkerType FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:0.02 Out:0 2 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | accrual_method_enum FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 2 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | collection_method_enum FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 2 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | customer_order_state_enFINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 7 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | customer_order_type_enuFINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 4 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | fin_ledger_acct_type_enFINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 2 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | lineitemassociation FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 5 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | tender_class_enum FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 15 0 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | total_type_enum FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 19 1 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | transaction_type_enum FINISHED_OK
[java] Fri 16:18:41 INFO [WatchDog] Tracking | %cpu:… Out:0 34 1 0 0
[java] Fri 16:18:41 INFO [WatchDog] Tracking | ---------------------------------** End of Log **--------------------------------
[java] Fri 16:18:41 INFO [WatchDog] WatchDog | -----------------------** Summary of Phases execution **---------------------
[java] Fri 16:18:41 INFO [WatchDog] WatchDog | Phase# Finished Status RunTime(sec) MemoryAllocation(KB)
[java] Fri 16:18:41 INFO [WatchDog] WatchDog | 0 FINISHED_OK 0 10605
[java] Fri 16:18:41 INFO [WatchDog] WatchDog | ------------------------------** End of Summary **---------------------------
[java] Fri 16:18:41 INFO [WatchDog] WatchDog | WatchDog thread finished - total execution time: 6 (sec)
[java] Freeing graph resources.
[java] Execution of graph successful !

Geez, I found the root cause. Turns out when we invoke Clover from our build system we’re passing in a properties file for the DB2 driver, which is enabling all kinds of logging at the JDBC layer. If I turn off the JDBC logging then the connection times become much faster.

Doh!

Hi,
you are right in your first message: each connection is first. If you want to disable this feature use threadSafeConnection=false (see DBConnection)