[11:41:23.095] INFO: *** Welcome to pxar ***
[11:41:23.095] INFO: *** Today: 2015/08/04
[11:41:23.095] INFO: readRocDacs: /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters_C15.dat
[11:41:23.096] INFO: readTbmDacs: /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/tbmParameters_C0a.dat .. /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/tbmParameters_C0b.dat
[11:41:23.096] INFO: readMaskFile: /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/defaultMaskFile.dat
[11:41:23.096] INFO: readTrimFile: /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/trimParameters_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/trimParameters_C15.dat
[11:41:23.199] INFO: clk: 4
[11:41:23.199] INFO: ctr: 4
[11:41:23.199] INFO: sda: 19
[11:41:23.199] INFO: tin: 9
[11:41:23.199] INFO: level: 15
[11:41:23.199] INFO: triggerdelay: 0
[11:41:23.199] QUIET: Instanciating API for pxar prod-01+36~geb5ac3f
[11:41:23.199] INFO: Log level: INFO
[11:41:23.211] INFO: Found DTB DTB_WZ4THG
[11:41:23.221] QUIET: Connection to board DTB_WZ4THG opened.
[11:41:23.224] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 173
HW version: DTB1.2
FW version: 4.2
SW version: 4.2
USB id: DTB_WZ4THG
MAC address: 40D8551180AD
Hostname: pixelDTB173
Comment:
------------------------------------------------------
[11:41:23.227] INFO: RPC call hashes of host and DTB match: 447413373
[11:41:24.965] INFO: DUT info:
[11:41:24.965] INFO: The DUT currently contains the following objects:
[11:41:24.965] INFO: 2 TBM Cores tbm08c (2 ON)
[11:41:24.965] INFO: TBM Core alpha (0): 7 registers set
[11:41:24.965] INFO: TBM Core beta (1): 7 registers set
[11:41:24.965] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:41:24.965] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:24.965] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:24.965] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:24.965] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:24.965] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:24.965] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:24.966] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:24.966] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:24.966] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:24.966] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:24.966] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:24.966] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:24.966] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:24.966] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:24.966] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:24.966] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:25.367] INFO: enter 'restricted' command line mode
[11:41:25.367] INFO: enter test to run
[11:41:25.368] INFO: test: pretest no parameter change
[11:41:25.368] INFO: running: pretest
[11:41:25.378] INFO: ######################################################################
[11:41:25.378] INFO: PixTestPretest::doTest()
[11:41:25.378] INFO: ######################################################################
[11:41:25.380] INFO: ----------------------------------------------------------------------
[11:41:25.380] INFO: PixTestPretest::programROC()
[11:41:25.380] INFO: ----------------------------------------------------------------------
[11:41:43.403] INFO: PixTestPretest::programROC() done: ROCs are all programmable
[11:41:43.403] INFO: IA differences per ROC: 19.3 20.1 19.3 20.9 19.3 17.7 16.9 19.3 20.1 17.7 18.5 19.3 17.7 18.5 20.1 18.5
[11:41:43.492] INFO: ----------------------------------------------------------------------
[11:41:43.492] INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[11:41:43.492] INFO: ----------------------------------------------------------------------
[11:42:03.162] INFO: PixTestPretest::setVana() done, Module Ia 389.9 mA = 24.3688 mA/ROC
[11:42:03.166] INFO: ----------------------------------------------------------------------
[11:42:03.166] INFO: PixTestPretest::findWorkingPixel()
[11:42:03.166] INFO: ----------------------------------------------------------------------
[11:42:10.911] INFO: Test took 7741ms.
[11:42:11.252] INFO: Found working pixel in all ROCs: col/row = 12/22
[11:42:11.336] INFO: ----------------------------------------------------------------------
[11:42:11.336] INFO: PixTestPretest::setVthrCompCalDel()
[11:42:11.336] INFO: ----------------------------------------------------------------------
[11:42:19.049] INFO: Test took 7704ms.
[11:42:19.393] INFO: PixTestPretest::setVthrCompCalDel() done
[11:42:19.393] INFO: CalDel: 111 111 113 97 118 119 143 133 117 133 154 135 111 119 132 145
[11:42:19.393] INFO: VthrComp: 51 53 51 51 51 51 51 51 52 52 51 51 51 51 51 51
[11:42:19.397] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters_C0.dat
[11:42:19.397] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters_C1.dat
[11:42:19.397] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters_C2.dat
[11:42:19.397] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters_C3.dat
[11:42:19.397] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters_C4.dat
[11:42:19.397] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters_C5.dat
[11:42:19.397] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters_C6.dat
[11:42:19.397] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters_C7.dat
[11:42:19.398] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters_C8.dat
[11:42:19.398] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters_C9.dat
[11:42:19.398] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters_C10.dat
[11:42:19.398] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters_C11.dat
[11:42:19.398] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters_C12.dat
[11:42:19.398] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters_C13.dat
[11:42:19.398] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters_C14.dat
[11:42:19.398] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters_C15.dat
[11:42:19.398] INFO: PixTestPretest::doTest() done, duration: 54 seconds
[11:42:19.498] INFO: enter test to run
[11:42:19.498] INFO: test: fulltest no parameter change
[11:42:19.498] INFO: running: fulltest
[11:42:19.498] INFO: ######################################################################
[11:42:19.498] INFO: PixTestFullTest::doTest()
[11:42:19.498] INFO: ######################################################################
[11:42:19.500] INFO: ######################################################################
[11:42:19.500] INFO: PixTestAlive::doTest()
[11:42:19.500] INFO: ######################################################################
[11:42:19.502] INFO: ----------------------------------------------------------------------
[11:42:19.502] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:42:19.502] INFO: ----------------------------------------------------------------------
[11:42:23.627] INFO: Test took 4123ms.
[11:42:23.662] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:23.915] INFO: PixTestAlive::aliveTest() done
[11:42:23.915] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:42:23.917] INFO: ----------------------------------------------------------------------
[11:42:23.917] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:42:23.917] INFO: ----------------------------------------------------------------------
[11:42:26.881] INFO: Test took 2961ms.
[11:42:26.887] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:26.887] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[11:42:27.130] INFO: PixTestAlive::maskTest() done
[11:42:27.130] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:42:27.133] INFO: ----------------------------------------------------------------------
[11:42:27.133] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:42:27.133] INFO: ----------------------------------------------------------------------
[11:42:31.284] INFO: Test took 4149ms.
[11:42:31.319] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:31.568] INFO: PixTestAlive::addressDecodingTest() done
[11:42:31.568] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:42:31.569] INFO: PixTestAlive::doTest() done, duration: 12 seconds
[11:42:31.576] INFO: ######################################################################
[11:42:31.576] INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[11:42:31.576] INFO: ######################################################################
[11:42:31.580] INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30/5) hits flags = 2 (plus default)
[11:42:31.609] INFO: dacScan step from 0 .. 29
[11:42:31.609] INFO: dacScan split into 1 runs with ntrig = 5
[11:42:31.609] INFO: run 1 of 1
[11:42:50.630] INFO: Test took 19021ms.
[11:42:50.688] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:50.688] INFO: dacScan step from 30 .. 59
[11:42:50.688] INFO: dacScan split into 1 runs with ntrig = 5
[11:42:50.688] INFO: run 1 of 1
[11:43:10.254] INFO: Test took 19566ms.
[11:43:10.357] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:43:10.366] INFO: dacScan step from 60 .. 89
[11:43:10.366] INFO: dacScan split into 1 runs with ntrig = 5
[11:43:10.366] INFO: run 1 of 1
[11:43:34.881] INFO: Test took 24515ms.
[11:43:35.258] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:43:35.346] INFO: dacScan step from 90 .. 119
[11:43:35.347] INFO: dacScan split into 1 runs with ntrig = 5
[11:43:35.347] INFO: run 1 of 1
[11:44:02.199] INFO: Test took 26852ms.
[11:44:02.763] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:44:02.873] INFO: dacScan step from 120 .. 149
[11:44:02.873] INFO: dacScan split into 1 runs with ntrig = 5
[11:44:02.873] INFO: run 1 of 1
[11:44:28.695] INFO: Test took 25822ms.
[11:44:29.111] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:45:09.539] INFO: PixTestBBMap::doTest() done, duration: 157 seconds
[11:45:09.539] INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:45:09.539] INFO: separation cut (per ROC): 128 128 97 117 96 95 121 114 140 120 88 113 105 116 110 119
[11:45:09.634] INFO: ######################################################################
[11:45:09.634] INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = 1
[11:45:09.634] INFO: ######################################################################
[11:45:09.634] INFO: ----------------------------------------------------------------------
[11:45:09.634] INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = 1
[11:45:09.634] INFO: ----------------------------------------------------------------------
[11:45:09.635] INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (-1/1) hits flags = 16 (plus default)
[11:45:09.649] INFO: dacScan split into 50 runs with ntrig = 1
[11:45:09.649] INFO: run 1 of 50
[11:45:58.238] INFO: Test took 48589ms.
[11:45:58.679] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:45:58.898] INFO: run 2 of 50
[11:46:47.459] INFO: Test took 48561ms.
[11:46:47.884] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:48.112] INFO: run 3 of 50
[11:47:36.707] INFO: Test took 48595ms.
[11:47:37.151] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:37.367] INFO: run 4 of 50
[11:48:25.937] INFO: Test took 48570ms.
[11:48:26.383] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:48:26.611] INFO: run 5 of 50
[11:49:15.271] INFO: Test took 48660ms.
[11:49:15.692] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:49:15.890] INFO: run 6 of 50
[11:50:04.432] INFO: Test took 48541ms.
[11:50:04.864] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:50:05.103] INFO: run 7 of 50
[11:50:53.680] INFO: Test took 48577ms.
[11:50:54.106] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:50:54.316] INFO: run 8 of 50
[11:51:42.920] INFO: Test took 48604ms.
[11:51:43.333] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:51:43.530] INFO: run 9 of 50
[11:52:32.064] INFO: Test took 48534ms.
[11:52:32.507] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:52:32.712] INFO: run 10 of 50
[11:53:21.349] INFO: Test took 48637ms.
[11:53:21.776] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:53:21.972] INFO: run 11 of 50
[11:54:10.552] INFO: Test took 48580ms.
[11:54:10.964] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:54:11.172] INFO: run 12 of 50
[11:54:59.833] INFO: Test took 48661ms.
[11:55:00.253] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:55:00.443] INFO: run 13 of 50
[11:55:49.089] INFO: Test took 48646ms.
[11:55:49.492] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:55:49.690] INFO: run 14 of 50
[11:56:38.367] INFO: Test took 48677ms.
[11:56:38.780] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:56:38.990] INFO: run 15 of 50
[11:57:27.635] INFO: Test took 48645ms.
[11:57:28.115] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:57:28.315] INFO: run 16 of 50
[11:58:16.957] INFO: Test took 48642ms.
[11:58:17.379] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:58:17.594] INFO: run 17 of 50
[11:59:06.332] INFO: Test took 48738ms.
[11:59:06.731] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:59:06.957] INFO: run 18 of 50
[11:59:55.683] INFO: Test took 48726ms.
[11:59:56.109] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:59:56.348] INFO: run 19 of 50
[12:00:45.069] INFO: Test took 48721ms.
[12:00:45.485] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:00:45.725] INFO: run 20 of 50
[12:01:34.578] INFO: Test took 48853ms.
[12:01:34.967] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:01:35.227] INFO: run 21 of 50
[12:02:23.921] INFO: Test took 48694ms.
[12:02:24.318] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:02:24.575] INFO: run 22 of 50
[12:03:13.400] INFO: Test took 48825ms.
[12:03:13.802] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:03:14.034] INFO: run 23 of 50
[12:04:02.846] INFO: Test took 48812ms.
[12:04:03.229] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:04:03.488] INFO: run 24 of 50
[12:04:52.294] INFO: Test took 48806ms.
[12:04:52.685] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:04:52.942] INFO: run 25 of 50
[12:05:41.702] INFO: Test took 48759ms.
[12:05:42.095] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:05:42.362] INFO: run 26 of 50
[12:06:31.147] INFO: Test took 48785ms.
[12:06:31.533] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:06:31.797] INFO: run 27 of 50
[12:07:20.638] INFO: Test took 48841ms.
[12:07:21.047] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:07:21.288] INFO: run 28 of 50
[12:08:10.069] INFO: Test took 48780ms.
[12:08:10.483] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:08:10.733] INFO: run 29 of 50
[12:08:59.578] INFO: Test took 48845ms.
[12:08:59.956] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:00.214] INFO: run 30 of 50
[12:09:49.065] INFO: Test took 48851ms.
[12:09:49.459] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:49.726] INFO: run 31 of 50
[12:10:38.591] INFO: Test took 48865ms.
[12:10:38.979] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:39.223] INFO: run 32 of 50
[12:11:28.045] INFO: Test took 48822ms.
[12:11:28.445] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:28.730] INFO: run 33 of 50
[12:12:17.550] INFO: Test took 48820ms.
[12:12:17.945] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:12:18.228] INFO: run 34 of 50
[12:13:07.132] INFO: Test took 48904ms.
[12:13:07.508] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:13:07.718] INFO: run 35 of 50
[12:13:56.563] INFO: Test took 48845ms.
[12:13:56.969] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:13:57.227] INFO: run 36 of 50
[12:14:46.094] INFO: Test took 48867ms.
[12:14:46.476] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:14:46.733] INFO: run 37 of 50
[12:15:35.610] INFO: Test took 48876ms.
[12:15:36.005] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:15:36.250] INFO: run 38 of 50
[12:16:25.048] INFO: Test took 48798ms.
[12:16:25.425] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:16:25.684] INFO: run 39 of 50
[12:17:14.541] INFO: Test took 48857ms.
[12:17:14.947] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:17:15.212] INFO: run 40 of 50
[12:18:04.142] INFO: Test took 48930ms.
[12:18:04.542] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:18:04.805] INFO: run 41 of 50
[12:18:53.715] INFO: Test took 48910ms.
[12:18:54.091] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:18:54.327] INFO: run 42 of 50
[12:19:43.202] INFO: Test took 48875ms.
[12:19:43.592] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:19:43.853] INFO: run 43 of 50
[12:20:32.834] INFO: Test took 48980ms.
[12:20:33.244] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:20:33.464] INFO: run 44 of 50
[12:21:22.282] INFO: Test took 48817ms.
[12:21:22.696] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:22.969] INFO: run 45 of 50
[12:22:11.941] INFO: Test took 48972ms.
[12:22:12.336] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:12.590] INFO: run 46 of 50
[12:23:01.539] INFO: Test took 48948ms.
[12:23:01.940] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:23:02.190] INFO: run 47 of 50
[12:23:51.130] INFO: Test took 48940ms.
[12:23:51.508] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:23:51.732] INFO: run 48 of 50
[12:24:40.644] INFO: Test took 48912ms.
[12:24:41.039] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:41.260] INFO: run 49 of 50
[12:25:30.146] INFO: Test took 48885ms.
[12:25:30.565] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:30.803] INFO: run 50 of 50
[12:26:19.653] INFO: Test took 48850ms.
[12:26:20.063] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:26:20.344] INFO: dumping ASCII scurve output file: SCurveData
[12:26:22.430] INFO: dumping ASCII scurve output file: SCurveData
[12:26:24.433] INFO: dumping ASCII scurve output file: SCurveData
[12:26:26.773] INFO: dumping ASCII scurve output file: SCurveData
[12:26:28.947] INFO: dumping ASCII scurve output file: SCurveData
[12:26:31.219] INFO: dumping ASCII scurve output file: SCurveData
[12:26:33.617] INFO: dumping ASCII scurve output file: SCurveData
[12:26:36.009] INFO: dumping ASCII scurve output file: SCurveData
[12:26:38.403] INFO: dumping ASCII scurve output file: SCurveData
[12:26:40.769] INFO: dumping ASCII scurve output file: SCurveData
[12:26:43.477] INFO: dumping ASCII scurve output file: SCurveData
[12:26:46.129] INFO: dumping ASCII scurve output file: SCurveData
[12:26:48.583] INFO: dumping ASCII scurve output file: SCurveData
[12:26:51.204] INFO: dumping ASCII scurve output file: SCurveData
[12:26:53.826] INFO: dumping ASCII scurve output file: SCurveData
[12:26:56.321] INFO: dumping ASCII scurve output file: SCurveData
[12:26:59.031] INFO: PixTestScurves::scurves() done
[12:26:59.031] INFO: Vcal mean: 103.58 116.11 86.08 100.18 89.84 84.05 87.69 83.48 106.33 109.80 82.91 98.88 92.63 104.41 94.32 87.05
[12:26:59.031] INFO: Vcal RMS: 6.00 6.07 5.46 5.48 6.18 5.09 5.42 5.41 5.54 6.17 5.52 5.86 5.45 6.16 6.03 5.26
[12:26:59.031] INFO: PixTestScurves::fullTest() done, duration: 2509 seconds
[12:26:59.137] INFO: ######################################################################
[12:26:59.137] INFO: PixTestTrim::doTest()
[12:26:59.137] INFO: ######################################################################
[12:26:59.139] INFO: ----------------------------------------------------------------------
[12:26:59.139] INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[12:26:59.139] INFO: ----------------------------------------------------------------------
[12:26:59.288] INFO: ---> VthrComp thr map (minimal VthrComp)
[12:26:59.288] INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[12:26:59.304] INFO: dacScan step from 0 .. 19
[12:26:59.304] INFO: dacScan split into 1 runs with ntrig = 5
[12:26:59.304] INFO: run 1 of 1
[12:27:12.843] INFO: Test took 13539ms.
[12:27:12.890] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:12.890] INFO: dacScan step from 20 .. 39
[12:27:12.890] INFO: dacScan split into 1 runs with ntrig = 5
[12:27:12.890] INFO: run 1 of 1
[12:27:26.643] INFO: Test took 13753ms.
[12:27:26.686] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:26.686] INFO: dacScan step from 40 .. 59
[12:27:26.686] INFO: dacScan split into 1 runs with ntrig = 5
[12:27:26.686] INFO: run 1 of 1
[12:27:40.271] INFO: Test took 13585ms.
[12:27:40.311] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:40.311] INFO: dacScan step from 60 .. 79
[12:27:40.311] INFO: dacScan split into 1 runs with ntrig = 5
[12:27:40.311] INFO: run 1 of 1
[12:27:53.906] INFO: Test took 13594ms.
[12:27:53.953] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:53.953] INFO: dacScan step from 80 .. 99
[12:27:53.953] INFO: dacScan split into 1 runs with ntrig = 5
[12:27:53.953] INFO: run 1 of 1
[12:28:07.001] INFO: Test took 14047ms.
[12:28:08.090] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:08.098] INFO: dacScan step from 100 .. 119
[12:28:08.098] INFO: dacScan split into 1 runs with ntrig = 5
[12:28:08.098] INFO: run 1 of 1
[12:28:24.375] INFO: Test took 16277ms.
[12:28:24.587] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:24.633] INFO: dacScan step from 120 .. 139
[12:28:24.633] INFO: dacScan split into 1 runs with ntrig = 5
[12:28:24.633] INFO: run 1 of 1
[12:28:43.224] INFO: Test took 18591ms.
[12:28:43.479] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:43.548] INFO: dacScan step from 140 .. 159
[12:28:43.548] INFO: dacScan split into 1 runs with ntrig = 5
[12:28:43.548] INFO: run 1 of 1
[12:28:58.913] INFO: Test took 15365ms.
[12:28:59.083] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:36.047] INFO: ROC 0 VthrComp = 106
[12:29:36.048] INFO: ROC 1 VthrComp = 110
[12:29:36.048] INFO: ROC 2 VthrComp = 90
[12:29:36.048] INFO: ROC 3 VthrComp = 105
[12:29:36.048] INFO: ROC 4 VthrComp = 87
[12:29:36.048] INFO: ROC 5 VthrComp = 89
[12:29:36.048] INFO: ROC 6 VthrComp = 89
[12:29:36.048] INFO: ROC 7 VthrComp = 87
[12:29:36.048] INFO: ROC 8 VthrComp = 108
[12:29:36.049] INFO: ROC 9 VthrComp = 105
[12:29:36.049] INFO: ROC 10 VthrComp = 80
[12:29:36.049] INFO: ROC 11 VthrComp = 101
[12:29:36.049] INFO: ROC 12 VthrComp = 96
[12:29:36.049] INFO: ROC 13 VthrComp = 105
[12:29:36.049] INFO: ROC 14 VthrComp = 97
[12:29:36.049] INFO: ROC 15 VthrComp = 91
[12:29:36.049] INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[12:29:36.049] INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[12:29:36.064] INFO: dacScan step from 0 .. 19
[12:29:36.064] INFO: dacScan split into 1 runs with ntrig = 5
[12:29:36.064] INFO: run 1 of 1
[12:29:49.606] INFO: Test took 13542ms.
[12:29:49.656] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:49.656] INFO: dacScan step from 20 .. 39
[12:29:49.656] INFO: dacScan split into 1 runs with ntrig = 5
[12:29:49.656] INFO: run 1 of 1
[12:30:03.356] INFO: Test took 13699ms.
[12:30:03.407] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:03.409] INFO: dacScan step from 40 .. 59
[12:30:03.409] INFO: dacScan split into 1 runs with ntrig = 5
[12:30:03.409] INFO: run 1 of 1
[12:30:20.281] INFO: Test took 16871ms.
[12:30:20.578] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:20.645] INFO: dacScan step from 60 .. 79
[12:30:20.645] INFO: dacScan split into 1 runs with ntrig = 5
[12:30:20.645] INFO: run 1 of 1
[12:30:39.682] INFO: Test took 19036ms.
[12:30:39.997] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:40.076] INFO: dacScan step from 80 .. 99
[12:30:40.076] INFO: dacScan split into 1 runs with ntrig = 5
[12:30:40.076] INFO: run 1 of 1
[12:30:59.079] INFO: Test took 19003ms.
[12:30:59.467] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:59.548] INFO: dacScan step from 100 .. 119
[12:30:59.548] INFO: dacScan split into 1 runs with ntrig = 5
[12:30:59.548] INFO: run 1 of 1
[12:31:18.503] INFO: Test took 18955ms.
[12:31:18.795] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:31:18.863] INFO: dacScan step from 120 .. 139
[12:31:18.863] INFO: dacScan split into 1 runs with ntrig = 5
[12:31:18.863] INFO: run 1 of 1
[12:31:39.300] INFO: Test took 20437ms.
[12:31:39.620] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:31:39.698] INFO: dacScan step from 140 .. 159
[12:31:39.698] INFO: dacScan split into 1 runs with ntrig = 5
[12:31:39.698] INFO: run 1 of 1
[12:31:58.754] INFO: Test took 19056ms.
[12:31:59.097] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:32:42.415] INFO: roc 0 with ID = 0 has maximal Vcal 62.6729 for pixel 10/2 mean/min/max = 48.2643/33.825/62.7035
[12:32:42.415] INFO: roc 1 with ID = 1 has maximal Vcal 70.9609 for pixel 5/17 mean/min/max = 53.5928/35.8019/71.3837
[12:32:42.416] INFO: roc 2 with ID = 2 has maximal Vcal 59.4644 for pixel 0/19 mean/min/max = 46.1769/32.7021/59.6517
[12:32:42.416] INFO: roc 3 with ID = 3 has maximal Vcal 61.102 for pixel 3/37 mean/min/max = 47.5571/33.9504/61.1637
[12:32:42.417] INFO: roc 4 with ID = 4 has maximal Vcal 63.4334 for pixel 1/74 mean/min/max = 47.4122/31.3645/63.4599
[12:32:42.417] INFO: roc 5 with ID = 5 has maximal Vcal 57.7743 for pixel 9/16 mean/min/max = 45.2023/32.607/57.7976
[12:32:42.418] INFO: roc 6 with ID = 6 has maximal Vcal 58.3335 for pixel 25/0 mean/min/max = 45.7823/32.9023/58.6622
[12:32:42.418] INFO: roc 7 with ID = 7 has maximal Vcal 59.0832 for pixel 0/19 mean/min/max = 45.4971/31.6085/59.3856
[12:32:42.418] INFO: roc 8 with ID = 8 has maximal Vcal 62.1371 for pixel 14/2 mean/min/max = 48.1002/33.9156/62.2847
[12:32:42.419] INFO: roc 9 with ID = 9 has maximal Vcal 67.1297 for pixel 2/41 mean/min/max = 49.9594/32.6971/67.2216
[12:32:42.419] INFO: roc 10 with ID = 10 has maximal Vcal 62.3138 for pixel 1/67 mean/min/max = 47.0505/31.7399/62.3612
[12:32:42.420] INFO: roc 11 with ID = 11 has maximal Vcal 60.4346 for pixel 3/6 mean/min/max = 46.2411/31.9744/60.5078
[12:32:42.420] INFO: roc 12 with ID = 12 has maximal Vcal 59.3188 for pixel 18/38 mean/min/max = 45.6234/31.8688/59.378
[12:32:42.421] INFO: roc 13 with ID = 13 has maximal Vcal 62.3173 for pixel 25/78 mean/min/max = 47.8255/32.8781/62.7729
[12:32:42.421] INFO: roc 14 with ID = 14 has maximal Vcal 58.951 for pixel 17/14 mean/min/max = 45.0757/30.8061/59.3453
[12:32:42.422] INFO: roc 15 with ID = 15 has maximal Vcal 58.8615 for pixel 0/48 mean/min/max = 45.6259/32.3789/58.8728
[12:32:42.422] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:34:05.752] INFO: Test took 83330ms.
[12:34:07.856] INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[12:34:07.870] INFO: dacScan step from 0 .. 19
[12:34:07.870] INFO: dacScan split into 2 runs with ntrig = 5
[12:34:07.870] INFO: run 1 of 2
[12:34:21.478] INFO: Test took 13608ms.
[12:34:21.526] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:21.529] INFO: run 2 of 2
[12:34:35.306] INFO: Test took 13777ms.
[12:34:35.360] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:35.362] INFO: dacScan step from 20 .. 39
[12:34:35.362] INFO: dacScan split into 2 runs with ntrig = 5
[12:34:35.362] INFO: run 1 of 2
[12:34:51.561] INFO: Test took 16199ms.
[12:34:51.762] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:51.811] INFO: run 2 of 2
[12:35:07.992] INFO: Test took 16181ms.
[12:35:08.198] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:08.250] INFO: dacScan step from 40 .. 59
[12:35:08.250] INFO: dacScan split into 2 runs with ntrig = 5
[12:35:08.250] INFO: run 1 of 2
[12:35:27.221] INFO: Test took 18971ms.
[12:35:27.516] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:27.587] INFO: run 2 of 2
[12:35:46.501] INFO: Test took 18914ms.
[12:35:46.786] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:46.859] INFO: dacScan step from 60 .. 79
[12:35:46.859] INFO: dacScan split into 2 runs with ntrig = 5
[12:35:46.859] INFO: run 1 of 2
[12:36:05.869] INFO: Test took 19010ms.
[12:36:06.185] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:36:06.263] INFO: run 2 of 2
[12:36:25.332] INFO: Test took 19069ms.
[12:36:25.634] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:36:25.708] INFO: dacScan step from 80 .. 99
[12:36:25.708] INFO: dacScan split into 2 runs with ntrig = 5
[12:36:25.708] INFO: run 1 of 2
[12:36:44.742] INFO: Test took 19034ms.
[12:36:45.041] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:36:45.116] INFO: run 2 of 2
[12:37:04.114] INFO: Test took 18998ms.
[12:37:04.447] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:37:04.516] INFO: dacScan step from 100 .. 119
[12:37:04.516] INFO: dacScan split into 2 runs with ntrig = 5
[12:37:04.516] INFO: run 1 of 2
[12:37:23.655] INFO: Test took 19139ms.
[12:37:23.949] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:37:24.026] INFO: run 2 of 2
[12:37:43.143] INFO: Test took 19117ms.
[12:37:43.465] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:37:43.535] INFO: dacScan step from 120 .. 139
[12:37:43.535] INFO: dacScan split into 2 runs with ntrig = 5
[12:37:43.535] INFO: run 1 of 2
[12:38:04.111] INFO: Test took 20576ms.
[12:38:04.387] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:38:04.455] INFO: run 2 of 2
[12:38:24.974] INFO: Test took 20519ms.
[12:38:25.288] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:38:25.365] INFO: dacScan step from 140 .. 159
[12:38:25.365] INFO: dacScan split into 2 runs with ntrig = 5
[12:38:25.365] INFO: run 1 of 2
[12:38:44.616] INFO: Test took 19250ms.
[12:38:44.922] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:38:44.997] INFO: run 2 of 2
[12:39:04.236] INFO: Test took 19239ms.
[12:39:04.555] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:39:04.632] INFO: dacScan step from 160 .. 179
[12:39:04.632] INFO: dacScan split into 2 runs with ntrig = 5
[12:39:04.632] INFO: run 1 of 2
[12:39:23.801] INFO: Test took 19169ms.
[12:39:24.103] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:39:24.182] INFO: run 2 of 2
[12:39:43.386] INFO: Test took 19204ms.
[12:39:43.695] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:39:43.762] INFO: dacScan step from 180 .. 199
[12:39:43.762] INFO: dacScan split into 2 runs with ntrig = 5
[12:39:43.762] INFO: run 1 of 2
[12:40:03.691] INFO: Test took 19929ms.
[12:40:03.962] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:40:04.030] INFO: run 2 of 2
[12:40:23.815] INFO: Test took 19785ms.
[12:40:24.151] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:06.888] INFO: ---> TrimStepCorr4 extremal thresholds: 0.024810 .. 255.000000
[12:41:07.074] INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 255 (20/-1) hits flags = 16 (plus default)
[12:41:07.088] INFO: dacScan step from 0 .. 19
[12:41:07.089] INFO: dacScan split into 1 runs with ntrig = 4
[12:41:07.089] INFO: run 1 of 1
[12:41:19.486] INFO: Test took 12397ms.
[12:41:19.530] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:19.530] INFO: dacScan step from 20 .. 39
[12:41:19.530] INFO: dacScan split into 1 runs with ntrig = 4
[12:41:19.530] INFO: run 1 of 1
[12:41:32.898] INFO: Test took 13368ms.
[12:41:33.043] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:33.076] INFO: dacScan step from 40 .. 59
[12:41:33.076] INFO: dacScan split into 1 runs with ntrig = 4
[12:41:33.076] INFO: run 1 of 1
[12:41:49.569] INFO: Test took 16493ms.
[12:41:49.848] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:49.922] INFO: dacScan step from 60 .. 79
[12:41:49.922] INFO: dacScan split into 1 runs with ntrig = 4
[12:41:49.922] INFO: run 1 of 1
[12:42:06.694] INFO: Test took 16772ms.
[12:42:06.957] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:42:07.033] INFO: dacScan step from 80 .. 99
[12:42:07.033] INFO: dacScan split into 1 runs with ntrig = 4
[12:42:07.033] INFO: run 1 of 1
[12:42:23.882] INFO: Test took 16849ms.
[12:42:24.146] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:42:24.223] INFO: dacScan step from 100 .. 119
[12:42:24.223] INFO: dacScan split into 1 runs with ntrig = 4
[12:42:24.223] INFO: run 1 of 1
[12:42:41.149] INFO: Test took 16926ms.
[12:42:41.401] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:42:41.475] INFO: dacScan step from 120 .. 139
[12:42:41.475] INFO: dacScan split into 1 runs with ntrig = 4
[12:42:41.475] INFO: run 1 of 1
[12:42:59.754] INFO: Test took 18278ms.
[12:43:00.016] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:00.087] INFO: dacScan step from 140 .. 159
[12:43:00.087] INFO: dacScan split into 1 runs with ntrig = 4
[12:43:00.087] INFO: run 1 of 1
[12:43:17.033] INFO: Test took 16946ms.
[12:43:17.300] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:17.378] INFO: dacScan step from 160 .. 179
[12:43:17.378] INFO: dacScan split into 1 runs with ntrig = 4
[12:43:17.378] INFO: run 1 of 1
[12:43:34.288] INFO: Test took 16910ms.
[12:43:34.552] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:34.628] INFO: dacScan step from 180 .. 199
[12:43:34.628] INFO: dacScan split into 1 runs with ntrig = 4
[12:43:34.628] INFO: run 1 of 1
[12:43:52.212] INFO: Test took 17584ms.
[12:43:52.483] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:52.550] INFO: dacScan step from 200 .. 219
[12:43:52.550] INFO: dacScan split into 1 runs with ntrig = 4
[12:43:52.550] INFO: run 1 of 1
[12:44:09.240] INFO: Test took 16690ms.
[12:44:09.508] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:09.581] INFO: dacScan step from 220 .. 239
[12:44:09.581] INFO: dacScan split into 1 runs with ntrig = 4
[12:44:09.581] INFO: run 1 of 1
[12:44:26.311] INFO: Test took 16730ms.
[12:44:26.566] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:26.639] INFO: dacScan step from 240 .. 255
[12:44:26.639] INFO: dacScan split into 1 runs with ntrig = 4
[12:44:26.639] INFO: run 1 of 1
[12:44:40.493] INFO: Test took 13854ms.
[12:44:40.713] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:45:32.914] INFO: ---> TrimStepCorr2 extremal thresholds: 15.663804 .. 44.215608
[12:45:33.065] INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 5 .. 54 (20/-1) hits flags = 16 (plus default)
[12:45:33.079] INFO: dacScan step from 5 .. 24
[12:45:33.079] INFO: dacScan split into 1 runs with ntrig = 4
[12:45:33.079] INFO: run 1 of 1
[12:45:45.378] INFO: Test took 12299ms.
[12:45:45.420] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:45:45.420] INFO: dacScan step from 25 .. 44
[12:45:45.420] INFO: dacScan split into 1 runs with ntrig = 4
[12:45:45.420] INFO: run 1 of 1
[12:45:59.667] INFO: Test took 14247ms.
[12:45:59.864] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:45:59.917] INFO: dacScan step from 45 .. 54
[12:45:59.917] INFO: dacScan split into 1 runs with ntrig = 4
[12:45:59.917] INFO: run 1 of 1
[12:46:09.659] INFO: Test took 9742ms.
[12:46:09.811] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:34.382] INFO: ---> TrimStepCorr1a extremal thresholds: 10.500000 .. 41.366108
[12:46:34.525] INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 0 .. 51 (20/-1) hits flags = 16 (plus default)
[12:46:34.539] INFO: dacScan step from 0 .. 19
[12:46:34.539] INFO: dacScan split into 1 runs with ntrig = 4
[12:46:34.539] INFO: run 1 of 1
[12:46:46.771] INFO: Test took 12232ms.
[12:46:46.810] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:46.810] INFO: dacScan step from 20 .. 39
[12:46:46.810] INFO: dacScan split into 1 runs with ntrig = 4
[12:46:46.810] INFO: run 1 of 1
[12:46:59.001] INFO: Test took 13191ms.
[12:47:00.128] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:47:00.161] INFO: dacScan step from 40 .. 51
[12:47:00.161] INFO: dacScan split into 1 runs with ntrig = 4
[12:47:00.161] INFO: run 1 of 1
[12:47:11.329] INFO: Test took 11167ms.
[12:47:11.503] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:47:34.802] INFO: ---> TrimStepCorr1b extremal thresholds: 1.085459 .. 40.422563
[12:47:34.938] INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 1 .. 50 (20/-1) hits flags = 16 (plus default)
[12:47:34.952] INFO: dacScan step from 1 .. 20
[12:47:34.952] INFO: dacScan split into 1 runs with ntrig = 4
[12:47:34.952] INFO: run 1 of 1
[12:47:47.302] INFO: Test took 12349ms.
[12:47:47.338] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:47:47.338] INFO: dacScan step from 21 .. 40
[12:47:47.338] INFO: dacScan split into 1 runs with ntrig = 4
[12:47:47.338] INFO: run 1 of 1
[12:48:00.613] INFO: Test took 13275ms.
[12:48:00.781] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:00.824] INFO: dacScan step from 41 .. 50
[12:48:00.824] INFO: dacScan split into 1 runs with ntrig = 4
[12:48:00.824] INFO: run 1 of 1
[12:48:10.708] INFO: Test took 9883ms.
[12:48:10.871] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:34.767] INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[12:48:34.767] INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20/5) hits flags = 16 (plus default)
[12:48:34.781] INFO: dacScan step from 15 .. 34
[12:48:34.781] INFO: dacScan split into 2 runs with ntrig = 5
[12:48:34.781] INFO: run 1 of 2
[12:48:48.658] INFO: Test took 13877ms.
[12:48:48.736] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:48.749] INFO: run 2 of 2
[12:49:02.580] INFO: Test took 13830ms.
[12:49:02.657] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:02.670] INFO: dacScan step from 35 .. 54
[12:49:02.670] INFO: dacScan split into 2 runs with ntrig = 5
[12:49:02.670] INFO: run 1 of 2
[12:49:21.236] INFO: Test took 18565ms.
[12:49:21.546] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:21.618] INFO: run 2 of 2
[12:49:40.278] INFO: Test took 18660ms.
[12:49:40.605] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:40.689] INFO: dacScan step from 55 .. 55
[12:49:40.690] INFO: dacScan split into 2 runs with ntrig = 5
[12:49:40.690] INFO: run 1 of 2
[12:49:44.295] INFO: Test took 3605ms.
[12:49:44.317] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:44.326] INFO: run 2 of 2
[12:49:47.971] INFO: Test took 3645ms.
[12:49:47.993] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:50:12.415] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C0.dat
[12:50:12.415] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C1.dat
[12:50:12.416] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C2.dat
[12:50:12.416] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C3.dat
[12:50:12.416] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C4.dat
[12:50:12.417] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C5.dat
[12:50:12.417] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C6.dat
[12:50:12.417] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C7.dat
[12:50:12.418] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C8.dat
[12:50:12.418] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C9.dat
[12:50:12.418] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C10.dat
[12:50:12.419] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C11.dat
[12:50:12.419] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C12.dat
[12:50:12.419] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C13.dat
[12:50:12.419] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C14.dat
[12:50:12.420] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C15.dat
[12:50:12.420] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/trimParameters35_C0.dat
[12:50:12.434] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/trimParameters35_C1.dat
[12:50:12.446] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/trimParameters35_C2.dat
[12:50:12.458] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/trimParameters35_C3.dat
[12:50:12.471] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/trimParameters35_C4.dat
[12:50:12.483] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/trimParameters35_C5.dat
[12:50:12.494] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/trimParameters35_C6.dat
[12:50:12.507] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/trimParameters35_C7.dat
[12:50:12.520] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/trimParameters35_C8.dat
[12:50:12.532] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/trimParameters35_C9.dat
[12:50:12.544] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/trimParameters35_C10.dat
[12:50:12.556] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/trimParameters35_C11.dat
[12:50:12.569] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/trimParameters35_C12.dat
[12:50:12.580] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/trimParameters35_C13.dat
[12:50:12.592] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/trimParameters35_C14.dat
[12:50:12.604] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/trimParameters35_C15.dat
[12:50:12.616] INFO: PixTestTrim::trimTest() done
[12:50:12.616] INFO: vtrim: 131 144 105 116 117 99 107 112 108 138 104 101 98 113 102 98
[12:50:12.616] INFO: vthrcomp: 106 110 90 105 87 89 89 87 108 105 80 101 96 105 97 91
[12:50:12.616] INFO: vcal mean: 35.05 35.06 35.02 35.09 35.06 35.08 35.12 35.03 35.09 35.10 35.04 35.04 35.07 35.07 35.03 35.05
[12:50:12.616] INFO: vcal RMS: 1.05 1.20 1.07 0.99 1.23 1.04 1.10 1.04 1.00 1.23 1.61 1.09 1.08 1.15 1.11 1.18
[12:50:12.616] INFO: bits mean: 8.98 8.18 9.29 8.90 9.84 10.24 9.88 9.72 8.63 9.21 9.57 9.46 9.46 9.27 9.86 9.73
[12:50:12.616] INFO: bits RMS: 2.46 2.28 2.62 2.52 2.49 2.26 2.45 2.64 2.61 2.43 2.63 2.66 2.73 2.53 2.70 2.53
[12:50:12.628] INFO: ----------------------------------------------------------------------
[12:50:12.628] INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[12:50:12.628] INFO: ----------------------------------------------------------------------
[12:50:12.632] INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[12:50:12.647] INFO: dacScan step from 0 .. 19
[12:50:12.647] INFO: dacScan split into 2 runs with ntrig = 5
[12:50:12.647] INFO: run 1 of 2
[12:50:26.215] INFO: Test took 13568ms.
[12:50:26.257] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:50:26.258] INFO: run 2 of 2
[12:50:39.887] INFO: Test took 13629ms.
[12:50:39.932] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:50:39.932] INFO: dacScan step from 20 .. 39
[12:50:39.932] INFO: dacScan split into 2 runs with ntrig = 5
[12:50:39.932] INFO: run 1 of 2
[12:50:53.607] INFO: Test took 13674ms.
[12:50:53.648] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:50:53.648] INFO: run 2 of 2
[12:51:07.234] INFO: Test took 13585ms.
[12:51:07.274] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:51:07.274] INFO: dacScan step from 40 .. 59
[12:51:07.274] INFO: dacScan split into 2 runs with ntrig = 5
[12:51:07.274] INFO: run 1 of 2
[12:51:20.927] INFO: Test took 13652ms.
[12:51:20.969] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:51:20.969] INFO: run 2 of 2
[12:51:34.627] INFO: Test took 13658ms.
[12:51:34.671] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:51:34.671] INFO: dacScan step from 60 .. 79
[12:51:34.671] INFO: dacScan split into 2 runs with ntrig = 5
[12:51:34.671] INFO: run 1 of 2
[12:51:48.194] INFO: Test took 13523ms.
[12:51:48.239] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:51:48.239] INFO: run 2 of 2
[12:52:01.817] INFO: Test took 13577ms.
[12:52:01.861] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:52:01.861] INFO: dacScan step from 80 .. 99
[12:52:01.861] INFO: dacScan split into 2 runs with ntrig = 5
[12:52:01.861] INFO: run 1 of 2
[12:52:15.436] INFO: Test took 13575ms.
[12:52:15.481] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:52:15.482] INFO: run 2 of 2
[12:52:29.083] INFO: Test took 13601ms.
[12:52:29.130] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:52:29.131] INFO: dacScan step from 100 .. 119
[12:52:29.131] INFO: dacScan split into 2 runs with ntrig = 5
[12:52:29.131] INFO: run 1 of 2
[12:52:43.795] INFO: Test took 14664ms.
[12:52:43.938] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:52:43.961] INFO: run 2 of 2
[12:52:58.732] INFO: Test took 14770ms.
[12:52:58.875] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:52:58.898] INFO: dacScan step from 120 .. 139
[12:52:58.898] INFO: dacScan split into 2 runs with ntrig = 5
[12:52:58.898] INFO: run 1 of 2
[12:53:17.757] INFO: Test took 18859ms.
[12:53:18.026] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:53:18.092] INFO: run 2 of 2
[12:53:37.006] INFO: Test took 18914ms.
[12:53:37.290] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:53:37.355] INFO: dacScan step from 140 .. 159
[12:53:37.355] INFO: dacScan split into 2 runs with ntrig = 5
[12:53:37.355] INFO: run 1 of 2
[12:53:56.470] INFO: Test took 19114ms.
[12:53:56.771] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:53:56.850] INFO: run 2 of 2
[12:54:15.854] INFO: Test took 19004ms.
[12:54:16.167] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:16.248] INFO: dacScan step from 160 .. 179
[12:54:16.248] INFO: dacScan split into 2 runs with ntrig = 5
[12:54:16.248] INFO: run 1 of 2
[12:54:35.453] INFO: Test took 19205ms.
[12:54:35.772] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:35.850] INFO: run 2 of 2
[12:54:54.941] INFO: Test took 19091ms.
[12:54:55.283] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:55.393] INFO: dacScan step from 180 .. 199
[12:54:55.393] INFO: dacScan split into 2 runs with ntrig = 5
[12:54:55.393] INFO: run 1 of 2
[12:55:15.455] INFO: Test took 20062ms.
[12:55:15.755] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:15.832] INFO: run 2 of 2
[12:55:35.823] INFO: Test took 19991ms.
[12:55:36.156] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:56:18.365] INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 187 (20/5) hits flags = 16 (plus default)
[12:56:18.380] INFO: dacScan step from 0 .. 19
[12:56:18.381] INFO: dacScan split into 2 runs with ntrig = 5
[12:56:18.381] INFO: run 1 of 2
[12:56:32.055] INFO: Test took 13674ms.
[12:56:32.101] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:56:32.101] INFO: run 2 of 2
[12:56:45.733] INFO: Test took 13632ms.
[12:56:45.777] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:56:45.777] INFO: dacScan step from 20 .. 39
[12:56:45.777] INFO: dacScan split into 2 runs with ntrig = 5
[12:56:45.777] INFO: run 1 of 2
[12:56:59.522] INFO: Test took 13745ms.
[12:56:59.572] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:56:59.572] INFO: run 2 of 2
[12:57:13.211] INFO: Test took 13639ms.
[12:57:13.258] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:13.259] INFO: dacScan step from 40 .. 59
[12:57:13.259] INFO: dacScan split into 2 runs with ntrig = 5
[12:57:13.259] INFO: run 1 of 2
[12:57:26.929] INFO: Test took 13670ms.
[12:57:26.972] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:26.972] INFO: run 2 of 2
[12:57:40.637] INFO: Test took 13664ms.
[12:57:40.681] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:40.681] INFO: dacScan step from 60 .. 79
[12:57:40.681] INFO: dacScan split into 2 runs with ntrig = 5
[12:57:40.681] INFO: run 1 of 2
[12:57:54.306] INFO: Test took 13624ms.
[12:57:54.347] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:54.347] INFO: run 2 of 2
[12:58:08.068] INFO: Test took 13721ms.
[12:58:08.109] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:08.109] INFO: dacScan step from 80 .. 99
[12:58:08.109] INFO: dacScan split into 2 runs with ntrig = 5
[12:58:08.109] INFO: run 1 of 2
[12:58:21.905] INFO: Test took 13796ms.
[12:58:21.980] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:21.986] INFO: run 2 of 2
[12:58:35.875] INFO: Test took 13889ms.
[12:58:35.942] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:35.952] INFO: dacScan step from 100 .. 119
[12:58:35.952] INFO: dacScan split into 2 runs with ntrig = 5
[12:58:35.952] INFO: run 1 of 2
[12:58:52.349] INFO: Test took 16397ms.
[12:58:52.538] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:52.577] INFO: run 2 of 2
[12:59:08.637] INFO: Test took 16059ms.
[12:59:08.864] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:08.912] INFO: dacScan step from 120 .. 139
[12:59:08.912] INFO: dacScan split into 2 runs with ntrig = 5
[12:59:08.912] INFO: run 1 of 2
[12:59:28.710] INFO: Test took 19798ms.
[12:59:29.102] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:29.188] INFO: run 2 of 2
[12:59:49.203] INFO: Test took 20014ms.
[12:59:49.512] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:49.582] INFO: dacScan step from 140 .. 159
[12:59:49.582] INFO: dacScan split into 2 runs with ntrig = 5
[12:59:49.582] INFO: run 1 of 2
[13:00:08.667] INFO: Test took 19085ms.
[13:00:08.950] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:09.022] INFO: run 2 of 2
[13:00:28.225] INFO: Test took 19203ms.
[13:00:28.543] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:28.623] INFO: dacScan step from 160 .. 179
[13:00:28.623] INFO: dacScan split into 2 runs with ntrig = 5
[13:00:28.624] INFO: run 1 of 2
[13:00:47.782] INFO: Test took 19158ms.
[13:00:48.108] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:48.188] INFO: run 2 of 2
[13:01:07.403] INFO: Test took 19215ms.
[13:01:07.740] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:07.821] INFO: dacScan step from 180 .. 187
[13:01:07.821] INFO: dacScan split into 2 runs with ntrig = 5
[13:01:07.821] INFO: run 1 of 2
[13:01:17.870] INFO: Test took 10049ms.
[13:01:17.997] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:18.032] INFO: run 2 of 2
[13:01:27.993] INFO: Test took 9961ms.
[13:01:28.112] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:07.984] INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 178 (20/5) hits flags = 16 (plus default)
[13:02:07.998] INFO: dacScan step from 0 .. 19
[13:02:07.998] INFO: dacScan split into 2 runs with ntrig = 5
[13:02:07.998] INFO: run 1 of 2
[13:02:21.683] INFO: Test took 13685ms.
[13:02:21.730] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:21.730] INFO: run 2 of 2
[13:02:35.332] INFO: Test took 13602ms.
[13:02:35.373] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:35.373] INFO: dacScan step from 20 .. 39
[13:02:35.373] INFO: dacScan split into 2 runs with ntrig = 5
[13:02:35.373] INFO: run 1 of 2
[13:02:49.103] INFO: Test took 13730ms.
[13:02:49.148] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:49.149] INFO: run 2 of 2
[13:03:02.858] INFO: Test took 13709ms.
[13:03:02.900] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:02.900] INFO: dacScan step from 40 .. 59
[13:03:02.900] INFO: dacScan split into 2 runs with ntrig = 5
[13:03:02.900] INFO: run 1 of 2
[13:03:16.508] INFO: Test took 13608ms.
[13:03:16.553] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:16.553] INFO: run 2 of 2
[13:03:30.207] INFO: Test took 13655ms.
[13:03:30.251] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:30.251] INFO: dacScan step from 60 .. 79
[13:03:30.251] INFO: dacScan split into 2 runs with ntrig = 5
[13:03:30.251] INFO: run 1 of 2
[13:03:43.794] INFO: Test took 13543ms.
[13:03:43.849] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:43.849] INFO: run 2 of 2
[13:03:57.493] INFO: Test took 13644ms.
[13:03:57.534] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:57.534] INFO: dacScan step from 80 .. 99
[13:03:57.534] INFO: dacScan split into 2 runs with ntrig = 5
[13:03:57.534] INFO: run 1 of 2
[13:04:11.468] INFO: Test took 13934ms.
[13:04:11.532] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:11.536] INFO: run 2 of 2
[13:04:25.353] INFO: Test took 13816ms.
[13:04:25.425] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:25.430] INFO: dacScan step from 100 .. 119
[13:04:25.430] INFO: dacScan split into 2 runs with ntrig = 5
[13:04:25.430] INFO: run 1 of 2
[13:04:41.603] INFO: Test took 16173ms.
[13:04:41.795] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:41.833] INFO: run 2 of 2
[13:04:57.943] INFO: Test took 16109ms.
[13:04:58.158] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:58.202] INFO: dacScan step from 120 .. 139
[13:04:58.202] INFO: dacScan split into 2 runs with ntrig = 5
[13:04:58.202] INFO: run 1 of 2
[13:05:18.204] INFO: Test took 20002ms.
[13:05:18.495] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:05:18.566] INFO: run 2 of 2
[13:05:38.482] INFO: Test took 19916ms.
[13:05:38.790] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:05:38.857] INFO: dacScan step from 140 .. 159
[13:05:38.857] INFO: dacScan split into 2 runs with ntrig = 5
[13:05:38.857] INFO: run 1 of 2
[13:05:58.325] INFO: Test took 19467ms.
[13:05:58.685] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:05:58.774] INFO: run 2 of 2
[13:06:18.198] INFO: Test took 19424ms.
[13:06:18.526] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:06:18.608] INFO: dacScan step from 160 .. 178
[13:06:18.608] INFO: dacScan split into 2 runs with ntrig = 5
[13:06:18.608] INFO: run 1 of 2
[13:06:37.074] INFO: Test took 18466ms.
[13:06:37.394] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:06:37.471] INFO: run 2 of 2
[13:06:55.841] INFO: Test took 18370ms.
[13:06:56.151] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:07:34.920] INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 178 (20/5) hits flags = 16 (plus default)
[13:07:34.938] INFO: dacScan step from 0 .. 19
[13:07:34.938] INFO: dacScan split into 2 runs with ntrig = 5
[13:07:34.938] INFO: run 1 of 2
[13:07:48.665] INFO: Test took 13727ms.
[13:07:48.711] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:07:48.711] INFO: run 2 of 2
[13:08:02.436] INFO: Test took 13724ms.
[13:08:02.480] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:08:02.480] INFO: dacScan step from 20 .. 39
[13:08:02.480] INFO: dacScan split into 2 runs with ntrig = 5
[13:08:02.480] INFO: run 1 of 2
[13:08:16.186] INFO: Test took 13706ms.
[13:08:16.224] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:08:16.224] INFO: run 2 of 2
[13:08:29.951] INFO: Test took 13727ms.
[13:08:29.997] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:08:29.997] INFO: dacScan step from 40 .. 59
[13:08:29.997] INFO: dacScan split into 2 runs with ntrig = 5
[13:08:29.997] INFO: run 1 of 2
[13:08:43.574] INFO: Test took 13576ms.
[13:08:43.623] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:08:43.623] INFO: run 2 of 2
[13:08:57.313] INFO: Test took 13689ms.
[13:08:57.361] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:08:57.361] INFO: dacScan step from 60 .. 79
[13:08:57.361] INFO: dacScan split into 2 runs with ntrig = 5
[13:08:57.361] INFO: run 1 of 2
[13:09:11.146] INFO: Test took 13785ms.
[13:09:11.186] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:09:11.186] INFO: run 2 of 2
[13:09:24.819] INFO: Test took 13633ms.
[13:09:24.862] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:09:24.862] INFO: dacScan step from 80 .. 99
[13:09:24.862] INFO: dacScan split into 2 runs with ntrig = 5
[13:09:24.862] INFO: run 1 of 2
[13:09:38.513] INFO: Test took 13651ms.
[13:09:38.585] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:09:38.592] INFO: run 2 of 2
[13:09:52.304] INFO: Test took 13712ms.
[13:09:52.371] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:09:52.381] INFO: dacScan step from 100 .. 119
[13:09:52.381] INFO: dacScan split into 2 runs with ntrig = 5
[13:09:52.381] INFO: run 1 of 2
[13:10:08.668] INFO: Test took 16287ms.
[13:10:08.879] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:10:08.922] INFO: run 2 of 2
[13:10:25.094] INFO: Test took 16172ms.
[13:10:25.322] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:10:25.366] INFO: dacScan step from 120 .. 139
[13:10:25.366] INFO: dacScan split into 2 runs with ntrig = 5
[13:10:25.366] INFO: run 1 of 2
[13:10:45.283] INFO: Test took 19917ms.
[13:10:45.597] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:10:45.670] INFO: run 2 of 2
[13:11:05.659] INFO: Test took 19988ms.
[13:11:05.956] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:11:06.025] INFO: dacScan step from 140 .. 159
[13:11:06.025] INFO: dacScan split into 2 runs with ntrig = 5
[13:11:06.025] INFO: run 1 of 2
[13:11:25.171] INFO: Test took 19146ms.
[13:11:25.487] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:11:25.564] INFO: run 2 of 2
[13:11:44.854] INFO: Test took 19289ms.
[13:11:45.187] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:11:45.271] INFO: dacScan step from 160 .. 178
[13:11:45.271] INFO: dacScan split into 2 runs with ntrig = 5
[13:11:45.271] INFO: run 1 of 2
[13:12:03.675] INFO: Test took 18404ms.
[13:12:04.040] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:12:04.131] INFO: run 2 of 2
[13:12:22.623] INFO: Test took 18492ms.
[13:12:22.936] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:13:01.979] INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 176 (20/5) hits flags = 16 (plus default)
[13:13:01.998] INFO: dacScan step from 0 .. 19
[13:13:01.998] INFO: dacScan split into 2 runs with ntrig = 5
[13:13:01.998] INFO: run 1 of 2
[13:13:15.730] INFO: Test took 13732ms.
[13:13:15.771] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:13:15.771] INFO: run 2 of 2
[13:13:29.377] INFO: Test took 13605ms.
[13:13:29.422] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:13:29.422] INFO: dacScan step from 20 .. 39
[13:13:29.422] INFO: dacScan split into 2 runs with ntrig = 5
[13:13:29.422] INFO: run 1 of 2
[13:13:43.144] INFO: Test took 13722ms.
[13:13:43.186] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:13:43.186] INFO: run 2 of 2
[13:13:56.882] INFO: Test took 13696ms.
[13:13:56.925] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:13:56.925] INFO: dacScan step from 40 .. 59
[13:13:56.925] INFO: dacScan split into 2 runs with ntrig = 5
[13:13:56.925] INFO: run 1 of 2
[13:14:10.614] INFO: Test took 13689ms.
[13:14:10.662] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:10.662] INFO: run 2 of 2
[13:14:24.288] INFO: Test took 13626ms.
[13:14:24.336] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:24.336] INFO: dacScan step from 60 .. 79
[13:14:24.336] INFO: dacScan split into 2 runs with ntrig = 5
[13:14:24.336] INFO: run 1 of 2
[13:14:37.969] INFO: Test took 13633ms.
[13:14:38.013] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:38.013] INFO: run 2 of 2
[13:14:51.576] INFO: Test took 13563ms.
[13:14:51.620] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:51.620] INFO: dacScan step from 80 .. 99
[13:14:51.620] INFO: dacScan split into 2 runs with ntrig = 5
[13:14:51.620] INFO: run 1 of 2
[13:15:05.331] INFO: Test took 13711ms.
[13:15:05.411] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:05.417] INFO: run 2 of 2
[13:15:19.213] INFO: Test took 13796ms.
[13:15:19.289] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:19.296] INFO: dacScan step from 100 .. 119
[13:15:19.296] INFO: dacScan split into 2 runs with ntrig = 5
[13:15:19.296] INFO: run 1 of 2
[13:15:35.427] INFO: Test took 16131ms.
[13:15:35.655] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:35.700] INFO: run 2 of 2
[13:15:51.923] INFO: Test took 16223ms.
[13:15:52.134] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:52.177] INFO: dacScan step from 120 .. 139
[13:15:52.177] INFO: dacScan split into 2 runs with ntrig = 5
[13:15:52.177] INFO: run 1 of 2
[13:16:12.176] INFO: Test took 19998ms.
[13:16:12.483] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:12.556] INFO: run 2 of 2
[13:16:32.374] INFO: Test took 19818ms.
[13:16:32.642] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:32.720] INFO: dacScan step from 140 .. 159
[13:16:32.720] INFO: dacScan split into 2 runs with ntrig = 5
[13:16:32.720] INFO: run 1 of 2
[13:16:51.826] INFO: Test took 19106ms.
[13:16:52.153] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:52.234] INFO: run 2 of 2
[13:17:11.407] INFO: Test took 19173ms.
[13:17:11.756] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:11.838] INFO: dacScan step from 160 .. 176
[13:17:11.838] INFO: dacScan split into 2 runs with ntrig = 5
[13:17:11.838] INFO: run 1 of 2
[13:17:28.643] INFO: Test took 16805ms.
[13:17:28.941] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:29.015] INFO: run 2 of 2
[13:17:45.804] INFO: Test took 16789ms.
[13:17:46.069] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:23.763] INFO: PixTestTrim::trimBitTest() done
[13:18:23.765] INFO: PixTestTrim::doTest() done, duration: 3084 seconds
[13:18:24.741] INFO: ######################################################################
[13:18:24.741] INFO: PixTestPhOptimization::doTest() Ntrig = 10
[13:18:24.741] INFO: ######################################################################
[13:18:28.982] INFO: Test took 4239ms.
[13:18:29.024] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:33.492] INFO: Test took 4261ms.
[13:18:33.627] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:37.909] INFO: Test took 4263ms.
[13:18:38.056] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:38.068] INFO: The DUT currently contains the following objects:
[13:18:38.068] INFO: 2 TBM Cores tbm08c (2 ON)
[13:18:38.068] INFO: TBM Core alpha (0): 7 registers set
[13:18:38.068] INFO: TBM Core beta (1): 7 registers set
[13:18:38.068] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:18:38.068] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:38.068] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:38.068] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:38.068] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:38.068] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:38.069] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:38.069] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:38.069] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:38.069] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:38.069] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:38.069] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:38.069] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:38.069] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:38.069] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:38.069] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:38.069] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:39.169] INFO: Test took 1100ms.
[13:18:39.172] INFO: The DUT currently contains the following objects:
[13:18:39.172] INFO: 2 TBM Cores tbm08c (2 ON)
[13:18:39.172] INFO: TBM Core alpha (0): 7 registers set
[13:18:39.172] INFO: TBM Core beta (1): 7 registers set
[13:18:39.172] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:18:39.172] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:39.172] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:39.172] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:39.172] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:39.172] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:39.172] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:39.172] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:39.172] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:39.172] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:39.172] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:39.172] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:39.172] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:39.172] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:39.172] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:39.172] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:39.173] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:40.272] INFO: Test took 1099ms.
[13:18:40.274] INFO: The DUT currently contains the following objects:
[13:18:40.274] INFO: 2 TBM Cores tbm08c (2 ON)
[13:18:40.274] INFO: TBM Core alpha (0): 7 registers set
[13:18:40.274] INFO: TBM Core beta (1): 7 registers set
[13:18:40.274] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:18:40.274] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:40.274] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:40.274] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:40.274] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:40.275] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:40.275] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:40.275] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:40.275] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:40.275] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:40.275] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:40.275] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:40.275] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:40.275] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:40.275] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:40.275] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:40.275] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:41.374] INFO: Test took 1099ms.
[13:18:41.376] INFO: The DUT currently contains the following objects:
[13:18:41.376] INFO: 2 TBM Cores tbm08c (2 ON)
[13:18:41.376] INFO: TBM Core alpha (0): 7 registers set
[13:18:41.376] INFO: TBM Core beta (1): 7 registers set
[13:18:41.376] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:18:41.376] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:41.376] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:41.376] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:41.376] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:41.376] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:41.376] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:41.376] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:41.376] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:41.376] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:41.376] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:41.376] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:41.377] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:41.377] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:41.377] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:41.377] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:41.377] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:42.474] INFO: Test took 1097ms.
[13:18:42.476] INFO: The DUT currently contains the following objects:
[13:18:42.476] INFO: 2 TBM Cores tbm08c (2 ON)
[13:18:42.476] INFO: TBM Core alpha (0): 7 registers set
[13:18:42.476] INFO: TBM Core beta (1): 7 registers set
[13:18:42.476] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:18:42.476] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:42.476] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:42.476] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:42.476] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:42.476] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:42.476] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:42.476] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:42.476] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:42.476] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:42.476] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:42.476] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:42.476] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:42.476] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:42.476] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:42.476] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:42.476] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:43.572] INFO: Test took 1096ms.
[13:18:43.574] INFO: The DUT currently contains the following objects:
[13:18:43.574] INFO: 2 TBM Cores tbm08c (2 ON)
[13:18:43.574] INFO: TBM Core alpha (0): 7 registers set
[13:18:43.574] INFO: TBM Core beta (1): 7 registers set
[13:18:43.574] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:18:43.574] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:43.574] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:43.574] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:43.574] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:43.574] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:43.574] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:43.574] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:43.574] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:43.574] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:43.574] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:43.574] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:43.574] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:43.574] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:43.574] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:43.574] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:43.574] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:44.672] INFO: Test took 1098ms.
[13:18:44.674] INFO: The DUT currently contains the following objects:
[13:18:44.674] INFO: 2 TBM Cores tbm08c (2 ON)
[13:18:44.674] INFO: TBM Core alpha (0): 7 registers set
[13:18:44.674] INFO: TBM Core beta (1): 7 registers set
[13:18:44.674] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:18:44.674] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:44.674] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:44.674] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:44.674] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:44.674] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:44.675] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:44.675] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:44.675] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:44.675] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:44.675] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:44.675] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:44.675] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:44.675] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:44.675] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:44.675] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:44.675] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:45.773] INFO: Test took 1098ms.
[13:18:45.776] INFO: The DUT currently contains the following objects:
[13:18:45.776] INFO: 2 TBM Cores tbm08c (2 ON)
[13:18:45.776] INFO: TBM Core alpha (0): 7 registers set
[13:18:45.776] INFO: TBM Core beta (1): 7 registers set
[13:18:45.776] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:18:45.776] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:45.776] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:45.776] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:45.776] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:45.776] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:45.776] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:45.776] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:45.776] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:45.776] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:45.776] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:45.776] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:45.776] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:45.776] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:45.776] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:45.776] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:45.776] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:46.873] INFO: Test took 1097ms.
[13:18:46.875] INFO: The DUT currently contains the following objects:
[13:18:46.875] INFO: 2 TBM Cores tbm08c (2 ON)
[13:18:46.875] INFO: TBM Core alpha (0): 7 registers set
[13:18:46.875] INFO: TBM Core beta (1): 7 registers set
[13:18:46.875] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:18:46.875] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:46.875] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:46.875] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:46.875] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:46.875] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:46.875] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:46.875] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:46.875] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:46.875] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:46.875] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:46.875] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:46.875] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:46.875] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:46.875] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:46.876] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:46.876] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:47.969] INFO: Test took 1093ms.
[13:18:47.970] INFO: The DUT currently contains the following objects:
[13:18:47.971] INFO: 2 TBM Cores tbm08c (2 ON)
[13:18:47.971] INFO: TBM Core alpha (0): 7 registers set
[13:18:47.971] INFO: TBM Core beta (1): 7 registers set
[13:18:47.971] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:18:47.971] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:47.971] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:47.971] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:47.971] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:47.971] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:47.971] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:47.971] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:47.971] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:47.971] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:47.971] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:47.971] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:47.971] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:47.971] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:47.971] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:47.971] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:47.971] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:49.065] INFO: Test took 1094ms.
[13:18:49.066] INFO: The DUT currently contains the following objects:
[13:18:49.066] INFO: 2 TBM Cores tbm08c (2 ON)
[13:18:49.066] INFO: TBM Core alpha (0): 7 registers set
[13:18:49.066] INFO: TBM Core beta (1): 7 registers set
[13:18:49.066] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:18:49.066] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:49.066] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:49.066] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:49.066] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:49.066] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:49.066] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:49.066] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:49.066] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:49.066] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:49.066] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:49.066] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:49.066] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:49.066] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:49.066] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:49.066] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:49.066] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:50.163] INFO: Test took 1097ms.
[13:18:50.164] INFO: The DUT currently contains the following objects:
[13:18:50.165] INFO: 2 TBM Cores tbm08c (2 ON)
[13:18:50.165] INFO: TBM Core alpha (0): 7 registers set
[13:18:50.165] INFO: TBM Core beta (1): 7 registers set
[13:18:50.165] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:18:50.165] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:50.165] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:50.165] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:50.165] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:50.165] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:50.165] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:50.165] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:50.165] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:50.165] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:50.165] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:50.165] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:50.165] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:50.165] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:50.165] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:50.165] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:50.165] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:51.262] INFO: Test took 1097ms.
[13:18:51.264] INFO: The DUT currently contains the following objects:
[13:18:51.264] INFO: 2 TBM Cores tbm08c (2 ON)
[13:18:51.264] INFO: TBM Core alpha (0): 7 registers set
[13:18:51.264] INFO: TBM Core beta (1): 7 registers set
[13:18:51.264] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:18:51.264] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:51.264] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:51.264] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:51.264] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:51.264] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:51.264] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:51.264] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:51.264] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:51.264] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:51.264] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:51.264] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:51.264] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:51.264] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:51.264] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:51.264] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:51.264] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:52.363] INFO: Test took 1099ms.
[13:18:52.365] INFO: The DUT currently contains the following objects:
[13:18:52.365] INFO: 2 TBM Cores tbm08c (2 ON)
[13:18:52.365] INFO: TBM Core alpha (0): 7 registers set
[13:18:52.365] INFO: TBM Core beta (1): 7 registers set
[13:18:52.365] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:18:52.365] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:52.365] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:52.365] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:52.365] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:52.365] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:52.365] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:52.365] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:52.365] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:52.365] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:52.365] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:52.365] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:52.365] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:52.365] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:52.365] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:52.365] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:52.365] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:53.464] INFO: Test took 1099ms.
[13:18:53.466] INFO: The DUT currently contains the following objects:
[13:18:53.466] INFO: 2 TBM Cores tbm08c (2 ON)
[13:18:53.466] INFO: TBM Core alpha (0): 7 registers set
[13:18:53.466] INFO: TBM Core beta (1): 7 registers set
[13:18:53.466] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:18:53.466] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:53.466] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:53.466] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:53.466] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:53.466] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:53.466] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:53.466] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:53.466] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:53.466] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:53.466] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:53.466] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:53.466] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:53.466] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:53.466] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:53.466] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:53.466] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:54.564] INFO: Test took 1097ms.
[13:18:54.566] INFO: The DUT currently contains the following objects:
[13:18:54.566] INFO: 2 TBM Cores tbm08c (2 ON)
[13:18:54.566] INFO: TBM Core alpha (0): 7 registers set
[13:18:54.566] INFO: TBM Core beta (1): 7 registers set
[13:18:54.566] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[13:18:54.566] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:54.566] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:54.566] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:54.566] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:54.567] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:54.567] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:54.567] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:54.567] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:54.567] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:54.567] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:54.567] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:54.567] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:54.567] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:54.567] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:54.567] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:54.567] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[13:18:55.665] INFO: Test took 1098ms.
[13:18:55.672] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:22:09.889] INFO: Test took 194217ms.
[13:22:13.011] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:26.233] INFO: Test took 193222ms.
[13:25:29.533] INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:29.543] INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:29.554] INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:29.564] INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:29.574] INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:29.585] INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:29.595] INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:29.606] INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:29.616] INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:29.626] INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:29.637] INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:29.647] INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:29.658] INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:29.669] INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:29.678] INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:29.689] INFO: safety margin for low PH: adding 0, margin is now 20
[13:25:29.752] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C0.dat
[13:25:29.752] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C1.dat
[13:25:29.752] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C2.dat
[13:25:29.752] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C3.dat
[13:25:29.753] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C4.dat
[13:25:29.753] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C5.dat
[13:25:29.753] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C6.dat
[13:25:29.753] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C7.dat
[13:25:29.753] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C8.dat
[13:25:29.754] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C9.dat
[13:25:29.754] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C10.dat
[13:25:29.754] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C11.dat
[13:25:29.754] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C12.dat
[13:25:29.755] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C13.dat
[13:25:29.755] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C14.dat
[13:25:29.755] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/dacParameters35_C15.dat
[13:25:33.982] INFO: Test took 4222ms.
[13:25:38.513] INFO: Test took 4168ms.
[13:25:43.146] INFO: Test took 4274ms.
[13:25:43.521] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:44.427] INFO: Test took 907ms.
[13:25:44.433] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:45.526] INFO: Test took 1093ms.
[13:25:45.530] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:46.630] INFO: Test took 1100ms.
[13:25:46.635] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:47.728] INFO: Test took 1093ms.
[13:25:47.733] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:48.829] INFO: Test took 1097ms.
[13:25:48.834] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:49.932] INFO: Test took 1098ms.
[13:25:49.936] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:51.033] INFO: Test took 1097ms.
[13:25:51.038] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:52.136] INFO: Test took 1098ms.
[13:25:52.142] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:53.239] INFO: Test took 1097ms.
[13:25:53.244] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:54.337] INFO: Test took 1093ms.
[13:25:54.341] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:55.443] INFO: Test took 1102ms.
[13:25:55.449] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:56.545] INFO: Test took 1097ms.
[13:25:56.550] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:57.649] INFO: Test took 1100ms.
[13:25:57.654] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:58.750] INFO: Test took 1096ms.
[13:25:58.754] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:25:59.852] INFO: Test took 1098ms.
[13:25:59.857] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:00.955] INFO: Test took 1098ms.
[13:26:00.960] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:02.058] INFO: Test took 1099ms.
[13:26:02.065] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:03.175] INFO: Test took 1111ms.
[13:26:03.181] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:04.275] INFO: Test took 1094ms.
[13:26:04.280] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:05.378] INFO: Test took 1099ms.
[13:26:05.383] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:06.481] INFO: Test took 1098ms.
[13:26:06.486] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:07.588] INFO: Test took 1103ms.
[13:26:07.594] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:08.691] INFO: Test took 1098ms.
[13:26:08.695] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:09.792] INFO: Test took 1097ms.
[13:26:09.797] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:10.895] INFO: Test took 1098ms.
[13:26:10.901] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:11.001] INFO: Test took 1100ms.
[13:26:12.007] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:13.108] INFO: Test took 1101ms.
[13:26:13.114] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:14.210] INFO: Test took 1097ms.
[13:26:14.215] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:15.311] INFO: Test took 1096ms.
[13:26:15.316] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:16.415] INFO: Test took 1099ms.
[13:26:16.420] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:17.516] INFO: Test took 1097ms.
[13:26:17.522] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:18.616] INFO: Test took 1095ms.
[13:26:19.212] INFO: PixTestPhOptimization::doTest() done, duration: 474 seconds
[13:26:19.212] INFO: PH scale (per ROC): 70 75 78 90 82 82 65 80 75 69 79 71 78 75 80 80
[13:26:19.212] INFO: PH offset (per ROC): 166 155 157 149 166 157 162 142 162 174 167 161 152 167 160 159
[13:26:19.443] INFO: ######################################################################
[13:26:19.443] INFO: PixTestGainPedestal::fullTest() ntrig = 10
[13:26:19.443] INFO: ######################################################################
[13:26:19.461] INFO: scanning low vcal = 10
[13:26:23.188] INFO: Test took 3727ms.
[13:26:23.198] INFO: scanning low vcal = 20
[13:26:26.935] INFO: Test took 3737ms.
[13:26:26.943] INFO: scanning low vcal = 30
[13:26:30.698] INFO: Test took 3755ms.
[13:26:30.718] INFO: scanning low vcal = 40
[13:26:34.902] INFO: Test took 4184ms.
[13:26:35.031] INFO: scanning low vcal = 50
[13:26:39.302] INFO: Test took 4271ms.
[13:26:39.439] INFO: scanning low vcal = 60
[13:26:43.693] INFO: Test took 4254ms.
[13:26:43.834] INFO: scanning low vcal = 70
[13:26:48.095] INFO: Test took 4261ms.
[13:26:48.230] INFO: scanning low vcal = 80
[13:26:52.485] INFO: Test took 4255ms.
[13:26:52.617] INFO: scanning low vcal = 90
[13:26:56.906] INFO: Test took 4289ms.
[13:26:57.033] INFO: scanning low vcal = 100
[13:27:01.272] INFO: Test took 4239ms.
[13:27:01.408] INFO: scanning low vcal = 110
[13:27:05.688] INFO: Test took 4280ms.
[13:27:05.823] INFO: scanning low vcal = 120
[13:27:10.081] INFO: Test took 4258ms.
[13:27:10.220] INFO: scanning low vcal = 130
[13:27:14.598] INFO: Test took 4378ms.
[13:27:14.732] INFO: scanning low vcal = 140
[13:27:19.135] INFO: Test took 4403ms.
[13:27:19.272] INFO: scanning low vcal = 150
[13:27:23.543] INFO: Test took 4270ms.
[13:27:23.688] INFO: scanning low vcal = 160
[13:27:27.961] INFO: Test took 4273ms.
[13:27:28.115] INFO: scanning low vcal = 170
[13:27:32.452] INFO: Test took 4337ms.
[13:27:32.624] INFO: scanning low vcal = 180
[13:27:36.953] INFO: Test took 4329ms.
[13:27:37.094] INFO: scanning low vcal = 190
[13:27:41.335] INFO: Test took 4241ms.
[13:27:41.476] INFO: scanning low vcal = 200
[13:27:45.744] INFO: Test took 4268ms.
[13:27:45.888] INFO: scanning low vcal = 210
[13:27:50.288] INFO: Test took 4400ms.
[13:27:50.434] INFO: scanning low vcal = 220
[13:27:54.719] INFO: Test took 4285ms.
[13:27:54.856] INFO: scanning low vcal = 230
[13:27:59.177] INFO: Test took 4320ms.
[13:27:59.320] INFO: scanning low vcal = 240
[13:28:03.583] INFO: Test took 4263ms.
[13:28:03.723] INFO: scanning low vcal = 250
[13:28:08.009] INFO: Test took 4286ms.
[13:28:08.150] INFO: scanning high vcal = 30 (= 210 in low range)
[13:28:12.447] INFO: Test took 4297ms.
[13:28:12.585] INFO: scanning high vcal = 50 (= 350 in low range)
[13:28:16.854] INFO: Test took 4269ms.
[13:28:17.029] INFO: scanning high vcal = 70 (= 490 in low range)
[13:28:21.373] INFO: Test took 4344ms.
[13:28:21.514] INFO: scanning high vcal = 90 (= 630 in low range)
[13:28:25.830] INFO: Test took 4316ms.
[13:28:25.953] INFO: scanning high vcal = 200 (= 1400 in low range)
[13:28:30.196] INFO: Test took 4243ms.
[13:28:31.096] INFO: PixTestGainPedestal::measure() done
[13:29:25.489] INFO: PixTestGainPedestal::fit() done
[13:29:25.489] INFO: non-linearity mean: 0.950 0.960 0.960 0.959 0.953 0.951 0.953 0.949 0.957 0.955 0.958 0.956 0.960 0.955 0.956 0.957
[13:29:25.490] INFO: non-linearity RMS: 0.006 0.006 0.005 0.005 0.008 0.007 0.007 0.006 0.005 0.006 0.007 0.006 0.005 0.007 0.005 0.005
[13:29:25.490] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/phCalibrationFitErr35_C0.dat
[13:29:25.522] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/phCalibrationFitErr35_C1.dat
[13:29:25.548] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/phCalibrationFitErr35_C2.dat
[13:29:25.574] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/phCalibrationFitErr35_C3.dat
[13:29:25.600] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/phCalibrationFitErr35_C4.dat
[13:29:25.626] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/phCalibrationFitErr35_C5.dat
[13:29:25.653] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/phCalibrationFitErr35_C6.dat
[13:29:25.679] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/phCalibrationFitErr35_C7.dat
[13:29:25.705] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/phCalibrationFitErr35_C8.dat
[13:29:25.730] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/phCalibrationFitErr35_C9.dat
[13:29:25.757] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/phCalibrationFitErr35_C10.dat
[13:29:25.783] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/phCalibrationFitErr35_C11.dat
[13:29:25.808] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/phCalibrationFitErr35_C12.dat
[13:29:25.834] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/phCalibrationFitErr35_C13.dat
[13:29:25.858] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/phCalibrationFitErr35_C14.dat
[13:29:25.884] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/phCalibrationFitErr35_C15.dat
[13:29:25.909] INFO: PixTestGainPedestal::doTest() done, duration: 186 seconds
[13:29:25.917] INFO: readReadbackCal: /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C15.dat
[13:29:25.919] INFO: PixTestReadback::doTest() start.
[13:29:25.920] INFO: PixTestReadback::RES sent once
[13:29:42.550] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C0.dat
[13:29:42.550] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C1.dat
[13:29:42.551] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C2.dat
[13:29:42.551] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C3.dat
[13:29:42.551] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C4.dat
[13:29:42.551] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C5.dat
[13:29:42.551] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C6.dat
[13:29:42.551] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C7.dat
[13:29:42.551] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C8.dat
[13:29:42.552] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C9.dat
[13:29:42.552] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C10.dat
[13:29:42.552] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C11.dat
[13:29:42.552] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C12.dat
[13:29:42.552] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C13.dat
[13:29:42.552] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C14.dat
[13:29:42.552] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C15.dat
[13:29:42.600] INFO: PixTestPattern:: pg_setup set to default.
[13:29:42.601] INFO: PixTestReadback::RES sent once
[13:29:59.127] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C0.dat
[13:29:59.127] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C1.dat
[13:29:59.127] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C2.dat
[13:29:59.127] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C3.dat
[13:29:59.127] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C4.dat
[13:29:59.128] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C5.dat
[13:29:59.128] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C6.dat
[13:29:59.128] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C7.dat
[13:29:59.128] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C8.dat
[13:29:59.128] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C9.dat
[13:29:59.128] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C10.dat
[13:29:59.128] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C11.dat
[13:29:59.128] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C12.dat
[13:29:59.129] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C13.dat
[13:29:59.129] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C14.dat
[13:29:59.129] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C15.dat
[13:29:59.178] INFO: PixTestPattern:: pg_setup set to default.
[13:29:59.178] INFO: PixTestReadback::RES sent once
[13:30:11.984] INFO: PixTestPattern:: pg_setup set to default.
[13:30:11.984] INFO: Vbg will be calibrated using Vd calibration
[13:30:11.984] INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 153.5calibrated Vbg = 1.24722 :::*/*/*/*/
[13:30:11.984] INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 153.4calibrated Vbg = 1.25292 :::*/*/*/*/
[13:30:11.984] INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 147.9calibrated Vbg = 1.25565 :::*/*/*/*/
[13:30:11.984] INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 155.4calibrated Vbg = 1.25189 :::*/*/*/*/
[13:30:11.984] INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 149.7calibrated Vbg = 1.26529 :::*/*/*/*/
[13:30:11.984] INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 156.4calibrated Vbg = 1.25989 :::*/*/*/*/
[13:30:11.984] INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 157.2calibrated Vbg = 1.26441 :::*/*/*/*/
[13:30:11.984] INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 151calibrated Vbg = 1.26686 :::*/*/*/*/
[13:30:11.984] INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 152.6calibrated Vbg = 1.26581 :::*/*/*/*/
[13:30:11.985] INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 155.3calibrated Vbg = 1.26906 :::*/*/*/*/
[13:30:11.985] INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 154.2calibrated Vbg = 1.26999 :::*/*/*/*/
[13:30:11.985] INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 162.6calibrated Vbg = 1.26028 :::*/*/*/*/
[13:30:11.985] INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 155.3calibrated Vbg = 1.25213 :::*/*/*/*/
[13:30:11.985] INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 162calibrated Vbg = 1.24141 :::*/*/*/*/
[13:30:11.985] INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 155.2calibrated Vbg = 1.24495 :::*/*/*/*/
[13:30:11.985] INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 157.6calibrated Vbg = 1.24507 :::*/*/*/*/
[13:30:11.988] INFO: PixTestReadback::RES sent once
[13:34:07.639] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C0.dat
[13:34:07.639] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C1.dat
[13:34:07.639] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C2.dat
[13:34:07.639] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C3.dat
[13:34:07.639] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C4.dat
[13:34:07.639] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C5.dat
[13:34:07.639] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C6.dat
[13:34:07.639] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C7.dat
[13:34:07.640] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C8.dat
[13:34:07.640] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C9.dat
[13:34:07.640] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C10.dat
[13:34:07.640] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C11.dat
[13:34:07.640] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C12.dat
[13:34:07.640] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C13.dat
[13:34:07.640] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C14.dat
[13:34:07.640] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4560_FullQualification_2015-08-04_11h20m_1438683615/001_Fulltest_m20/readbackCal_C15.dat
[13:34:07.689] INFO: PixTestPattern:: pg_setup set to default.
[13:34:07.692] INFO: PixTestReadback::doTest() done
[13:34:07.712] INFO: enter test to run
[13:34:07.712] INFO: test: q no parameter change
[13:34:07.897] QUIET: Connection to board 173 closed.
[13:34:07.900] INFO: pXar: this is the end, my friend