[00:01:09.910] INFO: *** Welcome to pxar ***
[00:01:09.910] INFO: *** Today: 2015/08/15
[00:01:09.910] INFO: readRocDacs: /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters_C15.dat
[00:01:09.911] INFO: readTbmDacs: /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/tbmParameters_C0b.dat
[00:01:09.911] INFO: readMaskFile: /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/defaultMaskFile.dat
[00:01:09.911] INFO: readTrimFile: /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/trimParameters_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/trimParameters_C15.dat
[00:01:10.010] INFO: clk: 4
[00:01:10.010] INFO: ctr: 4
[00:01:10.010] INFO: sda: 19
[00:01:10.010] INFO: tin: 9
[00:01:10.010] INFO: level: 15
[00:01:10.010] INFO: triggerdelay: 0
[00:01:10.011] QUIET: Instanciating API for pxar prod-01+69~g35a931b
[00:01:10.011] INFO: Log level: INFO
[00:01:10.024] INFO: Found DTB DTB_WZ4WY6
[00:01:10.037] QUIET: Connection to board DTB_WZ4WY6 opened.
[00:01:10.040] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 175
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WZ4WY6
MAC address: 40D8551180AF
Hostname: pixelDTB175
Comment:
------------------------------------------------------
[00:01:10.043] INFO: RPC call hashes of host and DTB match: 447413373
[00:01:11.786] INFO: DUT info:
[00:01:11.786] INFO: The DUT currently contains the following objects:
[00:01:11.786] INFO: 2 TBM Cores tbm08c (2 ON)
[00:01:11.786] INFO: TBM Core alpha (0): 7 registers set
[00:01:11.786] INFO: TBM Core beta (1): 7 registers set
[00:01:11.786] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[00:01:11.786] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[00:01:11.786] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[00:01:11.786] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[00:01:11.786] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[00:01:11.786] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[00:01:11.786] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[00:01:11.786] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[00:01:11.787] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[00:01:11.787] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[00:01:11.787] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[00:01:11.787] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[00:01:11.787] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[00:01:11.787] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[00:01:11.787] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[00:01:11.787] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[00:01:11.787] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[00:01:12.188] INFO: enter 'restricted' command line mode
[00:01:12.188] INFO: enter test to run
[00:01:12.188] INFO: test: pretest no parameter change
[00:01:12.188] INFO: running: pretest
[00:01:12.199] INFO: ######################################################################
[00:01:12.199] INFO: PixTestPretest::doTest()
[00:01:12.199] INFO: ######################################################################
[00:01:12.201] INFO: ----------------------------------------------------------------------
[00:01:12.201] INFO: PixTestPretest::programROC()
[00:01:12.201] INFO: ----------------------------------------------------------------------
[00:01:30.224] INFO: PixTestPretest::programROC() done: ROCs are all programmable
[00:01:30.224] INFO: IA differences per ROC: 15.3 20.1 17.7 19.3 18.5 17.7 18.5 17.7 16.9 16.9 19.3 17.7 17.7 18.5 18.5 19.3
[00:01:30.336] INFO: ----------------------------------------------------------------------
[00:01:30.336] INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[00:01:30.336] INFO: ----------------------------------------------------------------------
[00:01:50.023] INFO: PixTestPretest::setVana() done, Module Ia 385.1 mA = 24.0688 mA/ROC
[00:01:50.025] INFO: ----------------------------------------------------------------------
[00:01:50.025] INFO: PixTestPreTest::setTimings()
[00:01:50.025] INFO: ----------------------------------------------------------------------
[00:01:51.018] INFO: ----------------------------------------------------------------------
[00:01:51.018] INFO: Current timings are good. No timing scan needed.
[00:01:51.018] INFO: ----------------------------------------------------------------------
[00:01:51.018] INFO: Test took 993 ms.
[00:01:51.018] INFO: PixTestPretest::setTimings() done.
[00:01:51.225] INFO: ----------------------------------------------------------------------
[00:01:51.225] INFO: PixTestPretest::findWorkingPixel()
[00:01:51.225] INFO: ----------------------------------------------------------------------
[00:01:58.905] INFO: Test took 7671ms.
[00:01:59.243] INFO: Found working pixel in all ROCs: col/row = 12/22
[00:01:59.293] INFO: ----------------------------------------------------------------------
[00:01:59.293] INFO: PixTestPretest::setVthrCompCalDel()
[00:01:59.293] INFO: ----------------------------------------------------------------------
[00:02:06.000] INFO: Test took 7697ms.
[00:02:07.366] INFO: PixTestPretest::setVthrCompCalDel() done
[00:02:07.366] INFO: CalDel: 124 149 145 125 123 153 134 111 116 139 135 122 132 124 148 125
[00:02:07.366] INFO: VthrComp: 51 51 51 51 51 51 51 51 51 53 51 51 51 51 51 51
[00:02:07.371] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters_C0.dat
[00:02:07.371] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters_C1.dat
[00:02:07.371] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters_C2.dat
[00:02:07.371] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters_C3.dat
[00:02:07.372] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters_C4.dat
[00:02:07.372] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters_C5.dat
[00:02:07.372] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters_C6.dat
[00:02:07.372] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters_C7.dat
[00:02:07.372] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters_C8.dat
[00:02:07.372] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters_C9.dat
[00:02:07.373] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters_C10.dat
[00:02:07.373] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters_C11.dat
[00:02:07.373] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters_C12.dat
[00:02:07.373] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters_C13.dat
[00:02:07.373] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters_C14.dat
[00:02:07.373] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters_C15.dat
[00:02:07.374] INFO: write tbm parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/tbmParameters_C0a.dat
[00:02:07.374] INFO: write tbm parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/tbmParameters_C0b.dat
[00:02:07.374] INFO: PixTestPretest::doTest() done, duration: 55 seconds
[00:02:07.466] INFO: enter test to run
[00:02:07.466] INFO: test: fulltest no parameter change
[00:02:07.466] INFO: running: fulltest
[00:02:07.466] INFO: ######################################################################
[00:02:07.466] INFO: PixTestFullTest::doTest()
[00:02:07.466] INFO: ######################################################################
[00:02:07.467] INFO: ######################################################################
[00:02:07.467] INFO: PixTestAlive::doTest()
[00:02:07.467] INFO: ######################################################################
[00:02:07.469] INFO: ----------------------------------------------------------------------
[00:02:07.469] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[00:02:07.469] INFO: ----------------------------------------------------------------------
[00:02:11.105] INFO: Test took 3634ms.
[00:02:11.141] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:02:11.409] INFO: PixTestAlive::aliveTest() done
[00:02:11.410] INFO: number of dead pixels (per ROC): 0 0 0 3 2 0 0 0 0 0 0 0 0 0 0 0
[00:02:11.412] INFO: ----------------------------------------------------------------------
[00:02:11.412] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[00:02:11.412] INFO: ----------------------------------------------------------------------
[00:02:14.359] INFO: Test took 2945ms.
[00:02:14.366] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:02:14.367] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[00:02:14.628] INFO: PixTestAlive::maskTest() done
[00:02:14.628] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[00:02:14.631] INFO: ----------------------------------------------------------------------
[00:02:14.631] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[00:02:14.631] INFO: ----------------------------------------------------------------------
[00:02:18.277] INFO: Test took 3644ms.
[00:02:18.307] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:02:18.567] INFO: PixTestAlive::addressDecodingTest() done
[00:02:18.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
[00:02:18.568] INFO: PixTestAlive::doTest() done, duration: 11 seconds
[00:02:18.580] INFO: ######################################################################
[00:02:18.580] INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[00:02:18.580] INFO: ######################################################################
[00:02:18.584] INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30/5) hits flags = 2 (plus default)
[00:02:18.605] INFO: dacScan step from 0 .. 29
[00:02:18.605] INFO: dacScan split into 1 runs with ntrig = 5
[00:02:18.605] INFO: run 1 of 1
[00:02:38.511] INFO: Test took 19906ms.
[00:02:38.579] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:02:38.579] INFO: dacScan step from 30 .. 59
[00:02:38.579] INFO: dacScan split into 1 runs with ntrig = 5
[00:02:38.579] INFO: run 1 of 1
[00:02:58.832] INFO: Test took 20252ms.
[00:02:58.918] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:02:58.922] INFO: dacScan step from 60 .. 89
[00:02:58.922] INFO: dacScan split into 1 runs with ntrig = 5
[00:02:58.922] INFO: run 1 of 1
[00:03:24.221] INFO: Test took 25299ms.
[00:03:24.567] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:03:24.641] INFO: dacScan step from 90 .. 119
[00:03:24.641] INFO: dacScan split into 1 runs with ntrig = 5
[00:03:24.641] INFO: run 1 of 1
[00:03:52.944] INFO: Test took 28303ms.
[00:03:53.357] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:03:53.453] INFO: dacScan step from 120 .. 149
[00:03:53.453] INFO: dacScan split into 1 runs with ntrig = 5
[00:03:53.453] INFO: run 1 of 1
[00:04:18.630] INFO: Test took 25177ms.
[00:04:19.089] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:04:57.022] INFO: PixTestBBMap::doTest() done, duration: 158 seconds
[00:04:57.022] INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 2 0 1 0 0 0 0 0 0 6
[00:04:57.022] INFO: separation cut (per ROC): 108 97 105 108 108 102 94 118 115 106 107 113 108 114 100 105
[00:04:57.124] INFO: ######################################################################
[00:04:57.124] INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = 1
[00:04:57.124] INFO: ######################################################################
[00:04:57.124] INFO: ----------------------------------------------------------------------
[00:04:57.124] INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = 1
[00:04:57.124] INFO: ----------------------------------------------------------------------
[00:04:57.124] INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (-1/1) hits flags = 16 (plus default)
[00:04:57.139] INFO: dacScan split into 50 runs with ntrig = 1
[00:04:57.139] INFO: run 1 of 50
[00:05:47.664] INFO: Test took 50525ms.
[00:05:47.993] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:05:48.144] INFO: run 2 of 50
[00:06:38.601] INFO: Test took 50457ms.
[00:06:38.937] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:06:39.089] INFO: run 3 of 50
[00:07:29.495] INFO: Test took 50406ms.
[00:07:29.828] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:07:29.971] INFO: run 4 of 50
[00:08:20.299] INFO: Test took 50328ms.
[00:08:20.630] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:08:20.776] INFO: run 5 of 50
[00:09:11.010] INFO: Test took 50233ms.
[00:09:11.343] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:09:11.488] INFO: run 6 of 50
[00:10:01.800] INFO: Test took 50312ms.
[00:10:02.131] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:10:02.277] INFO: run 7 of 50
[00:10:52.635] INFO: Test took 50358ms.
[00:10:52.969] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:10:53.116] INFO: run 8 of 50
[00:11:43.628] INFO: Test took 50512ms.
[00:11:43.957] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:11:44.106] INFO: run 9 of 50
[00:12:34.626] INFO: Test took 50520ms.
[00:12:34.961] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:12:35.115] INFO: run 10 of 50
[00:13:25.649] INFO: Test took 50534ms.
[00:13:25.984] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:13:26.128] INFO: run 11 of 50
[00:14:16.773] INFO: Test took 50645ms.
[00:14:17.103] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:14:17.251] INFO: run 12 of 50
[00:15:07.810] INFO: Test took 50559ms.
[00:15:08.141] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:15:08.290] INFO: run 13 of 50
[00:15:58.956] INFO: Test took 50666ms.
[00:15:59.283] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:15:59.430] INFO: run 14 of 50
[00:16:49.966] INFO: Test took 50536ms.
[00:16:50.298] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:16:50.443] INFO: run 15 of 50
[00:17:40.921] INFO: Test took 50478ms.
[00:17:41.252] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:17:41.397] INFO: run 16 of 50
[00:18:31.885] INFO: Test took 50487ms.
[00:18:32.216] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:18:32.363] INFO: run 17 of 50
[00:19:22.885] INFO: Test took 50522ms.
[00:19:23.226] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:19:23.371] INFO: run 18 of 50
[00:20:13.840] INFO: Test took 50469ms.
[00:20:14.174] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:20:14.377] INFO: run 19 of 50
[00:21:04.862] INFO: Test took 50484ms.
[00:21:05.197] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:21:05.349] INFO: run 20 of 50
[00:21:55.832] INFO: Test took 50483ms.
[00:21:56.162] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:21:56.306] INFO: run 21 of 50
[00:22:46.825] INFO: Test took 50519ms.
[00:22:47.149] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:22:47.298] INFO: run 22 of 50
[00:23:37.942] INFO: Test took 50643ms.
[00:23:38.270] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:23:38.418] INFO: run 23 of 50
[00:24:29.145] INFO: Test took 50727ms.
[00:24:29.464] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:24:29.609] INFO: run 24 of 50
[00:25:20.236] INFO: Test took 50627ms.
[00:25:20.556] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:25:20.705] INFO: run 25 of 50
[00:26:11.345] INFO: Test took 50640ms.
[00:26:11.675] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:26:11.825] INFO: run 26 of 50
[00:27:02.482] INFO: Test took 50657ms.
[00:27:02.806] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:27:02.954] INFO: run 27 of 50
[00:27:53.496] INFO: Test took 50542ms.
[00:27:53.813] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:27:53.959] INFO: run 28 of 50
[00:28:44.421] INFO: Test took 50462ms.
[00:28:44.741] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:28:44.886] INFO: run 29 of 50
[00:29:35.413] INFO: Test took 50527ms.
[00:29:35.738] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:29:35.889] INFO: run 30 of 50
[00:30:26.343] INFO: Test took 50454ms.
[00:30:26.659] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:30:26.805] INFO: run 31 of 50
[00:31:17.284] INFO: Test took 50479ms.
[00:31:17.602] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:31:17.748] INFO: run 32 of 50
[00:32:08.296] INFO: Test took 50548ms.
[00:32:08.607] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:32:08.751] INFO: run 33 of 50
[00:32:59.336] INFO: Test took 50585ms.
[00:32:59.656] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:32:59.801] INFO: run 34 of 50
[00:33:50.371] INFO: Test took 50570ms.
[00:33:50.698] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:33:50.847] INFO: run 35 of 50
[00:34:41.583] INFO: Test took 50736ms.
[00:34:41.907] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:34:42.054] INFO: run 36 of 50
[00:35:32.699] INFO: Test took 50645ms.
[00:35:33.016] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:35:33.160] INFO: run 37 of 50
[00:36:23.820] INFO: Test took 50660ms.
[00:36:24.143] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:36:24.290] INFO: run 38 of 50
[00:37:14.953] INFO: Test took 50663ms.
[00:37:15.267] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:37:15.416] INFO: run 39 of 50
[00:38:06.114] INFO: Test took 50698ms.
[00:38:06.432] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:38:06.576] INFO: run 40 of 50
[00:38:57.165] INFO: Test took 50589ms.
[00:38:57.493] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:38:57.644] INFO: run 41 of 50
[00:39:48.304] INFO: Test took 50660ms.
[00:39:48.628] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:39:48.775] INFO: run 42 of 50
[00:40:39.333] INFO: Test took 50558ms.
[00:40:39.660] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:40:39.805] INFO: run 43 of 50
[00:41:30.372] INFO: Test took 50567ms.
[00:41:30.691] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:41:30.839] INFO: run 44 of 50
[00:42:21.335] INFO: Test took 50496ms.
[00:42:21.662] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:42:21.807] INFO: run 45 of 50
[00:43:12.282] INFO: Test took 50475ms.
[00:43:12.603] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:43:12.751] INFO: run 46 of 50
[00:44:03.401] INFO: Test took 50650ms.
[00:44:03.722] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:44:03.871] INFO: run 47 of 50
[00:44:54.515] INFO: Test took 50644ms.
[00:44:54.836] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:44:54.980] INFO: run 48 of 50
[00:45:45.510] INFO: Test took 50530ms.
[00:45:45.862] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:45:46.021] INFO: run 49 of 50
[00:46:36.752] INFO: Test took 50731ms.
[00:46:37.088] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:46:37.250] INFO: run 50 of 50
[00:47:27.846] INFO: Test took 50596ms.
[00:47:28.164] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:47:28.307] INFO: dumping ASCII scurve output file: SCurveData
[00:47:30.171] INFO: dumping ASCII scurve output file: SCurveData
[00:47:32.208] INFO: dumping ASCII scurve output file: SCurveData
[00:47:34.199] INFO: dumping ASCII scurve output file: SCurveData
[00:47:36.148] INFO: dumping ASCII scurve output file: SCurveData
[00:47:38.203] INFO: dumping ASCII scurve output file: SCurveData
[00:47:40.436] INFO: dumping ASCII scurve output file: SCurveData
[00:47:42.578] INFO: dumping ASCII scurve output file: SCurveData
[00:47:44.849] INFO: dumping ASCII scurve output file: SCurveData
[00:47:47.077] INFO: dumping ASCII scurve output file: SCurveData
[00:47:49.403] INFO: dumping ASCII scurve output file: SCurveData
[00:47:51.710] INFO: dumping ASCII scurve output file: SCurveData
[00:47:53.830] INFO: dumping ASCII scurve output file: SCurveData
[00:47:56.571] INFO: dumping ASCII scurve output file: SCurveData
[00:47:59.100] INFO: dumping ASCII scurve output file: SCurveData
[00:48:01.502] INFO: dumping ASCII scurve output file: SCurveData
[00:48:03.866] INFO: PixTestScurves::scurves() done
[00:48:03.866] INFO: Vcal mean: 105.81 88.26 102.15 105.56 94.39 92.35 96.85 99.94 113.68 109.31 108.09 112.64 108.03 104.72 95.64 107.13
[00:48:03.866] INFO: Vcal RMS: 4.78 5.85 5.73 5.86 5.33 5.25 5.19 5.73 5.51 6.67 5.39 5.69 5.48 5.86 5.79 5.34
[00:48:03.866] INFO: PixTestScurves::fullTest() done, duration: 2586 seconds
[00:48:03.969] INFO: ######################################################################
[00:48:03.970] INFO: PixTestTrim::doTest()
[00:48:03.970] INFO: ######################################################################
[00:48:03.971] INFO: ----------------------------------------------------------------------
[00:48:03.971] INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[00:48:03.971] INFO: ----------------------------------------------------------------------
[00:48:04.118] INFO: ---> VthrComp thr map (minimal VthrComp)
[00:48:04.118] INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[00:48:04.133] INFO: dacScan step from 0 .. 19
[00:48:04.133] INFO: dacScan split into 1 runs with ntrig = 5
[00:48:04.133] INFO: run 1 of 1
[00:48:18.871] INFO: Test took 14738ms.
[00:48:18.916] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:48:18.916] INFO: dacScan step from 20 .. 39
[00:48:18.916] INFO: dacScan split into 1 runs with ntrig = 5
[00:48:18.916] INFO: run 1 of 1
[00:48:33.652] INFO: Test took 14736ms.
[00:48:33.699] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:48:33.699] INFO: dacScan step from 40 .. 59
[00:48:33.699] INFO: dacScan split into 1 runs with ntrig = 5
[00:48:33.699] INFO: run 1 of 1
[00:48:48.512] INFO: Test took 14813ms.
[00:48:48.560] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:48:48.560] INFO: dacScan step from 60 .. 79
[00:48:48.560] INFO: dacScan split into 1 runs with ntrig = 5
[00:48:48.560] INFO: run 1 of 1
[00:49:03.121] INFO: Test took 14560ms.
[00:49:03.163] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:49:03.163] INFO: dacScan step from 80 .. 99
[00:49:03.163] INFO: dacScan split into 1 runs with ntrig = 5
[00:49:03.163] INFO: run 1 of 1
[00:49:17.589] INFO: Test took 14426ms.
[00:49:17.651] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:49:17.655] INFO: dacScan step from 100 .. 119
[00:49:17.655] INFO: dacScan split into 1 runs with ntrig = 5
[00:49:17.655] INFO: run 1 of 1
[00:49:34.009] INFO: Test took 16354ms.
[00:49:34.237] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:49:34.284] INFO: dacScan step from 120 .. 139
[00:49:34.284] INFO: dacScan split into 1 runs with ntrig = 5
[00:49:34.284] INFO: run 1 of 1
[00:49:52.484] INFO: Test took 18200ms.
[00:49:52.751] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:49:52.813] INFO: dacScan step from 140 .. 159
[00:49:52.813] INFO: dacScan split into 1 runs with ntrig = 5
[00:49:52.813] INFO: run 1 of 1
[00:50:08.611] INFO: Test took 15797ms.
[00:50:08.748] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:50:43.496] INFO: ROC 0 VthrComp = 105
[00:50:43.497] INFO: ROC 1 VthrComp = 86
[00:50:43.497] INFO: ROC 2 VthrComp = 98
[00:50:43.497] INFO: ROC 3 VthrComp = 106
[00:50:43.497] INFO: ROC 4 VthrComp = 98
[00:50:43.497] INFO: ROC 5 VthrComp = 90
[00:50:43.497] INFO: ROC 6 VthrComp = 94
[00:50:43.498] INFO: ROC 7 VthrComp = 96
[00:50:43.498] INFO: ROC 8 VthrComp = 105
[00:50:43.498] INFO: ROC 9 VthrComp = 100
[00:50:43.498] INFO: ROC 10 VthrComp = 105
[00:50:43.499] INFO: ROC 11 VthrComp = 108
[00:50:43.499] INFO: ROC 12 VthrComp = 103
[00:50:43.499] INFO: ROC 13 VthrComp = 107
[00:50:43.499] INFO: ROC 14 VthrComp = 95
[00:50:43.500] INFO: ROC 15 VthrComp = 105
[00:50:43.500] INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[00:50:43.500] INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[00:50:43.515] INFO: dacScan step from 0 .. 19
[00:50:43.515] INFO: dacScan split into 1 runs with ntrig = 5
[00:50:43.515] INFO: run 1 of 1
[00:50:57.848] INFO: Test took 14333ms.
[00:50:57.894] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:50:57.894] INFO: dacScan step from 20 .. 39
[00:50:57.894] INFO: dacScan split into 1 runs with ntrig = 5
[00:50:57.894] INFO: run 1 of 1
[00:51:12.500] INFO: Test took 14606ms.
[00:51:12.553] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:51:12.559] INFO: dacScan step from 40 .. 59
[00:51:12.559] INFO: dacScan split into 1 runs with ntrig = 5
[00:51:12.559] INFO: run 1 of 1
[00:51:30.103] INFO: Test took 17544ms.
[00:51:30.355] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:51:30.415] INFO: dacScan step from 60 .. 79
[00:51:30.415] INFO: dacScan split into 1 runs with ntrig = 5
[00:51:30.415] INFO: run 1 of 1
[00:51:50.092] INFO: Test took 19677ms.
[00:51:50.418] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:51:50.497] INFO: dacScan step from 80 .. 99
[00:51:50.497] INFO: dacScan split into 1 runs with ntrig = 5
[00:51:50.497] INFO: run 1 of 1
[00:52:10.359] INFO: Test took 19862ms.
[00:52:10.699] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:52:10.776] INFO: dacScan step from 100 .. 119
[00:52:10.777] INFO: dacScan split into 1 runs with ntrig = 5
[00:52:10.777] INFO: run 1 of 1
[00:52:30.688] INFO: Test took 19911ms.
[00:52:30.983] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:52:31.050] INFO: dacScan step from 120 .. 139
[00:52:31.050] INFO: dacScan split into 1 runs with ntrig = 5
[00:52:31.050] INFO: run 1 of 1
[00:52:50.963] INFO: Test took 19912ms.
[00:52:51.234] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:52:51.308] INFO: dacScan step from 140 .. 159
[00:52:51.308] INFO: dacScan split into 1 runs with ntrig = 5
[00:52:51.309] INFO: run 1 of 1
[00:53:11.144] INFO: Test took 19835ms.
[00:53:11.433] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:53:52.093] INFO: roc 0 with ID = 0 has maximal Vcal 59.2496 for pixel 0/39 mean/min/max = 46.8402/34.4236/59.2567
[00:53:52.094] INFO: roc 1 with ID = 1 has maximal Vcal 61.6862 for pixel 15/1 mean/min/max = 46.9317/32.1174/61.7461
[00:53:52.094] INFO: roc 2 with ID = 2 has maximal Vcal 59.5298 for pixel 0/75 mean/min/max = 45.6036/31.5421/59.6652
[00:53:52.095] INFO: roc 3 with ID = 3 has maximal Vcal 58.5572 for pixel 16/67 mean/min/max = 46.3191/34.0405/58.5978
[00:53:52.095] INFO: roc 4 with ID = 4 has maximal Vcal 57.1906 for pixel 18/79 mean/min/max = 44.4628/31.5204/57.4052
[00:53:52.096] INFO: roc 5 with ID = 5 has maximal Vcal 60.4326 for pixel 31/3 mean/min/max = 47.082/33.7008/60.4632
[00:53:52.096] INFO: roc 6 with ID = 6 has maximal Vcal 57.8079 for pixel 8/5 mean/min/max = 45.4953/33.0506/57.9401
[00:53:52.097] INFO: roc 7 with ID = 7 has maximal Vcal 61.1515 for pixel 11/1 mean/min/max = 46.7113/32.2704/61.1521
[00:53:52.097] INFO: roc 8 with ID = 8 has maximal Vcal 65.1717 for pixel 18/79 mean/min/max = 49.7635/34.0812/65.4459
[00:53:52.098] INFO: roc 9 with ID = 9 has maximal Vcal 65.7721 for pixel 26/12 mean/min/max = 49.5903/33.3078/65.8727
[00:53:52.098] INFO: roc 10 with ID = 10 has maximal Vcal 60.7993 for pixel 42/4 mean/min/max = 47.9306/34.9701/60.8911
[00:53:52.099] INFO: roc 11 with ID = 11 has maximal Vcal 64.7779 for pixel 10/79 mean/min/max = 49.8899/34.9341/64.8457
[00:53:52.099] INFO: roc 12 with ID = 12 has maximal Vcal 60.4157 for pixel 51/1 mean/min/max = 46.8086/33.2004/60.4169
[00:53:52.099] INFO: roc 13 with ID = 13 has maximal Vcal 61.2343 for pixel 6/2 mean/min/max = 47.4609/33.5949/61.3269
[00:53:52.100] INFO: roc 14 with ID = 14 has maximal Vcal 59.8117 for pixel 16/1 mean/min/max = 46.0957/32.235/59.9563
[00:53:52.100] INFO: roc 15 with ID = 15 has maximal Vcal 61.2107 for pixel 2/66 mean/min/max = 47.9462/34.4682/61.4241
[00:53:52.100] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[00:55:13.098] INFO: Test took 80998ms.
[00:55:15.269] INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[00:55:15.284] INFO: dacScan step from 0 .. 19
[00:55:15.284] INFO: dacScan split into 2 runs with ntrig = 5
[00:55:15.284] INFO: run 1 of 2
[00:55:29.807] INFO: Test took 14523ms.
[00:55:29.867] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:55:29.870] INFO: run 2 of 2
[00:55:44.409] INFO: Test took 14539ms.
[00:55:44.461] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:55:44.466] INFO: dacScan step from 20 .. 39
[00:55:44.466] INFO: dacScan split into 2 runs with ntrig = 5
[00:55:44.466] INFO: run 1 of 2
[00:56:01.521] INFO: Test took 17054ms.
[00:56:01.733] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:56:01.780] INFO: run 2 of 2
[00:56:18.787] INFO: Test took 17007ms.
[00:56:19.008] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:56:19.055] INFO: dacScan step from 40 .. 59
[00:56:19.056] INFO: dacScan split into 2 runs with ntrig = 5
[00:56:19.056] INFO: run 1 of 2
[00:56:38.797] INFO: Test took 19741ms.
[00:56:39.075] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:56:39.144] INFO: run 2 of 2
[00:56:58.967] INFO: Test took 19823ms.
[00:56:59.270] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:56:59.343] INFO: dacScan step from 60 .. 79
[00:56:59.343] INFO: dacScan split into 2 runs with ntrig = 5
[00:56:59.343] INFO: run 1 of 2
[00:57:19.074] INFO: Test took 19731ms.
[00:57:19.365] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:57:19.432] INFO: run 2 of 2
[00:57:39.290] INFO: Test took 19858ms.
[00:57:39.593] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:57:39.671] INFO: dacScan step from 80 .. 99
[00:57:39.671] INFO: dacScan split into 2 runs with ntrig = 5
[00:57:39.671] INFO: run 1 of 2
[00:57:59.295] INFO: Test took 19624ms.
[00:57:59.599] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:57:59.670] INFO: run 2 of 2
[00:58:19.431] INFO: Test took 19761ms.
[00:58:19.726] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:58:19.798] INFO: dacScan step from 100 .. 119
[00:58:19.798] INFO: dacScan split into 2 runs with ntrig = 5
[00:58:19.798] INFO: run 1 of 2
[00:58:39.528] INFO: Test took 19730ms.
[00:58:39.804] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:58:39.873] INFO: run 2 of 2
[00:58:59.612] INFO: Test took 19739ms.
[00:58:59.908] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:58:59.982] INFO: dacScan step from 120 .. 139
[00:58:59.982] INFO: dacScan split into 2 runs with ntrig = 5
[00:58:59.982] INFO: run 1 of 2
[00:59:19.757] INFO: Test took 19774ms.
[00:59:20.071] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:59:20.137] INFO: run 2 of 2
[00:59:39.795] INFO: Test took 19658ms.
[00:59:40.129] INFO: Fetched DAQ statistics. Counters are being reset now.
[00:59:40.204] INFO: dacScan step from 140 .. 159
[00:59:40.204] INFO: dacScan split into 2 runs with ntrig = 5
[00:59:40.204] INFO: run 1 of 2
[00:59:59.928] INFO: Test took 19724ms.
[01:00:00.274] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:00:00.358] INFO: run 2 of 2
[01:00:20.198] INFO: Test took 19840ms.
[01:00:20.529] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:00:20.602] INFO: dacScan step from 160 .. 179
[01:00:20.602] INFO: dacScan split into 2 runs with ntrig = 5
[01:00:20.602] INFO: run 1 of 2
[01:00:40.606] INFO: Test took 20004ms.
[01:00:40.903] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:00:40.978] INFO: run 2 of 2
[01:01:00.810] INFO: Test took 19832ms.
[01:01:01.142] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:01:01.210] INFO: dacScan step from 180 .. 199
[01:01:01.210] INFO: dacScan split into 2 runs with ntrig = 5
[01:01:01.210] INFO: run 1 of 2
[01:01:21.764] INFO: Test took 20554ms.
[01:01:22.075] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:01:22.147] INFO: run 2 of 2
[01:01:42.867] INFO: Test took 20720ms.
[01:01:43.167] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:02:25.145] INFO: ---> TrimStepCorr4 extremal thresholds: 0.018273 .. 255.000000
[01:02:25.300] INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 255 (20/-1) hits flags = 16 (plus default)
[01:02:25.314] INFO: dacScan step from 0 .. 19
[01:02:25.314] INFO: dacScan split into 1 runs with ntrig = 4
[01:02:25.314] INFO: run 1 of 1
[01:02:38.538] INFO: Test took 13224ms.
[01:02:38.577] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:02:38.577] INFO: dacScan step from 20 .. 39
[01:02:38.577] INFO: dacScan split into 1 runs with ntrig = 4
[01:02:38.577] INFO: run 1 of 1
[01:02:52.772] INFO: Test took 14194ms.
[01:02:52.903] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:02:52.934] INFO: dacScan step from 40 .. 59
[01:02:52.934] INFO: dacScan split into 1 runs with ntrig = 4
[01:02:52.934] INFO: run 1 of 1
[01:03:10.157] INFO: Test took 17223ms.
[01:03:10.438] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:03:10.519] INFO: dacScan step from 60 .. 79
[01:03:10.519] INFO: dacScan split into 1 runs with ntrig = 4
[01:03:10.519] INFO: run 1 of 1
[01:03:28.101] INFO: Test took 17582ms.
[01:03:28.379] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:03:28.456] INFO: dacScan step from 80 .. 99
[01:03:28.456] INFO: dacScan split into 1 runs with ntrig = 4
[01:03:28.456] INFO: run 1 of 1
[01:03:46.008] INFO: Test took 17552ms.
[01:03:46.289] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:03:46.365] INFO: dacScan step from 100 .. 119
[01:03:46.365] INFO: dacScan split into 1 runs with ntrig = 4
[01:03:46.365] INFO: run 1 of 1
[01:04:03.854] INFO: Test took 17489ms.
[01:04:04.157] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:04:04.244] INFO: dacScan step from 120 .. 139
[01:04:04.245] INFO: dacScan split into 1 runs with ntrig = 4
[01:04:04.245] INFO: run 1 of 1
[01:04:21.866] INFO: Test took 17621ms.
[01:04:22.150] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:04:22.231] INFO: dacScan step from 140 .. 159
[01:04:22.231] INFO: dacScan split into 1 runs with ntrig = 4
[01:04:22.231] INFO: run 1 of 1
[01:04:39.706] INFO: Test took 17474ms.
[01:04:39.992] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:04:40.071] INFO: dacScan step from 160 .. 179
[01:04:40.071] INFO: dacScan split into 1 runs with ntrig = 4
[01:04:40.071] INFO: run 1 of 1
[01:04:57.734] INFO: Test took 17663ms.
[01:04:58.080] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:04:58.161] INFO: dacScan step from 180 .. 199
[01:04:58.161] INFO: dacScan split into 1 runs with ntrig = 4
[01:04:58.161] INFO: run 1 of 1
[01:05:16.640] INFO: Test took 18479ms.
[01:05:16.888] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:05:16.960] INFO: dacScan step from 200 .. 219
[01:05:16.960] INFO: dacScan split into 1 runs with ntrig = 4
[01:05:16.960] INFO: run 1 of 1
[01:05:34.605] INFO: Test took 17644ms.
[01:05:34.877] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:05:34.944] INFO: dacScan step from 220 .. 239
[01:05:34.944] INFO: dacScan split into 1 runs with ntrig = 4
[01:05:34.944] INFO: run 1 of 1
[01:05:52.370] INFO: Test took 17425ms.
[01:05:52.645] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:05:52.724] INFO: dacScan step from 240 .. 255
[01:05:52.724] INFO: dacScan split into 1 runs with ntrig = 4
[01:05:52.724] INFO: run 1 of 1
[01:06:07.323] INFO: Test took 14599ms.
[01:06:07.543] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:06:59.651] INFO: ---> TrimStepCorr2 extremal thresholds: 13.929953 .. 49.518477
[01:06:59.807] INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 3 .. 59 (20/-1) hits flags = 16 (plus default)
[01:06:59.822] INFO: dacScan step from 3 .. 22
[01:06:59.822] INFO: dacScan split into 1 runs with ntrig = 4
[01:06:59.822] INFO: run 1 of 1
[01:07:12.921] INFO: Test took 13099ms.
[01:07:12.963] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:07:12.963] INFO: dacScan step from 23 .. 42
[01:07:12.963] INFO: dacScan split into 1 runs with ntrig = 4
[01:07:12.963] INFO: run 1 of 1
[01:07:27.566] INFO: Test took 14603ms.
[01:07:27.748] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:07:27.795] INFO: dacScan step from 43 .. 59
[01:07:27.795] INFO: dacScan split into 1 runs with ntrig = 4
[01:07:27.795] INFO: run 1 of 1
[01:07:42.998] INFO: Test took 15203ms.
[01:07:43.244] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:08:09.864] INFO: ---> TrimStepCorr1a extremal thresholds: 1.500000 .. 49.518477
[01:08:10.010] INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 1 .. 59 (20/-1) hits flags = 16 (plus default)
[01:08:10.026] INFO: dacScan step from 1 .. 20
[01:08:10.026] INFO: dacScan split into 1 runs with ntrig = 4
[01:08:10.026] INFO: run 1 of 1
[01:08:23.105] INFO: Test took 13079ms.
[01:08:23.149] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:08:23.150] INFO: dacScan step from 21 .. 40
[01:08:23.150] INFO: dacScan split into 1 runs with ntrig = 4
[01:08:23.150] INFO: run 1 of 1
[01:08:37.442] INFO: Test took 14292ms.
[01:08:37.580] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:08:37.615] INFO: dacScan step from 41 .. 59
[01:08:37.615] INFO: dacScan split into 1 runs with ntrig = 4
[01:08:37.615] INFO: run 1 of 1
[01:08:54.287] INFO: Test took 16672ms.
[01:08:54.556] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:09:20.647] INFO: ---> TrimStepCorr1b extremal thresholds: 1.074114 .. 49.518477
[01:09:20.801] INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 1 .. 59 (20/-1) hits flags = 16 (plus default)
[01:09:20.816] INFO: dacScan step from 1 .. 20
[01:09:20.816] INFO: dacScan split into 1 runs with ntrig = 4
[01:09:20.816] INFO: run 1 of 1
[01:09:34.142] INFO: Test took 13326ms.
[01:09:34.179] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:09:34.179] INFO: dacScan step from 21 .. 40
[01:09:34.179] INFO: dacScan split into 1 runs with ntrig = 4
[01:09:34.179] INFO: run 1 of 1
[01:09:48.369] INFO: Test took 14189ms.
[01:09:48.509] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:09:48.547] INFO: dacScan step from 41 .. 59
[01:09:48.547] INFO: dacScan split into 1 runs with ntrig = 4
[01:09:48.547] INFO: run 1 of 1
[01:10:05.144] INFO: Test took 16597ms.
[01:10:05.418] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:10:31.307] INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[01:10:31.307] INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20/5) hits flags = 16 (plus default)
[01:10:31.323] INFO: dacScan step from 15 .. 34
[01:10:31.323] INFO: dacScan split into 2 runs with ntrig = 5
[01:10:31.323] INFO: run 1 of 2
[01:10:46.104] INFO: Test took 14781ms.
[01:10:46.182] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:10:46.195] INFO: run 2 of 2
[01:11:00.875] INFO: Test took 14680ms.
[01:11:00.955] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:11:00.969] INFO: dacScan step from 35 .. 54
[01:11:00.969] INFO: dacScan split into 2 runs with ntrig = 5
[01:11:00.969] INFO: run 1 of 2
[01:11:20.368] INFO: Test took 19399ms.
[01:11:20.691] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:11:20.771] INFO: run 2 of 2
[01:11:40.378] INFO: Test took 19607ms.
[01:11:40.707] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:11:40.782] INFO: dacScan step from 55 .. 55
[01:11:40.782] INFO: dacScan split into 2 runs with ntrig = 5
[01:11:40.782] INFO: run 1 of 2
[01:11:44.772] INFO: Test took 3990ms.
[01:11:44.793] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:11:44.802] INFO: run 2 of 2
[01:11:48.862] INFO: Test took 4060ms.
[01:11:48.881] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:12:08.976] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C0.dat
[01:12:08.976] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C1.dat
[01:12:08.976] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C2.dat
[01:12:08.977] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C3.dat
[01:12:08.977] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C4.dat
[01:12:08.977] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C5.dat
[01:12:08.977] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C6.dat
[01:12:08.977] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C7.dat
[01:12:08.978] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C8.dat
[01:12:08.978] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C9.dat
[01:12:08.978] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C10.dat
[01:12:08.978] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C11.dat
[01:12:08.978] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C12.dat
[01:12:08.979] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C13.dat
[01:12:08.979] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C14.dat
[01:12:08.979] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C15.dat
[01:12:08.979] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/trimParameters35_C0.dat
[01:12:08.992] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/trimParameters35_C1.dat
[01:12:08.004] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/trimParameters35_C2.dat
[01:12:09.016] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/trimParameters35_C3.dat
[01:12:09.027] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/trimParameters35_C4.dat
[01:12:09.039] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/trimParameters35_C5.dat
[01:12:09.051] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/trimParameters35_C6.dat
[01:12:09.063] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/trimParameters35_C7.dat
[01:12:09.075] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/trimParameters35_C8.dat
[01:12:09.087] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/trimParameters35_C9.dat
[01:12:09.099] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/trimParameters35_C10.dat
[01:12:09.111] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/trimParameters35_C11.dat
[01:12:09.124] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/trimParameters35_C12.dat
[01:12:09.137] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/trimParameters35_C13.dat
[01:12:09.149] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/trimParameters35_C14.dat
[01:12:09.161] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/trimParameters35_C15.dat
[01:12:09.172] INFO: PixTestTrim::trimTest() done
[01:12:09.172] INFO: vtrim: 106 107 98 188 111 102 102 123 121 113 125 124 102 134 114 127
[01:12:09.172] INFO: vthrcomp: 105 86 98 106 98 90 94 96 105 100 105 108 103 107 95 105
[01:12:09.172] INFO: vcal mean: 35.13 35.12 35.10 34.98 35.07 35.21 35.14 35.09 35.17 35.15 35.11 35.08 35.12 35.08 35.12 35.16
[01:12:09.172] INFO: vcal RMS: 1.07 1.15 1.37 1.93 1.36 1.14 1.09 1.19 1.26 1.26 1.13 1.18 1.15 1.07 1.28 1.13
[01:12:09.172] INFO: bits mean: 8.80 9.63 9.60 11.43 10.22 9.67 9.96 10.02 8.72 9.55 9.55 8.69 8.86 9.53 10.16 9.38
[01:12:09.172] INFO: bits RMS: 2.56 2.51 2.73 1.56 2.48 2.33 2.41 2.39 2.45 2.18 2.15 2.36 2.71 2.34 2.37 2.27
[01:12:09.185] INFO: ----------------------------------------------------------------------
[01:12:09.185] INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[01:12:09.185] INFO: ----------------------------------------------------------------------
[01:12:09.190] INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[01:12:09.205] INFO: dacScan step from 0 .. 19
[01:12:09.205] INFO: dacScan split into 2 runs with ntrig = 5
[01:12:09.205] INFO: run 1 of 2
[01:12:23.461] INFO: Test took 14256ms.
[01:12:23.508] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:12:23.509] INFO: run 2 of 2
[01:12:37.712] INFO: Test took 14203ms.
[01:12:37.761] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:12:37.761] INFO: dacScan step from 20 .. 39
[01:12:37.761] INFO: dacScan split into 2 runs with ntrig = 5
[01:12:37.761] INFO: run 1 of 2
[01:12:51.954] INFO: Test took 14193ms.
[01:12:51.998] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:12:51.998] INFO: run 2 of 2
[01:13:06.156] INFO: Test took 14157ms.
[01:13:06.198] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:13:06.198] INFO: dacScan step from 40 .. 59
[01:13:06.198] INFO: dacScan split into 2 runs with ntrig = 5
[01:13:06.198] INFO: run 1 of 2
[01:13:20.390] INFO: Test took 14192ms.
[01:13:20.432] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:13:20.432] INFO: run 2 of 2
[01:13:34.613] INFO: Test took 14181ms.
[01:13:34.650] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:13:34.650] INFO: dacScan step from 60 .. 79
[01:13:34.650] INFO: dacScan split into 2 runs with ntrig = 5
[01:13:34.650] INFO: run 1 of 2
[01:13:48.745] INFO: Test took 14094ms.
[01:13:48.792] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:13:48.792] INFO: run 2 of 2
[01:14:02.911] INFO: Test took 14119ms.
[01:14:02.955] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:14:02.955] INFO: dacScan step from 80 .. 99
[01:14:02.955] INFO: dacScan split into 2 runs with ntrig = 5
[01:14:02.955] INFO: run 1 of 2
[01:14:17.240] INFO: Test took 14285ms.
[01:14:17.282] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:14:17.282] INFO: run 2 of 2
[01:14:31.845] INFO: Test took 14562ms.
[01:14:31.888] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:14:31.888] INFO: dacScan step from 100 .. 119
[01:14:31.888] INFO: dacScan split into 2 runs with ntrig = 5
[01:14:31.888] INFO: run 1 of 2
[01:14:46.513] INFO: Test took 14625ms.
[01:14:46.584] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:14:46.591] INFO: run 2 of 2
[01:15:01.125] INFO: Test took 14534ms.
[01:15:01.204] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:15:01.209] INFO: dacScan step from 120 .. 139
[01:15:01.209] INFO: dacScan split into 2 runs with ntrig = 5
[01:15:01.209] INFO: run 1 of 2
[01:15:17.795] INFO: Test took 16586ms.
[01:15:17.980] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:15:18.019] INFO: run 2 of 2
[01:15:34.753] INFO: Test took 16733ms.
[01:15:34.938] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:15:34.977] INFO: dacScan step from 140 .. 159
[01:15:34.977] INFO: dacScan split into 2 runs with ntrig = 5
[01:15:34.977] INFO: run 1 of 2
[01:15:54.269] INFO: Test took 19292ms.
[01:15:54.571] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:15:54.647] INFO: run 2 of 2
[01:16:14.219] INFO: Test took 19571ms.
[01:16:14.531] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:16:14.610] INFO: dacScan step from 160 .. 179
[01:16:14.610] INFO: dacScan split into 2 runs with ntrig = 5
[01:16:14.610] INFO: run 1 of 2
[01:16:34.627] INFO: Test took 20017ms.
[01:16:34.930] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:16:35.007] INFO: run 2 of 2
[01:16:55.038] INFO: Test took 20031ms.
[01:16:55.314] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:16:55.383] INFO: dacScan step from 180 .. 199
[01:16:55.383] INFO: dacScan split into 2 runs with ntrig = 5
[01:16:55.383] INFO: run 1 of 2
[01:17:16.134] INFO: Test took 20750ms.
[01:17:16.448] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:17:16.516] INFO: run 2 of 2
[01:17:37.314] INFO: Test took 20798ms.
[01:17:37.614] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:18:18.007] INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 192 (20/5) hits flags = 16 (plus default)
[01:18:18.026] INFO: dacScan step from 0 .. 19
[01:18:18.026] INFO: dacScan split into 2 runs with ntrig = 5
[01:18:18.026] INFO: run 1 of 2
[01:18:32.831] INFO: Test took 14805ms.
[01:18:32.875] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:18:32.875] INFO: run 2 of 2
[01:18:47.732] INFO: Test took 14856ms.
[01:18:47.775] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:18:47.775] INFO: dacScan step from 20 .. 39
[01:18:47.775] INFO: dacScan split into 2 runs with ntrig = 5
[01:18:47.775] INFO: run 1 of 2
[01:19:02.575] INFO: Test took 14799ms.
[01:19:02.617] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:19:02.617] INFO: run 2 of 2
[01:19:17.351] INFO: Test took 14734ms.
[01:19:17.390] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:19:17.390] INFO: dacScan step from 40 .. 59
[01:19:17.390] INFO: dacScan split into 2 runs with ntrig = 5
[01:19:17.390] INFO: run 1 of 2
[01:19:32.124] INFO: Test took 14734ms.
[01:19:32.164] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:19:32.164] INFO: run 2 of 2
[01:19:46.842] INFO: Test took 14677ms.
[01:19:46.894] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:19:46.894] INFO: dacScan step from 60 .. 79
[01:19:46.894] INFO: dacScan split into 2 runs with ntrig = 5
[01:19:46.894] INFO: run 1 of 2
[01:20:01.539] INFO: Test took 14644ms.
[01:20:01.589] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:20:01.589] INFO: run 2 of 2
[01:20:16.454] INFO: Test took 14865ms.
[01:20:16.497] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:20:16.497] INFO: dacScan step from 80 .. 99
[01:20:16.497] INFO: dacScan split into 2 runs with ntrig = 5
[01:20:16.497] INFO: run 1 of 2
[01:20:31.142] INFO: Test took 14645ms.
[01:20:31.190] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:20:31.191] INFO: run 2 of 2
[01:20:45.687] INFO: Test took 14496ms.
[01:20:45.732] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:20:45.732] INFO: dacScan step from 100 .. 119
[01:20:45.733] INFO: dacScan split into 2 runs with ntrig = 5
[01:20:45.733] INFO: run 1 of 2
[01:21:01.153] INFO: Test took 15420ms.
[01:21:01.272] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:21:01.291] INFO: run 2 of 2
[01:21:16.687] INFO: Test took 15396ms.
[01:21:16.823] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:21:16.845] INFO: dacScan step from 120 .. 139
[01:21:16.845] INFO: dacScan split into 2 runs with ntrig = 5
[01:21:16.845] INFO: run 1 of 2
[01:21:35.343] INFO: Test took 18498ms.
[01:21:35.601] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:21:35.665] INFO: run 2 of 2
[01:21:54.115] INFO: Test took 18450ms.
[01:21:54.403] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:21:54.468] INFO: dacScan step from 140 .. 159
[01:21:54.468] INFO: dacScan split into 2 runs with ntrig = 5
[01:21:54.468] INFO: run 1 of 2
[01:22:14.208] INFO: Test took 19740ms.
[01:22:14.553] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:22:14.628] INFO: run 2 of 2
[01:22:34.567] INFO: Test took 19939ms.
[01:22:34.869] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:22:34.943] INFO: dacScan step from 160 .. 179
[01:22:34.943] INFO: dacScan split into 2 runs with ntrig = 5
[01:22:34.943] INFO: run 1 of 2
[01:22:54.978] INFO: Test took 20035ms.
[01:22:55.265] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:22:55.340] INFO: run 2 of 2
[01:23:15.322] INFO: Test took 19982ms.
[01:23:15.604] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:23:15.669] INFO: dacScan step from 180 .. 192
[01:23:15.669] INFO: dacScan split into 2 runs with ntrig = 5
[01:23:15.669] INFO: run 1 of 2
[01:23:30.361] INFO: Test took 14692ms.
[01:23:30.553] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:23:30.602] INFO: run 2 of 2
[01:23:45.470] INFO: Test took 14867ms.
[01:23:45.652] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:24:26.233] INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 177 (20/5) hits flags = 16 (plus default)
[01:24:26.247] INFO: dacScan step from 0 .. 19
[01:24:26.247] INFO: dacScan split into 2 runs with ntrig = 5
[01:24:26.247] INFO: run 1 of 2
[01:24:40.904] INFO: Test took 14657ms.
[01:24:40.953] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:24:40.953] INFO: run 2 of 2
[01:24:55.520] INFO: Test took 14566ms.
[01:24:55.560] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:24:55.560] INFO: dacScan step from 20 .. 39
[01:24:55.560] INFO: dacScan split into 2 runs with ntrig = 5
[01:24:55.560] INFO: run 1 of 2
[01:25:10.131] INFO: Test took 14570ms.
[01:25:10.168] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:25:10.168] INFO: run 2 of 2
[01:25:24.804] INFO: Test took 14636ms.
[01:25:24.844] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:25:24.844] INFO: dacScan step from 40 .. 59
[01:25:24.844] INFO: dacScan split into 2 runs with ntrig = 5
[01:25:24.844] INFO: run 1 of 2
[01:25:39.515] INFO: Test took 14671ms.
[01:25:39.563] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:25:39.563] INFO: run 2 of 2
[01:25:54.312] INFO: Test took 14749ms.
[01:25:54.359] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:25:54.359] INFO: dacScan step from 60 .. 79
[01:25:54.359] INFO: dacScan split into 2 runs with ntrig = 5
[01:25:54.359] INFO: run 1 of 2
[01:26:08.955] INFO: Test took 14596ms.
[01:26:08.004] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:26:08.004] INFO: run 2 of 2
[01:26:23.782] INFO: Test took 14778ms.
[01:26:23.823] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:26:23.823] INFO: dacScan step from 80 .. 99
[01:26:23.823] INFO: dacScan split into 2 runs with ntrig = 5
[01:26:23.823] INFO: run 1 of 2
[01:26:38.331] INFO: Test took 14508ms.
[01:26:38.378] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:26:38.379] INFO: run 2 of 2
[01:26:52.873] INFO: Test took 14494ms.
[01:26:52.919] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:26:52.919] INFO: dacScan step from 100 .. 119
[01:26:52.919] INFO: dacScan split into 2 runs with ntrig = 5
[01:26:52.919] INFO: run 1 of 2
[01:27:08.157] INFO: Test took 15238ms.
[01:27:08.287] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:27:08.307] INFO: run 2 of 2
[01:27:23.708] INFO: Test took 15401ms.
[01:27:23.839] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:27:23.861] INFO: dacScan step from 120 .. 139
[01:27:23.861] INFO: dacScan split into 2 runs with ntrig = 5
[01:27:23.861] INFO: run 1 of 2
[01:27:42.278] INFO: Test took 18417ms.
[01:27:42.575] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:27:42.645] INFO: run 2 of 2
[01:28:01.213] INFO: Test took 18568ms.
[01:28:01.506] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:28:01.577] INFO: dacScan step from 140 .. 159
[01:28:01.577] INFO: dacScan split into 2 runs with ntrig = 5
[01:28:01.577] INFO: run 1 of 2
[01:28:21.616] INFO: Test took 20039ms.
[01:28:21.904] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:28:21.979] INFO: run 2 of 2
[01:28:41.696] INFO: Test took 19716ms.
[01:28:41.975] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:28:42.061] INFO: dacScan step from 160 .. 177
[01:28:42.061] INFO: dacScan split into 2 runs with ntrig = 5
[01:28:42.061] INFO: run 1 of 2
[01:29:00.446] INFO: Test took 18385ms.
[01:29:00.703] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:29:00.763] INFO: run 2 of 2
[01:29:19.233] INFO: Test took 18470ms.
[01:29:19.493] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:29:55.816] INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 175 (20/5) hits flags = 16 (plus default)
[01:29:55.836] INFO: dacScan step from 0 .. 19
[01:29:55.836] INFO: dacScan split into 2 runs with ntrig = 5
[01:29:55.836] INFO: run 1 of 2
[01:30:10.581] INFO: Test took 14744ms.
[01:30:10.627] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:30:10.627] INFO: run 2 of 2
[01:30:25.484] INFO: Test took 14857ms.
[01:30:25.525] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:30:25.525] INFO: dacScan step from 20 .. 39
[01:30:25.525] INFO: dacScan split into 2 runs with ntrig = 5
[01:30:25.525] INFO: run 1 of 2
[01:30:40.347] INFO: Test took 14822ms.
[01:30:40.395] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:30:40.396] INFO: run 2 of 2
[01:30:55.170] INFO: Test took 14774ms.
[01:30:55.209] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:30:55.209] INFO: dacScan step from 40 .. 59
[01:30:55.209] INFO: dacScan split into 2 runs with ntrig = 5
[01:30:55.209] INFO: run 1 of 2
[01:31:09.824] INFO: Test took 14615ms.
[01:31:09.873] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:31:09.873] INFO: run 2 of 2
[01:31:24.580] INFO: Test took 14706ms.
[01:31:24.625] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:31:24.625] INFO: dacScan step from 60 .. 79
[01:31:24.625] INFO: dacScan split into 2 runs with ntrig = 5
[01:31:24.625] INFO: run 1 of 2
[01:31:39.362] INFO: Test took 14737ms.
[01:31:39.407] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:31:39.407] INFO: run 2 of 2
[01:31:54.088] INFO: Test took 14681ms.
[01:31:54.129] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:31:54.129] INFO: dacScan step from 80 .. 99
[01:31:54.129] INFO: dacScan split into 2 runs with ntrig = 5
[01:31:54.129] INFO: run 1 of 2
[01:32:08.560] INFO: Test took 14431ms.
[01:32:08.605] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:32:08.606] INFO: run 2 of 2
[01:32:23.061] INFO: Test took 14455ms.
[01:32:23.106] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:32:23.107] INFO: dacScan step from 100 .. 119
[01:32:23.107] INFO: dacScan split into 2 runs with ntrig = 5
[01:32:23.108] INFO: run 1 of 2
[01:32:38.554] INFO: Test took 15446ms.
[01:32:38.688] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:32:38.710] INFO: run 2 of 2
[01:32:54.236] INFO: Test took 15526ms.
[01:32:54.368] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:32:54.389] INFO: dacScan step from 120 .. 139
[01:32:54.389] INFO: dacScan split into 2 runs with ntrig = 5
[01:32:54.389] INFO: run 1 of 2
[01:33:12.815] INFO: Test took 18426ms.
[01:33:13.103] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:33:13.173] INFO: run 2 of 2
[01:33:31.556] INFO: Test took 18383ms.
[01:33:31.838] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:33:31.905] INFO: dacScan step from 140 .. 159
[01:33:31.905] INFO: dacScan split into 2 runs with ntrig = 5
[01:33:31.905] INFO: run 1 of 2
[01:33:51.758] INFO: Test took 19852ms.
[01:33:52.056] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:33:52.135] INFO: run 2 of 2
[01:34:11.798] INFO: Test took 19663ms.
[01:34:12.103] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:34:12.209] INFO: dacScan step from 160 .. 175
[01:34:12.209] INFO: dacScan split into 2 runs with ntrig = 5
[01:34:12.210] INFO: run 1 of 2
[01:34:28.889] INFO: Test took 16679ms.
[01:34:29.147] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:34:29.209] INFO: run 2 of 2
[01:34:45.846] INFO: Test took 16637ms.
[01:34:46.092] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:35:23.198] INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 173 (20/5) hits flags = 16 (plus default)
[01:35:23.212] INFO: dacScan step from 0 .. 19
[01:35:23.212] INFO: dacScan split into 2 runs with ntrig = 5
[01:35:23.212] INFO: run 1 of 2
[01:35:38.062] INFO: Test took 14850ms.
[01:35:38.107] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:35:38.108] INFO: run 2 of 2
[01:35:52.913] INFO: Test took 14805ms.
[01:35:52.955] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:35:52.955] INFO: dacScan step from 20 .. 39
[01:35:52.955] INFO: dacScan split into 2 runs with ntrig = 5
[01:35:52.955] INFO: run 1 of 2
[01:36:07.767] INFO: Test took 14811ms.
[01:36:07.810] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:36:07.810] INFO: run 2 of 2
[01:36:22.547] INFO: Test took 14737ms.
[01:36:22.593] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:36:22.593] INFO: dacScan step from 40 .. 59
[01:36:22.593] INFO: dacScan split into 2 runs with ntrig = 5
[01:36:22.593] INFO: run 1 of 2
[01:36:37.379] INFO: Test took 14786ms.
[01:36:37.425] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:36:37.425] INFO: run 2 of 2
[01:36:52.168] INFO: Test took 14743ms.
[01:36:52.213] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:36:52.213] INFO: dacScan step from 60 .. 79
[01:36:52.213] INFO: dacScan split into 2 runs with ntrig = 5
[01:36:52.213] INFO: run 1 of 2
[01:37:06.944] INFO: Test took 14731ms.
[01:37:06.988] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:37:06.988] INFO: run 2 of 2
[01:37:21.695] INFO: Test took 14707ms.
[01:37:21.737] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:37:21.737] INFO: dacScan step from 80 .. 99
[01:37:21.737] INFO: dacScan split into 2 runs with ntrig = 5
[01:37:21.737] INFO: run 1 of 2
[01:37:36.253] INFO: Test took 14516ms.
[01:37:36.299] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:37:36.300] INFO: run 2 of 2
[01:37:50.809] INFO: Test took 14509ms.
[01:37:50.851] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:37:50.852] INFO: dacScan step from 100 .. 119
[01:37:50.852] INFO: dacScan split into 2 runs with ntrig = 5
[01:37:50.852] INFO: run 1 of 2
[01:38:06.188] INFO: Test took 15336ms.
[01:38:06.319] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:38:06.342] INFO: run 2 of 2
[01:38:21.641] INFO: Test took 15299ms.
[01:38:21.769] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:38:21.789] INFO: dacScan step from 120 .. 139
[01:38:21.789] INFO: dacScan split into 2 runs with ntrig = 5
[01:38:21.789] INFO: run 1 of 2
[01:38:40.304] INFO: Test took 18515ms.
[01:38:40.567] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:38:40.630] INFO: run 2 of 2
[01:38:59.151] INFO: Test took 18521ms.
[01:38:59.438] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:38:59.507] INFO: dacScan step from 140 .. 159
[01:38:59.507] INFO: dacScan split into 2 runs with ntrig = 5
[01:38:59.507] INFO: run 1 of 2
[01:39:19.296] INFO: Test took 19789ms.
[01:39:19.616] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:39:19.692] INFO: run 2 of 2
[01:39:39.504] INFO: Test took 19812ms.
[01:39:39.799] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:39:39.865] INFO: dacScan step from 160 .. 173
[01:39:39.865] INFO: dacScan split into 2 runs with ntrig = 5
[01:39:39.865] INFO: run 1 of 2
[01:39:54.732] INFO: Test took 14867ms.
[01:39:54.941] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:39:54.993] INFO: run 2 of 2
[01:40:09.864] INFO: Test took 14871ms.
[01:40:10.056] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:40:46.264] INFO: PixTestTrim::trimBitTest() done
[01:40:46.265] INFO: PixTestTrim::doTest() done, duration: 3162 seconds
[01:40:47.284] INFO: ######################################################################
[01:40:47.284] INFO: PixTestPhOptimization::doTest() Ntrig = 10
[01:40:47.284] INFO: ######################################################################
[01:40:51.041] INFO: Test took 3755ms.
[01:40:51.077] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:40:54.988] INFO: Test took 3708ms.
[01:40:55.127] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:40:58.829] INFO: Test took 3684ms.
[01:40:59.010] INFO: Fetched DAQ statistics. Counters are being reset now.
[01:40:59.024] INFO: The DUT currently contains the following objects:
[01:40:59.024] INFO: 2 TBM Cores tbm08c (2 ON)
[01:40:59.024] INFO: TBM Core alpha (0): 7 registers set
[01:40:59.024] INFO: TBM Core beta (1): 7 registers set
[01:40:59.024] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[01:40:59.024] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:40:59.024] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:40:59.024] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:40:59.024] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:40:59.024] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:40:59.024] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:40:59.024] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:40:59.024] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:40:59.024] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:40:59.024] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:40:59.024] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:40:59.024] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:40:59.024] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:40:59.024] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:40:59.024] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:40:59.024] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:00.125] INFO: Test took 1101ms.
[01:41:00.127] INFO: The DUT currently contains the following objects:
[01:41:00.127] INFO: 2 TBM Cores tbm08c (2 ON)
[01:41:00.127] INFO: TBM Core alpha (0): 7 registers set
[01:41:00.127] INFO: TBM Core beta (1): 7 registers set
[01:41:00.127] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[01:41:00.127] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:00.127] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:00.127] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:00.127] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:00.127] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:00.127] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:00.127] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:00.127] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:00.127] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:00.127] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:00.127] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:00.127] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:00.127] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:00.127] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:00.127] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:00.127] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:01.224] INFO: Test took 1097ms.
[01:41:01.226] INFO: The DUT currently contains the following objects:
[01:41:01.226] INFO: 2 TBM Cores tbm08c (2 ON)
[01:41:01.226] INFO: TBM Core alpha (0): 7 registers set
[01:41:01.227] INFO: TBM Core beta (1): 7 registers set
[01:41:01.227] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[01:41:01.227] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:01.227] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:01.227] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:01.227] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:01.227] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:01.227] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:01.227] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:01.227] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:01.227] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:01.227] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:01.227] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:01.227] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:01.227] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:01.227] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:01.227] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:01.227] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:02.366] INFO: Test took 1139ms.
[01:41:02.368] INFO: The DUT currently contains the following objects:
[01:41:02.368] INFO: 2 TBM Cores tbm08c (2 ON)
[01:41:02.368] INFO: TBM Core alpha (0): 7 registers set
[01:41:02.368] INFO: TBM Core beta (1): 7 registers set
[01:41:02.368] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[01:41:02.368] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:02.368] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:02.368] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:02.368] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:02.368] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:02.368] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:02.368] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:02.368] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:02.368] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:02.368] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:02.368] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:02.368] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:02.368] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:02.368] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:02.368] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:02.369] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:03.470] INFO: Test took 1101ms.
[01:41:03.472] INFO: The DUT currently contains the following objects:
[01:41:03.472] INFO: 2 TBM Cores tbm08c (2 ON)
[01:41:03.472] INFO: TBM Core alpha (0): 7 registers set
[01:41:03.472] INFO: TBM Core beta (1): 7 registers set
[01:41:03.472] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[01:41:03.472] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:03.473] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:03.473] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:03.473] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:03.473] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:03.473] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:03.473] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:03.473] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:03.473] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:03.473] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:03.473] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:03.473] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:03.473] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:03.473] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:03.473] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:03.473] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:04.574] INFO: Test took 1101ms.
[01:41:04.576] INFO: The DUT currently contains the following objects:
[01:41:04.576] INFO: 2 TBM Cores tbm08c (2 ON)
[01:41:04.576] INFO: TBM Core alpha (0): 7 registers set
[01:41:04.576] INFO: TBM Core beta (1): 7 registers set
[01:41:04.576] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[01:41:04.576] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:04.576] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:04.576] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:04.576] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:04.576] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:04.576] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:04.576] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:04.576] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:04.576] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:04.576] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:04.576] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:04.576] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:04.576] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:04.576] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:04.576] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:04.576] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:05.676] INFO: Test took 1100ms.
[01:41:05.678] INFO: The DUT currently contains the following objects:
[01:41:05.678] INFO: 2 TBM Cores tbm08c (2 ON)
[01:41:05.678] INFO: TBM Core alpha (0): 7 registers set
[01:41:05.678] INFO: TBM Core beta (1): 7 registers set
[01:41:05.678] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[01:41:05.678] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:05.678] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:05.678] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:05.678] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:05.678] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:05.678] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:05.678] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:05.678] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:05.678] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:05.678] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:05.678] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:05.678] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:05.678] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:05.678] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:05.678] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:05.678] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:06.811] INFO: Test took 1133ms.
[01:41:06.813] INFO: The DUT currently contains the following objects:
[01:41:06.813] INFO: 2 TBM Cores tbm08c (2 ON)
[01:41:06.813] INFO: TBM Core alpha (0): 7 registers set
[01:41:06.813] INFO: TBM Core beta (1): 7 registers set
[01:41:06.813] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[01:41:06.813] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:06.813] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:06.813] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:06.813] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:06.813] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:06.813] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:06.813] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:06.813] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:06.813] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:06.813] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:06.813] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:06.813] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:06.813] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:06.813] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:06.813] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:06.813] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:07.911] INFO: Test took 1098ms.
[01:41:07.913] INFO: The DUT currently contains the following objects:
[01:41:07.913] INFO: 2 TBM Cores tbm08c (2 ON)
[01:41:07.913] INFO: TBM Core alpha (0): 7 registers set
[01:41:07.913] INFO: TBM Core beta (1): 7 registers set
[01:41:07.913] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[01:41:07.913] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:07.913] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:07.913] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:07.913] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:07.913] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:07.913] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:07.913] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:07.913] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:07.913] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:07.913] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:07.913] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:07.913] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:07.914] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:07.914] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:07.914] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:07.914] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:09.010] INFO: Test took 1096ms.
[01:41:09.011] INFO: The DUT currently contains the following objects:
[01:41:09.011] INFO: 2 TBM Cores tbm08c (2 ON)
[01:41:09.011] INFO: TBM Core alpha (0): 7 registers set
[01:41:09.011] INFO: TBM Core beta (1): 7 registers set
[01:41:09.011] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[01:41:09.011] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:09.011] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:09.011] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:09.011] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:09.011] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:09.011] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:09.011] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:09.011] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:09.011] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:09.011] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:09.012] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:09.012] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:09.012] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:09.012] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:09.012] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:09.012] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:10.145] INFO: Test took 1133ms.
[01:41:10.147] INFO: The DUT currently contains the following objects:
[01:41:10.147] INFO: 2 TBM Cores tbm08c (2 ON)
[01:41:10.147] INFO: TBM Core alpha (0): 7 registers set
[01:41:10.147] INFO: TBM Core beta (1): 7 registers set
[01:41:10.147] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[01:41:10.147] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:10.147] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:10.147] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:10.147] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:10.147] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:10.147] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:10.147] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:10.147] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:10.147] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:10.148] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:10.148] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:10.148] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:10.148] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:10.148] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:10.148] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:10.148] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:11.249] INFO: Test took 1101ms.
[01:41:11.251] INFO: The DUT currently contains the following objects:
[01:41:11.251] INFO: 2 TBM Cores tbm08c (2 ON)
[01:41:11.251] INFO: TBM Core alpha (0): 7 registers set
[01:41:11.251] INFO: TBM Core beta (1): 7 registers set
[01:41:11.251] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[01:41:11.251] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:11.251] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:11.251] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:11.251] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:11.251] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:11.251] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:11.251] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:11.251] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:11.251] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:11.251] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:11.251] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:11.251] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:11.251] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:11.251] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:11.251] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:11.251] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:12.349] INFO: Test took 1098ms.
[01:41:12.350] INFO: The DUT currently contains the following objects:
[01:41:12.350] INFO: 2 TBM Cores tbm08c (2 ON)
[01:41:12.350] INFO: TBM Core alpha (0): 7 registers set
[01:41:12.350] INFO: TBM Core beta (1): 7 registers set
[01:41:12.350] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[01:41:12.350] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:12.350] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:12.350] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:12.350] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:12.350] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:12.350] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:12.350] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:12.350] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:12.350] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:12.350] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:12.350] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:12.350] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:12.350] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:12.350] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:12.351] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:12.351] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:13.482] INFO: Test took 1131ms.
[01:41:13.484] INFO: The DUT currently contains the following objects:
[01:41:13.484] INFO: 2 TBM Cores tbm08c (2 ON)
[01:41:13.484] INFO: TBM Core alpha (0): 7 registers set
[01:41:13.484] INFO: TBM Core beta (1): 7 registers set
[01:41:13.484] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[01:41:13.484] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:13.484] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:13.484] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:13.484] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:13.484] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:13.484] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:13.484] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:13.484] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:13.484] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:13.484] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:13.484] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:13.484] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:13.484] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:13.484] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:13.484] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:13.484] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:14.617] INFO: Test took 1133ms.
[01:41:14.619] INFO: The DUT currently contains the following objects:
[01:41:14.619] INFO: 2 TBM Cores tbm08c (2 ON)
[01:41:14.619] INFO: TBM Core alpha (0): 7 registers set
[01:41:14.619] INFO: TBM Core beta (1): 7 registers set
[01:41:14.619] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[01:41:14.619] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:14.619] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:14.619] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:14.619] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:14.619] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:14.619] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:14.619] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:14.619] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:14.619] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:14.619] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:14.619] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:14.619] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:14.619] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:14.619] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:14.619] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:14.619] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:15.718] INFO: Test took 1099ms.
[01:41:15.720] INFO: The DUT currently contains the following objects:
[01:41:15.720] INFO: 2 TBM Cores tbm08c (2 ON)
[01:41:15.720] INFO: TBM Core alpha (0): 7 registers set
[01:41:15.720] INFO: TBM Core beta (1): 7 registers set
[01:41:15.720] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[01:41:15.720] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:15.720] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:15.720] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:15.720] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:15.720] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:15.720] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:15.720] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:15.721] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:15.721] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:15.721] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:15.721] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:15.721] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:15.721] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:15.721] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:15.721] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:15.721] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[01:41:16.855] INFO: Test took 1134ms.
[01:41:16.863] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:44:27.487] INFO: Test took 190624ms.
[01:44:30.427] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:47:39.704] INFO: Test took 189277ms.
[01:47:42.858] INFO: safety margin for low PH: adding 0, margin is now 20
[01:47:42.868] INFO: safety margin for low PH: adding 0, margin is now 20
[01:47:42.876] INFO: safety margin for low PH: adding 0, margin is now 20
[01:47:42.886] INFO: safety margin for low PH: adding 0, margin is now 20
[01:47:42.895] INFO: safety margin for low PH: adding 0, margin is now 20
[01:47:42.904] INFO: safety margin for low PH: adding 0, margin is now 20
[01:47:42.913] INFO: safety margin for low PH: adding 0, margin is now 20
[01:47:42.922] INFO: safety margin for low PH: adding 1, margin is now 21
[01:47:42.932] INFO: safety margin for low PH: adding 2, margin is now 22
[01:47:42.941] INFO: safety margin for low PH: adding 3, margin is now 23
[01:47:42.950] INFO: safety margin for low PH: adding 4, margin is now 24
[01:47:42.959] INFO: safety margin for low PH: adding 5, margin is now 25
[01:47:42.969] INFO: safety margin for low PH: adding 6, margin is now 26
[01:47:42.978] INFO: safety margin for low PH: adding 7, margin is now 27
[01:47:42.988] INFO: safety margin for low PH: adding 8, margin is now 28
[01:47:42.998] INFO: safety margin for low PH: adding 0, margin is now 20
[01:47:43.007] INFO: safety margin for low PH: adding 0, margin is now 20
[01:47:43.017] INFO: safety margin for low PH: adding 0, margin is now 20
[01:47:43.027] INFO: safety margin for low PH: adding 1, margin is now 21
[01:47:43.037] INFO: safety margin for low PH: adding 0, margin is now 20
[01:47:43.047] INFO: safety margin for low PH: adding 0, margin is now 20
[01:47:43.056] INFO: safety margin for low PH: adding 0, margin is now 20
[01:47:43.065] INFO: safety margin for low PH: adding 0, margin is now 20
[01:47:43.074] INFO: safety margin for low PH: adding 0, margin is now 20
[01:47:43.084] INFO: safety margin for low PH: adding 0, margin is now 20
[01:47:43.165] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C0.dat
[01:47:43.166] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C1.dat
[01:47:43.166] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C2.dat
[01:47:43.166] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C3.dat
[01:47:43.166] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C4.dat
[01:47:43.167] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C5.dat
[01:47:43.167] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C6.dat
[01:47:43.167] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C7.dat
[01:47:43.167] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C8.dat
[01:47:43.168] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C9.dat
[01:47:43.168] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C10.dat
[01:47:43.168] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C11.dat
[01:47:43.168] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C12.dat
[01:47:43.169] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C13.dat
[01:47:43.169] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C14.dat
[01:47:43.169] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/dacParameters35_C15.dat
[01:47:46.864] INFO: Test took 3689ms.
[01:47:50.861] INFO: Test took 3635ms.
[01:47:54.985] INFO: Test took 3751ms.
[01:47:55.352] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:47:56.282] INFO: Test took 931ms.
[01:47:56.287] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:47:57.384] INFO: Test took 1097ms.
[01:47:57.389] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:47:58.527] INFO: Test took 1138ms.
[01:47:58.532] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:47:59.671] INFO: Test took 1139ms.
[01:47:59.677] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:00.775] INFO: Test took 1098ms.
[01:48:00.780] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:01.880] INFO: Test took 1100ms.
[01:48:01.885] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:02.983] INFO: Test took 1098ms.
[01:48:02.989] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:04.089] INFO: Test took 1100ms.
[01:48:04.094] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:05.193] INFO: Test took 1100ms.
[01:48:05.198] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:06.330] INFO: Test took 1132ms.
[01:48:06.335] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:07.467] INFO: Test took 1133ms.
[01:48:07.473] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:08.572] INFO: Test took 1100ms.
[01:48:08.577] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:09.675] INFO: Test took 1099ms.
[01:48:09.681] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:10.778] INFO: Test took 1097ms.
[01:48:10.783] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:11.881] INFO: Test took 1099ms.
[01:48:11.886] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:13.021] INFO: Test took 1135ms.
[01:48:13.028] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:14.159] INFO: Test took 1131ms.
[01:48:14.166] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:15.262] INFO: Test took 1096ms.
[01:48:15.268] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:16.369] INFO: Test took 1101ms.
[01:48:16.376] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:17.476] INFO: Test took 1101ms.
[01:48:17.481] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:18.579] INFO: Test took 1098ms.
[01:48:18.585] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:19.717] INFO: Test took 1133ms.
[01:48:19.722] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:20.853] INFO: Test took 1131ms.
[01:48:20.857] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:21.992] INFO: Test took 1135ms.
[01:48:21.998] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:23.095] INFO: Test took 1097ms.
[01:48:23.100] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:24.233] INFO: Test took 1134ms.
[01:48:24.239] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:25.338] INFO: Test took 1100ms.
[01:48:25.345] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:26.479] INFO: Test took 1134ms.
[01:48:26.485] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:27.586] INFO: Test took 1101ms.
[01:48:27.593] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:28.691] INFO: Test took 1099ms.
[01:48:28.696] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:29.829] INFO: Test took 1133ms.
[01:48:29.835] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[01:48:30.970] INFO: Test took 1136ms.
[01:48:31.593] INFO: PixTestPhOptimization::doTest() done, duration: 464 seconds
[01:48:31.593] INFO: PH scale (per ROC): 66 68 68 74 75 65 66 75 66 58 69 66 64 79 68 65
[01:48:31.593] INFO: PH offset (per ROC): 184 174 167 180 174 171 176 192 191 205 195 182 178 173 171 186
[01:48:31.792] INFO: ######################################################################
[01:48:31.792] INFO: PixTestGainPedestal::fullTest() ntrig = 10
[01:48:31.792] INFO: ######################################################################
[01:48:31.809] INFO: scanning low vcal = 10
[01:48:35.890] INFO: Test took 4081ms.
[01:48:35.898] INFO: scanning low vcal = 20
[01:48:39.996] INFO: Test took 4098ms.
[01:48:39.004] INFO: scanning low vcal = 30
[01:48:44.153] INFO: Test took 4149ms.
[01:48:44.175] INFO: scanning low vcal = 40
[01:48:48.758] INFO: Test took 4583ms.
[01:48:48.888] INFO: scanning low vcal = 50
[01:48:53.518] INFO: Test took 4630ms.
[01:48:53.636] INFO: scanning low vcal = 60
[01:48:58.245] INFO: Test took 4609ms.
[01:48:58.369] INFO: scanning low vcal = 70
[01:49:02.002] INFO: Test took 4633ms.
[01:49:03.135] INFO: scanning low vcal = 80
[01:49:07.811] INFO: Test took 4676ms.
[01:49:07.948] INFO: scanning low vcal = 90
[01:49:12.618] INFO: Test took 4670ms.
[01:49:12.736] INFO: scanning low vcal = 100
[01:49:17.437] INFO: Test took 4701ms.
[01:49:17.576] INFO: scanning low vcal = 110
[01:49:22.213] INFO: Test took 4637ms.
[01:49:22.350] INFO: scanning low vcal = 120
[01:49:26.968] INFO: Test took 4618ms.
[01:49:27.104] INFO: scanning low vcal = 130
[01:49:31.756] INFO: Test took 4652ms.
[01:49:31.896] INFO: scanning low vcal = 140
[01:49:36.534] INFO: Test took 4638ms.
[01:49:36.674] INFO: scanning low vcal = 150
[01:49:41.341] INFO: Test took 4667ms.
[01:49:41.480] INFO: scanning low vcal = 160
[01:49:46.204] INFO: Test took 4724ms.
[01:49:46.346] INFO: scanning low vcal = 170
[01:49:51.050] INFO: Test took 4704ms.
[01:49:51.191] INFO: scanning low vcal = 180
[01:49:55.877] INFO: Test took 4686ms.
[01:49:56.020] INFO: scanning low vcal = 190
[01:50:00.803] INFO: Test took 4783ms.
[01:50:00.945] INFO: scanning low vcal = 200
[01:50:05.665] INFO: Test took 4720ms.
[01:50:05.808] INFO: scanning low vcal = 210
[01:50:10.467] INFO: Test took 4659ms.
[01:50:10.607] INFO: scanning low vcal = 220
[01:50:15.275] INFO: Test took 4668ms.
[01:50:15.415] INFO: scanning low vcal = 230
[01:50:20.127] INFO: Test took 4712ms.
[01:50:20.267] INFO: scanning low vcal = 240
[01:50:24.907] INFO: Test took 4640ms.
[01:50:25.044] INFO: scanning low vcal = 250
[01:50:29.694] INFO: Test took 4649ms.
[01:50:29.825] INFO: scanning high vcal = 30 (= 210 in low range)
[01:50:34.512] INFO: Test took 4687ms.
[01:50:34.692] INFO: scanning high vcal = 50 (= 350 in low range)
[01:50:39.468] INFO: Test took 4776ms.
[01:50:39.602] INFO: scanning high vcal = 70 (= 490 in low range)
[01:50:44.245] INFO: Test took 4642ms.
[01:50:44.380] INFO: scanning high vcal = 90 (= 630 in low range)
[01:50:49.061] INFO: Test took 4681ms.
[01:50:49.184] INFO: scanning high vcal = 200 (= 1400 in low range)
[01:50:53.800] INFO: Test took 4616ms.
[01:50:54.646] INFO: PixTestGainPedestal::measure() done
[01:51:49.714] INFO: PixTestGainPedestal::fit() done
[01:51:49.714] INFO: non-linearity mean: 0.960 0.955 0.956 0.968 0.961 0.963 0.965 0.961 0.961 0.961 0.960 0.964 0.967 0.959 0.963 0.960
[01:51:49.714] INFO: non-linearity RMS: 0.007 0.007 0.009 0.008 0.006 0.005 0.005 0.006 0.007 0.008 0.006 0.005 0.007 0.005 0.009 0.007
[01:51:49.714] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/phCalibrationFitErr35_C0.dat
[01:51:49.738] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/phCalibrationFitErr35_C1.dat
[01:51:49.763] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/phCalibrationFitErr35_C2.dat
[01:51:49.789] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/phCalibrationFitErr35_C3.dat
[01:51:49.814] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/phCalibrationFitErr35_C4.dat
[01:51:49.839] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/phCalibrationFitErr35_C5.dat
[01:51:49.864] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/phCalibrationFitErr35_C6.dat
[01:51:49.889] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/phCalibrationFitErr35_C7.dat
[01:51:49.916] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/phCalibrationFitErr35_C8.dat
[01:51:49.942] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/phCalibrationFitErr35_C9.dat
[01:51:49.969] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/phCalibrationFitErr35_C10.dat
[01:51:49.995] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/phCalibrationFitErr35_C11.dat
[01:51:50.020] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/phCalibrationFitErr35_C12.dat
[01:51:50.044] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/phCalibrationFitErr35_C13.dat
[01:51:50.069] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/phCalibrationFitErr35_C14.dat
[01:51:50.095] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/phCalibrationFitErr35_C15.dat
[01:51:50.120] INFO: PixTestGainPedestal::doTest() done, duration: 198 seconds
[01:51:50.129] INFO: readReadbackCal: /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C15.dat
[01:51:50.131] INFO: PixTestReadback::doTest() start.
[01:51:50.132] INFO: PixTestReadback::RES sent once
[01:52:06.895] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C0.dat
[01:52:06.895] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C1.dat
[01:52:06.895] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C2.dat
[01:52:06.896] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C3.dat
[01:52:06.896] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C4.dat
[01:52:06.896] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C5.dat
[01:52:06.896] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C6.dat
[01:52:06.896] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C7.dat
[01:52:06.896] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C8.dat
[01:52:06.896] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C9.dat
[01:52:06.897] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C10.dat
[01:52:06.897] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C11.dat
[01:52:06.897] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C12.dat
[01:52:06.897] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C13.dat
[01:52:06.897] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C14.dat
[01:52:06.897] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C15.dat
[01:52:06.947] INFO: PixTestPattern:: pg_setup set to default.
[01:52:06.947] INFO: PixTestReadback::RES sent once
[01:52:23.672] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C0.dat
[01:52:23.673] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C1.dat
[01:52:23.673] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C2.dat
[01:52:23.673] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C3.dat
[01:52:23.673] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C4.dat
[01:52:23.673] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C5.dat
[01:52:23.673] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C6.dat
[01:52:23.673] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C7.dat
[01:52:23.673] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C8.dat
[01:52:23.673] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C9.dat
[01:52:23.673] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C10.dat
[01:52:23.673] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C11.dat
[01:52:23.674] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C12.dat
[01:52:23.674] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C13.dat
[01:52:23.674] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C14.dat
[01:52:23.674] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C15.dat
[01:52:23.730] INFO: PixTestPattern:: pg_setup set to default.
[01:52:23.731] INFO: PixTestReadback::RES sent once
[01:52:36.651] INFO: PixTestPattern:: pg_setup set to default.
[01:52:36.651] INFO: Vbg will be calibrated using Vd calibration
[01:52:36.651] INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 154calibrated Vbg = 1.21084 :::*/*/*/*/
[01:52:36.651] INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 152.4calibrated Vbg = 1.21358 :::*/*/*/*/
[01:52:36.651] INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 154.7calibrated Vbg = 1.21858 :::*/*/*/*/
[01:52:36.652] INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 150calibrated Vbg = 1.22286 :::*/*/*/*/
[01:52:36.652] INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 149.2calibrated Vbg = 1.22145 :::*/*/*/*/
[01:52:36.652] INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 150.6calibrated Vbg = 1.22641 :::*/*/*/*/
[01:52:36.652] INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 155.4calibrated Vbg = 1.22675 :::*/*/*/*/
[01:52:36.652] INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 161calibrated Vbg = 1.22655 :::*/*/*/*/
[01:52:36.652] INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 164.6calibrated Vbg = 1.22571 :::*/*/*/*/
[01:52:36.652] INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 159.7calibrated Vbg = 1.22612 :::*/*/*/*/
[01:52:36.652] INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 161.7calibrated Vbg = 1.22129 :::*/*/*/*/
[01:52:36.652] INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 160.6calibrated Vbg = 1.21256 :::*/*/*/*/
[01:52:36.652] INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 154.9calibrated Vbg = 1.21394 :::*/*/*/*/
[01:52:36.652] INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 154.1calibrated Vbg = 1.20931 :::*/*/*/*/
[01:52:36.652] INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 148.6calibrated Vbg = 1.21728 :::*/*/*/*/
[01:52:36.652] INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 153.8calibrated Vbg = 1.21209 :::*/*/*/*/
[01:52:36.656] INFO: PixTestReadback::RES sent once
[01:56:34.043] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C0.dat
[01:56:34.043] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C1.dat
[01:56:34.043] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C2.dat
[01:56:34.043] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C3.dat
[01:56:34.043] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C4.dat
[01:56:34.043] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C5.dat
[01:56:34.043] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C6.dat
[01:56:34.043] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C7.dat
[01:56:34.043] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C8.dat
[01:56:34.043] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C9.dat
[01:56:34.044] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C10.dat
[01:56:34.044] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C11.dat
[01:56:34.044] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C12.dat
[01:56:34.044] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C13.dat
[01:56:34.044] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C14.dat
[01:56:34.044] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4553_FullQualification_2015-08-14_15h47m_1439563624/003_Fulltest_p17/readbackCal_C15.dat
[01:56:34.094] INFO: PixTestPattern:: pg_setup set to default.
[01:56:34.098] INFO: PixTestReadback::doTest() done
[01:56:34.114] INFO: enter test to run
[01:56:34.114] INFO: test: q no parameter change
[01:56:34.265] QUIET: Connection to board 175 closed.
[01:56:34.268] INFO: pXar: this is the end, my friend