[12:56:57.467] INFO: *** Welcome to pxar ***
[12:56:57.467] INFO: *** Today: 2015/08/10
[12:56:57.467] INFO: readRocDacs: /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C15.dat
[12:56:57.468] INFO: readTbmDacs: /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/tbmParameters_C0a.dat .. /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/tbmParameters_C0b.dat
[12:56:57.468] INFO: readMaskFile: /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/defaultMaskFile.dat
[12:56:57.468] INFO: readTrimFile: /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters_C15.dat
[12:56:57.580] INFO: clk: 4
[12:56:57.580] INFO: ctr: 4
[12:56:57.580] INFO: sda: 19
[12:56:57.580] INFO: tin: 9
[12:56:57.580] INFO: level: 15
[12:56:57.580] INFO: triggerdelay: 0
[12:56:57.580] QUIET: Instanciating API for pxar prod-01+36~geb5ac3f
[12:56:57.580] INFO: Log level: INFO
[12:56:57.592] INFO: Found DTB DTB_X0DB1G
[12:56:57.611] QUIET: Connection to board DTB_X0DB1G opened.
[12:56:57.614] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 178
HW version: DTB1.2
FW version: 4.2
SW version: 4.2
USB id: DTB_X0DB1G
MAC address: 40D8551180B2
Hostname: pixelDTB178
Comment:
------------------------------------------------------
[12:56:57.616] INFO: RPC call hashes of host and DTB match: 447413373
[12:56:59.375] INFO: DUT info:
[12:56:59.375] INFO: The DUT currently contains the following objects:
[12:56:59.375] INFO: 2 TBM Cores tbm08c (2 ON)
[12:56:59.375] INFO: TBM Core alpha (0): 7 registers set
[12:56:59.375] INFO: TBM Core beta (1): 7 registers set
[12:56:59.375] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:56:59.375] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.375] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.375] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.375] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.375] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.375] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.375] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.375] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.375] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.375] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.375] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.376] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.376] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.376] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.376] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.376] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.777] INFO: enter 'restricted' command line mode
[12:56:59.777] INFO: enter test to run
[12:56:59.777] INFO: test: pretest no parameter change
[12:56:59.777] INFO: running: pretest
[12:56:59.783] INFO: ######################################################################
[12:56:59.783] INFO: PixTestPretest::doTest()
[12:56:59.783] INFO: ######################################################################
[12:56:59.785] INFO: ----------------------------------------------------------------------
[12:56:59.785] INFO: PixTestPretest::programROC()
[12:56:59.785] INFO: ----------------------------------------------------------------------
[12:57:17.805] INFO: PixTestPretest::programROC() done: ROCs are all programmable
[12:57:17.805] INFO: IA differences per ROC: 16.1 19.3 17.7 16.9 18.5 16.1 16.9 16.1 17.7 18.5 18.5 18.5 16.9 17.7 17.7 16.9
[12:57:17.882] INFO: ----------------------------------------------------------------------
[12:57:17.882] INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[12:57:17.882] INFO: ----------------------------------------------------------------------
[12:57:21.885] INFO: PixTestPretest::setVana() done, Module Ia 393.9 mA = 24.6187 mA/ROC
[12:57:21.889] INFO: ----------------------------------------------------------------------
[12:57:21.889] INFO: PixTestPretest::findWorkingPixel()
[12:57:21.889] INFO: ----------------------------------------------------------------------
[12:57:29.700] INFO: Test took 7804ms.
[12:57:30.038] INFO: Found working pixel in all ROCs: col/row = 12/22
[12:57:30.091] INFO: ----------------------------------------------------------------------
[12:57:30.091] INFO: PixTestPretest::setVthrCompCalDel()
[12:57:30.091] INFO: ----------------------------------------------------------------------
[12:57:37.923] INFO: Test took 7825ms.
[12:57:38.265] INFO: PixTestPretest::setVthrCompCalDel() done
[12:57:38.265] INFO: CalDel: 147 129 142 119 135 157 119 142 136 132 133 127 132 145 160 123
[12:57:38.265] INFO: VthrComp: 51 51 51 51 51 51 52 51 51 51 51 51 51 51 51 51
[12:57:38.270] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C0.dat
[12:57:38.270] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C1.dat
[12:57:38.270] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C2.dat
[12:57:38.271] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C3.dat
[12:57:38.271] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C4.dat
[12:57:38.271] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C5.dat
[12:57:38.271] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C6.dat
[12:57:38.272] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C7.dat
[12:57:38.272] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C8.dat
[12:57:38.272] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C9.dat
[12:57:38.272] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C10.dat
[12:57:38.273] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C11.dat
[12:57:38.273] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C12.dat
[12:57:38.273] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C13.dat
[12:57:38.273] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C14.dat
[12:57:38.273] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C15.dat
[12:57:38.274] INFO: PixTestPretest::doTest() done, duration: 38 seconds
[12:57:38.361] INFO: enter test to run
[12:57:38.361] INFO: test: fulltest no parameter change
[12:57:38.361] INFO: running: fulltest
[12:57:38.361] INFO: ######################################################################
[12:57:38.361] INFO: PixTestFullTest::doTest()
[12:57:38.361] INFO: ######################################################################
[12:57:38.363] INFO: ######################################################################
[12:57:38.363] INFO: PixTestAlive::doTest()
[12:57:38.363] INFO: ######################################################################
[12:57:38.364] INFO: ----------------------------------------------------------------------
[12:57:38.365] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:57:38.365] INFO: ----------------------------------------------------------------------
[12:57:42.516] INFO: Test took 4150ms.
[12:57:42.549] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:42.779] INFO: PixTestAlive::aliveTest() done
[12:57:42.779] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[12:57:42.780] INFO: ----------------------------------------------------------------------
[12:57:42.781] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:57:42.781] INFO: ----------------------------------------------------------------------
[12:57:45.733] INFO: Test took 2951ms.
[12:57:45.739] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:45.739] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:57:45.974] INFO: PixTestAlive::maskTest() done
[12:57:45.974] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:57:45.976] INFO: ----------------------------------------------------------------------
[12:57:45.976] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:57:45.976] INFO: ----------------------------------------------------------------------
[12:57:50.110] INFO: Test took 4132ms.
[12:57:50.142] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:50.378] INFO: PixTestAlive::addressDecodingTest() done
[12:57:50.378] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:57:50.378] INFO: PixTestAlive::doTest() done, duration: 12 seconds
[12:57:50.388] INFO: ######################################################################
[12:57:50.388] INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[12:57:50.388] INFO: ######################################################################
[12:57:50.391] INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30/5) hits flags = 2 (plus default)
[12:57:50.410] INFO: dacScan step from 0 .. 29
[12:57:50.410] INFO: dacScan split into 1 runs with ntrig = 5
[12:57:50.410] INFO: run 1 of 1
[12:58:09.522] INFO: Test took 19111ms.
[12:58:09.579] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:09.579] INFO: dacScan step from 30 .. 59
[12:58:09.579] INFO: dacScan split into 1 runs with ntrig = 5
[12:58:09.579] INFO: run 1 of 1
[12:58:29.089] INFO: Test took 19510ms.
[12:58:29.192] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:29.203] INFO: dacScan step from 60 .. 89
[12:58:29.203] INFO: dacScan split into 1 runs with ntrig = 5
[12:58:29.203] INFO: run 1 of 1
[12:58:54.559] INFO: Test took 25356ms.
[12:58:54.933] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:55.022] INFO: dacScan step from 90 .. 119
[12:58:55.022] INFO: dacScan split into 1 runs with ntrig = 5
[12:58:55.022] INFO: run 1 of 1
[12:59:22.226] INFO: Test took 27204ms.
[12:59:22.627] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:22.725] INFO: dacScan step from 120 .. 149
[12:59:22.725] INFO: dacScan split into 1 runs with ntrig = 5
[12:59:22.725] INFO: run 1 of 1
[12:59:48.803] INFO: Test took 26078ms.
[12:59:49.142] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:17.849] INFO: PixTestBBMap::doTest() done, duration: 147 seconds
[13:00:17.849] INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 1 0 1 0 0 0 0 0
[13:00:17.849] INFO: separation cut (per ROC): 114 107 101 115 107 87 108 116 101 123 100 113 94 101 102 114
[13:00:17.924] INFO: ######################################################################
[13:00:17.924] INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = 1
[13:00:17.924] INFO: ######################################################################
[13:00:17.924] INFO: ----------------------------------------------------------------------
[13:00:17.924] INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = 1
[13:00:17.924] INFO: ----------------------------------------------------------------------
[13:00:17.925] INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (-1/1) hits flags = 16 (plus default)
[13:00:17.938] INFO: dacScan split into 50 runs with ntrig = 1
[13:00:17.938] INFO: run 1 of 50
[13:01:06.726] INFO: Test took 48788ms.
[13:01:07.089] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:07.343] INFO: run 2 of 50
[13:01:56.130] INFO: Test took 48787ms.
[13:01:56.488] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:56.670] INFO: run 3 of 50
[13:02:45.395] INFO: Test took 48725ms.
[13:02:45.754] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:45.934] INFO: run 4 of 50
[13:03:34.741] INFO: Test took 48807ms.
[13:03:35.091] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:35.273] INFO: run 5 of 50
[13:04:24.106] INFO: Test took 48833ms.
[13:04:24.465] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:24.643] INFO: run 6 of 50
[13:05:13.426] INFO: Test took 48783ms.
[13:05:13.775] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:05:13.953] INFO: run 7 of 50
[13:06:02.784] INFO: Test took 48831ms.
[13:06:03.141] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:06:03.322] INFO: run 8 of 50
[13:06:52.065] INFO: Test took 48743ms.
[13:06:52.422] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:06:52.605] INFO: run 9 of 50
[13:07:41.386] INFO: Test took 48781ms.
[13:07:41.740] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:07:41.917] INFO: run 10 of 50
[13:08:30.667] INFO: Test took 48750ms.
[13:08:31.014] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:08:31.192] INFO: run 11 of 50
[13:09:19.870] INFO: Test took 48678ms.
[13:09:20.216] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:09:20.393] INFO: run 12 of 50
[13:10:09.121] INFO: Test took 48727ms.
[13:10:09.478] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:10:09.661] INFO: run 13 of 50
[13:10:58.389] INFO: Test took 48729ms.
[13:10:58.744] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:10:58.923] INFO: run 14 of 50
[13:11:47.833] INFO: Test took 48911ms.
[13:11:48.186] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:11:48.363] INFO: run 15 of 50
[13:12:37.156] INFO: Test took 48792ms.
[13:12:37.516] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:12:37.692] INFO: run 16 of 50
[13:13:26.504] INFO: Test took 48812ms.
[13:13:26.850] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:13:27.029] INFO: run 17 of 50
[13:14:15.907] INFO: Test took 48878ms.
[13:14:16.261] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:16.440] INFO: run 18 of 50
[13:15:05.242] INFO: Test took 48802ms.
[13:15:05.599] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:05.779] INFO: run 19 of 50
[13:15:54.558] INFO: Test took 48779ms.
[13:15:54.917] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:55.095] INFO: run 20 of 50
[13:16:43.964] INFO: Test took 48869ms.
[13:16:44.318] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:44.498] INFO: run 21 of 50
[13:17:33.421] INFO: Test took 48923ms.
[13:17:33.772] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:33.949] INFO: run 22 of 50
[13:18:22.845] INFO: Test took 48896ms.
[13:18:23.206] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:23.388] INFO: run 23 of 50
[13:19:12.159] INFO: Test took 48771ms.
[13:19:12.518] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:12.696] INFO: run 24 of 50
[13:20:01.632] INFO: Test took 48936ms.
[13:20:01.985] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:02.162] INFO: run 25 of 50
[13:20:51.076] INFO: Test took 48914ms.
[13:20:51.432] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:51.609] INFO: run 26 of 50
[13:21:40.506] INFO: Test took 48897ms.
[13:21:40.866] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:41.045] INFO: run 27 of 50
[13:22:29.870] INFO: Test took 48825ms.
[13:22:30.220] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:30.400] INFO: run 28 of 50
[13:23:19.247] INFO: Test took 48847ms.
[13:23:19.606] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:19.784] INFO: run 29 of 50
[13:24:08.569] INFO: Test took 48785ms.
[13:24:08.924] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:09.176] INFO: run 30 of 50
[13:24:58.070] INFO: Test took 48894ms.
[13:24:58.432] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:58.612] INFO: run 31 of 50
[13:25:47.500] INFO: Test took 48887ms.
[13:25:47.862] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:48.040] INFO: run 32 of 50
[13:26:36.979] INFO: Test took 48938ms.
[13:26:37.337] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:37.515] INFO: run 33 of 50
[13:27:26.397] INFO: Test took 48882ms.
[13:27:26.749] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:27:26.927] INFO: run 34 of 50
[13:28:15.801] INFO: Test took 48874ms.
[13:28:16.159] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:28:16.338] INFO: run 35 of 50
[13:29:05.209] INFO: Test took 48871ms.
[13:29:05.573] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:05.753] INFO: run 36 of 50
[13:29:54.681] INFO: Test took 48928ms.
[13:29:55.034] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:55.215] INFO: run 37 of 50
[13:30:44.194] INFO: Test took 48979ms.
[13:30:44.552] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:44.734] INFO: run 38 of 50
[13:31:33.686] INFO: Test took 48952ms.
[13:31:34.044] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:31:34.221] INFO: run 39 of 50
[13:32:23.120] INFO: Test took 48899ms.
[13:32:23.478] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:23.660] INFO: run 40 of 50
[13:33:12.543] INFO: Test took 48883ms.
[13:33:12.899] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:13.079] INFO: run 41 of 50
[13:34:01.986] INFO: Test took 48907ms.
[13:34:02.338] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:34:02.515] INFO: run 42 of 50
[13:34:51.335] INFO: Test took 48820ms.
[13:34:51.688] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:34:51.867] INFO: run 43 of 50
[13:35:40.774] INFO: Test took 48907ms.
[13:35:41.122] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:35:41.303] INFO: run 44 of 50
[13:36:30.140] INFO: Test took 48837ms.
[13:36:30.501] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:30.681] INFO: run 45 of 50
[13:37:19.522] INFO: Test took 48841ms.
[13:37:19.871] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:20.050] INFO: run 46 of 50
[13:38:08.839] INFO: Test took 48789ms.
[13:38:09.193] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:09.372] INFO: run 47 of 50
[13:38:58.230] INFO: Test took 48857ms.
[13:38:58.585] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:58.764] INFO: run 48 of 50
[13:39:47.665] INFO: Test took 48901ms.
[13:39:48.028] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:39:48.213] INFO: run 49 of 50
[13:40:37.116] INFO: Test took 48903ms.
[13:40:37.471] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:37.648] INFO: run 50 of 50
[13:41:26.618] INFO: Test took 48970ms.
[13:41:26.973] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:27.152] INFO: dumping ASCII scurve output file: SCurveData
[13:41:28.916] INFO: dumping ASCII scurve output file: SCurveData
[13:41:30.691] INFO: dumping ASCII scurve output file: SCurveData
[13:41:32.482] INFO: dumping ASCII scurve output file: SCurveData
[13:41:34.272] INFO: dumping ASCII scurve output file: SCurveData
[13:41:36.050] INFO: dumping ASCII scurve output file: SCurveData
[13:41:37.892] INFO: dumping ASCII scurve output file: SCurveData
[13:41:39.712] INFO: dumping ASCII scurve output file: SCurveData
[13:41:41.529] INFO: dumping ASCII scurve output file: SCurveData
[13:41:43.340] INFO: dumping ASCII scurve output file: SCurveData
[13:41:45.104] INFO: dumping ASCII scurve output file: SCurveData
[13:41:46.894] INFO: dumping ASCII scurve output file: SCurveData
[13:41:48.621] INFO: dumping ASCII scurve output file: SCurveData
[13:41:50.451] INFO: dumping ASCII scurve output file: SCurveData
[13:41:52.263] INFO: dumping ASCII scurve output file: SCurveData
[13:41:54.048] INFO: dumping ASCII scurve output file: SCurveData
[13:41:55.854] INFO: PixTestScurves::scurves() done
[13:41:55.854] INFO: Vcal mean: 87.40 96.79 88.39 96.86 93.02 82.93 100.42 91.53 86.73 105.23 89.69 110.82 84.86 89.10 94.31 104.33
[13:41:55.854] INFO: Vcal RMS: 6.64 5.98 5.51 6.71 6.14 4.66 6.26 6.03 5.50 5.98 5.57 5.78 5.00 5.35 5.28 5.54
[13:41:55.854] INFO: PixTestScurves::fullTest() done, duration: 2497 seconds
[13:41:55.940] INFO: ######################################################################
[13:41:55.940] INFO: PixTestTrim::doTest()
[13:41:55.940] INFO: ######################################################################
[13:41:55.941] INFO: ----------------------------------------------------------------------
[13:41:55.941] INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[13:41:55.941] INFO: ----------------------------------------------------------------------
[13:41:56.055] INFO: ---> VthrComp thr map (minimal VthrComp)
[13:41:56.055] INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[13:41:56.068] INFO: dacScan step from 0 .. 19
[13:41:56.068] INFO: dacScan split into 1 runs with ntrig = 5
[13:41:56.068] INFO: run 1 of 1
[13:42:09.463] INFO: Test took 13395ms.
[13:42:09.505] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:09.505] INFO: dacScan step from 20 .. 39
[13:42:09.505] INFO: dacScan split into 1 runs with ntrig = 5
[13:42:09.505] INFO: run 1 of 1
[13:42:23.005] INFO: Test took 13499ms.
[13:42:23.046] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:23.046] INFO: dacScan step from 40 .. 59
[13:42:23.046] INFO: dacScan split into 1 runs with ntrig = 5
[13:42:23.046] INFO: run 1 of 1
[13:42:36.412] INFO: Test took 13366ms.
[13:42:36.451] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:36.451] INFO: dacScan step from 60 .. 79
[13:42:36.451] INFO: dacScan split into 1 runs with ntrig = 5
[13:42:36.451] INFO: run 1 of 1
[13:42:49.860] INFO: Test took 13408ms.
[13:42:49.899] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:49.899] INFO: dacScan step from 80 .. 99
[13:42:49.899] INFO: dacScan split into 1 runs with ntrig = 5
[13:42:49.899] INFO: run 1 of 1
[13:43:03.539] INFO: Test took 13640ms.
[13:43:03.603] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:03.607] INFO: dacScan step from 100 .. 119
[13:43:03.607] INFO: dacScan split into 1 runs with ntrig = 5
[13:43:03.607] INFO: run 1 of 1
[13:43:19.784] INFO: Test took 16177ms.
[13:43:19.988] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:20.033] INFO: dacScan step from 120 .. 139
[13:43:20.033] INFO: dacScan split into 1 runs with ntrig = 5
[13:43:20.033] INFO: run 1 of 1
[13:43:38.903] INFO: Test took 18870ms.
[13:43:39.149] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:39.207] INFO: dacScan step from 140 .. 159
[13:43:39.207] INFO: dacScan split into 1 runs with ntrig = 5
[13:43:39.207] INFO: run 1 of 1
[13:43:54.325] INFO: Test took 15117ms.
[13:43:54.455] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:22.361] INFO: ROC 0 VthrComp = 90
[13:44:22.361] INFO: ROC 1 VthrComp = 98
[13:44:22.362] INFO: ROC 2 VthrComp = 92
[13:44:22.362] INFO: ROC 3 VthrComp = 97
[13:44:22.362] INFO: ROC 4 VthrComp = 99
[13:44:22.362] INFO: ROC 5 VthrComp = 87
[13:44:22.362] INFO: ROC 6 VthrComp = 100
[13:44:22.362] INFO: ROC 7 VthrComp = 89
[13:44:22.362] INFO: ROC 8 VthrComp = 92
[13:44:22.362] INFO: ROC 9 VthrComp = 106
[13:44:22.362] INFO: ROC 10 VthrComp = 95
[13:44:22.363] INFO: ROC 11 VthrComp = 106
[13:44:22.363] INFO: ROC 12 VthrComp = 89
[13:44:22.363] INFO: ROC 13 VthrComp = 95
[13:44:22.363] INFO: ROC 14 VthrComp = 98
[13:44:22.363] INFO: ROC 15 VthrComp = 100
[13:44:22.363] INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[13:44:22.363] INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[13:44:22.378] INFO: dacScan step from 0 .. 19
[13:44:22.378] INFO: dacScan split into 1 runs with ntrig = 5
[13:44:22.378] INFO: run 1 of 1
[13:44:35.893] INFO: Test took 13515ms.
[13:44:35.936] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:35.936] INFO: dacScan step from 20 .. 39
[13:44:35.936] INFO: dacScan split into 1 runs with ntrig = 5
[13:44:35.936] INFO: run 1 of 1
[13:44:49.493] INFO: Test took 13557ms.
[13:44:49.552] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:49.557] INFO: dacScan step from 40 .. 59
[13:44:49.557] INFO: dacScan split into 1 runs with ntrig = 5
[13:44:49.557] INFO: run 1 of 1
[13:45:06.438] INFO: Test took 16880ms.
[13:45:06.660] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:06.713] INFO: dacScan step from 60 .. 79
[13:45:06.713] INFO: dacScan split into 1 runs with ntrig = 5
[13:45:06.713] INFO: run 1 of 1
[13:45:25.620] INFO: Test took 18906ms.
[13:45:25.887] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:25.958] INFO: dacScan step from 80 .. 99
[13:45:25.958] INFO: dacScan split into 1 runs with ntrig = 5
[13:45:25.958] INFO: run 1 of 1
[13:45:44.775] INFO: Test took 18817ms.
[13:45:45.044] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:45.112] INFO: dacScan step from 100 .. 119
[13:45:45.112] INFO: dacScan split into 1 runs with ntrig = 5
[13:45:45.112] INFO: run 1 of 1
[13:46:03.904] INFO: Test took 18792ms.
[13:46:04.181] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:04.251] INFO: dacScan step from 120 .. 139
[13:46:04.251] INFO: dacScan split into 1 runs with ntrig = 5
[13:46:04.251] INFO: run 1 of 1
[13:46:24.531] INFO: Test took 20280ms.
[13:46:24.801] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:24.870] INFO: dacScan step from 140 .. 159
[13:46:24.870] INFO: dacScan split into 1 runs with ntrig = 5
[13:46:24.870] INFO: run 1 of 1
[13:46:43.989] INFO: Test took 19119ms.
[13:46:44.266] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:47:17.984] INFO: roc 0 with ID = 0 has maximal Vcal 63.2047 for pixel 5/3 mean/min/max = 47.5219/31.2148/63.8291
[13:47:17.985] INFO: roc 1 with ID = 1 has maximal Vcal 61.4406 for pixel 0/3 mean/min/max = 46.5593/31.5682/61.5504
[13:47:17.985] INFO: roc 2 with ID = 2 has maximal Vcal 58.9302 for pixel 36/79 mean/min/max = 45.8921/32.4562/59.3279
[13:47:17.986] INFO: roc 3 with ID = 3 has maximal Vcal 64.5056 for pixel 11/0 mean/min/max = 47.8871/31.1215/64.6527
[13:47:17.986] INFO: roc 4 with ID = 4 has maximal Vcal 59.7589 for pixel 18/10 mean/min/max = 45.4734/31.1132/59.8336
[13:47:17.987] INFO: roc 5 with ID = 5 has maximal Vcal 57.2591 for pixel 27/0 mean/min/max = 44.825/32.3174/57.3325
[13:47:17.987] INFO: roc 6 with ID = 6 has maximal Vcal 62.1823 for pixel 7/4 mean/min/max = 46.9346/31.4319/62.4373
[13:47:17.988] INFO: roc 7 with ID = 7 has maximal Vcal 63.3162 for pixel 4/46 mean/min/max = 47.8987/32.2783/63.5191
[13:47:17.988] INFO: roc 8 with ID = 8 has maximal Vcal 59.079 for pixel 7/79 mean/min/max = 45.6969/32.2807/59.1131
[13:47:17.989] INFO: roc 9 with ID = 9 has maximal Vcal 64.8223 for pixel 1/69 mean/min/max = 48.8907/32.9183/64.8632
[13:47:17.989] INFO: roc 10 with ID = 10 has maximal Vcal 58.2756 for pixel 0/79 mean/min/max = 44.5852/30.7675/58.403
[13:47:17.990] INFO: roc 11 with ID = 11 has maximal Vcal 66.0946 for pixel 5/69 mean/min/max = 49.8883/33.6418/66.1347
[13:47:17.990] INFO: roc 12 with ID = 12 has maximal Vcal 59.4064 for pixel 0/1 mean/min/max = 45.9962/32.5845/59.4079
[13:47:17.991] INFO: roc 13 with ID = 13 has maximal Vcal 57.3772 for pixel 19/65 mean/min/max = 44.6757/31.5611/57.7902
[13:47:17.991] INFO: roc 14 with ID = 14 has maximal Vcal 58.9186 for pixel 1/30 mean/min/max = 45.4561/31.79/59.1222
[13:47:17.992] INFO: roc 15 with ID = 15 has maximal Vcal 61.6926 for pixel 11/4 mean/min/max = 47.4292/33.0693/61.789
[13:47:17.992] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:48:41.030] INFO: Test took 83038ms.
[13:48:43.006] INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[13:48:43.020] INFO: dacScan step from 0 .. 19
[13:48:43.020] INFO: dacScan split into 2 runs with ntrig = 5
[13:48:43.020] INFO: run 1 of 2
[13:48:56.513] INFO: Test took 13493ms.
[13:48:56.570] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:56.572] INFO: run 2 of 2
[13:49:10.090] INFO: Test took 13518ms.
[13:49:10.145] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:10.149] INFO: dacScan step from 20 .. 39
[13:49:10.149] INFO: dacScan split into 2 runs with ntrig = 5
[13:49:10.149] INFO: run 1 of 2
[13:49:26.356] INFO: Test took 16207ms.
[13:49:26.557] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:26.604] INFO: run 2 of 2
[13:49:42.765] INFO: Test took 16161ms.
[13:49:42.974] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:43.024] INFO: dacScan step from 40 .. 59
[13:49:43.024] INFO: dacScan split into 2 runs with ntrig = 5
[13:49:43.024] INFO: run 1 of 2
[13:50:01.806] INFO: Test took 18782ms.
[13:50:02.073] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:02.144] INFO: run 2 of 2
[13:50:20.989] INFO: Test took 18845ms.
[13:50:21.295] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:21.367] INFO: dacScan step from 60 .. 79
[13:50:21.367] INFO: dacScan split into 2 runs with ntrig = 5
[13:50:21.367] INFO: run 1 of 2
[13:50:40.243] INFO: Test took 18876ms.
[13:50:40.521] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:40.589] INFO: run 2 of 2
[13:50:59.443] INFO: Test took 18854ms.
[13:50:59.716] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:59.786] INFO: dacScan step from 80 .. 99
[13:50:59.786] INFO: dacScan split into 2 runs with ntrig = 5
[13:50:59.786] INFO: run 1 of 2
[13:51:18.475] INFO: Test took 18689ms.
[13:51:18.747] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:18.823] INFO: run 2 of 2
[13:51:37.647] INFO: Test took 18824ms.
[13:51:37.928] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:37.999] INFO: dacScan step from 100 .. 119
[13:51:37.999] INFO: dacScan split into 2 runs with ntrig = 5
[13:51:37.999] INFO: run 1 of 2
[13:51:56.917] INFO: Test took 18918ms.
[13:51:57.189] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:57.259] INFO: run 2 of 2
[13:52:15.939] INFO: Test took 18680ms.
[13:52:16.208] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:16.276] INFO: dacScan step from 120 .. 139
[13:52:16.276] INFO: dacScan split into 2 runs with ntrig = 5
[13:52:16.276] INFO: run 1 of 2
[13:52:36.617] INFO: Test took 20340ms.
[13:52:36.893] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:36.961] INFO: run 2 of 2
[13:52:57.325] INFO: Test took 20364ms.
[13:52:57.604] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:57.674] INFO: dacScan step from 140 .. 159
[13:52:57.674] INFO: dacScan split into 2 runs with ntrig = 5
[13:52:57.674] INFO: run 1 of 2
[13:53:16.666] INFO: Test took 18992ms.
[13:53:16.934] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:16.002] INFO: run 2 of 2
[13:53:35.913] INFO: Test took 18911ms.
[13:53:36.177] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:36.245] INFO: dacScan step from 160 .. 179
[13:53:36.245] INFO: dacScan split into 2 runs with ntrig = 5
[13:53:36.245] INFO: run 1 of 2
[13:53:55.173] INFO: Test took 18927ms.
[13:53:55.463] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:55.535] INFO: run 2 of 2
[13:54:14.480] INFO: Test took 18945ms.
[13:54:14.748] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:14.819] INFO: dacScan step from 180 .. 199
[13:54:14.819] INFO: dacScan split into 2 runs with ntrig = 5
[13:54:14.819] INFO: run 1 of 2
[13:54:34.451] INFO: Test took 19632ms.
[13:54:34.714] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:34.781] INFO: run 2 of 2
[13:54:54.499] INFO: Test took 19718ms.
[13:54:54.769] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:55:29.258] INFO: ---> TrimStepCorr4 extremal thresholds: 0.074362 .. 255.000000
[13:55:29.385] INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 255 (20/-1) hits flags = 16 (plus default)
[13:55:29.400] INFO: dacScan step from 0 .. 19
[13:55:29.400] INFO: dacScan split into 1 runs with ntrig = 4
[13:55:29.400] INFO: run 1 of 1
[13:55:41.568] INFO: Test took 12168ms.
[13:55:41.605] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:55:41.605] INFO: dacScan step from 20 .. 39
[13:55:41.605] INFO: dacScan split into 1 runs with ntrig = 4
[13:55:41.605] INFO: run 1 of 1
[13:55:54.764] INFO: Test took 13159ms.
[13:55:54.897] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:55:54.931] INFO: dacScan step from 40 .. 59
[13:55:54.931] INFO: dacScan split into 1 runs with ntrig = 4
[13:55:54.931] INFO: run 1 of 1
[13:56:11.202] INFO: Test took 16271ms.
[13:56:11.479] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:11.557] INFO: dacScan step from 60 .. 79
[13:56:11.557] INFO: dacScan split into 1 runs with ntrig = 4
[13:56:11.557] INFO: run 1 of 1
[13:56:28.071] INFO: Test took 16514ms.
[13:56:28.295] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:28.361] INFO: dacScan step from 80 .. 99
[13:56:28.361] INFO: dacScan split into 1 runs with ntrig = 4
[13:56:28.361] INFO: run 1 of 1
[13:56:44.741] INFO: Test took 16379ms.
[13:56:44.965] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:45.036] INFO: dacScan step from 100 .. 119
[13:56:45.036] INFO: dacScan split into 1 runs with ntrig = 4
[13:56:45.036] INFO: run 1 of 1
[13:57:01.533] INFO: Test took 16497ms.
[13:57:01.762] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:01.828] INFO: dacScan step from 120 .. 139
[13:57:01.828] INFO: dacScan split into 1 runs with ntrig = 4
[13:57:01.828] INFO: run 1 of 1
[13:57:19.868] INFO: Test took 18040ms.
[13:57:20.096] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:20.164] INFO: dacScan step from 140 .. 159
[13:57:20.164] INFO: dacScan split into 1 runs with ntrig = 4
[13:57:20.164] INFO: run 1 of 1
[13:57:36.844] INFO: Test took 16680ms.
[13:57:37.067] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:37.135] INFO: dacScan step from 160 .. 179
[13:57:37.135] INFO: dacScan split into 1 runs with ntrig = 4
[13:57:37.135] INFO: run 1 of 1
[13:57:53.837] INFO: Test took 16702ms.
[13:57:54.055] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:54.122] INFO: dacScan step from 180 .. 199
[13:57:54.122] INFO: dacScan split into 1 runs with ntrig = 4
[13:57:54.122] INFO: run 1 of 1
[13:58:11.517] INFO: Test took 17395ms.
[13:58:11.750] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:11.819] INFO: dacScan step from 200 .. 219
[13:58:11.819] INFO: dacScan split into 1 runs with ntrig = 4
[13:58:11.819] INFO: run 1 of 1
[13:58:28.287] INFO: Test took 16467ms.
[13:58:28.516] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:28.584] INFO: dacScan step from 220 .. 239
[13:58:28.584] INFO: dacScan split into 1 runs with ntrig = 4
[13:58:28.584] INFO: run 1 of 1
[13:58:45.071] INFO: Test took 16486ms.
[13:58:45.294] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:45.363] INFO: dacScan step from 240 .. 255
[13:58:45.363] INFO: dacScan split into 1 runs with ntrig = 4
[13:58:45.363] INFO: run 1 of 1
[13:58:58.978] INFO: Test took 13615ms.
[13:58:59.191] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:59:47.350] INFO: ---> TrimStepCorr2 extremal thresholds: 13.018140 .. 44.644450
[13:59:47.488] INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 3 .. 54 (20/-1) hits flags = 16 (plus default)
[13:59:47.503] INFO: dacScan step from 3 .. 22
[13:59:47.503] INFO: dacScan split into 1 runs with ntrig = 4
[13:59:47.503] INFO: run 1 of 1
[13:59:59.639] INFO: Test took 12136ms.
[13:59:59.675] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:59:59.675] INFO: dacScan step from 23 .. 42
[13:59:59.675] INFO: dacScan split into 1 runs with ntrig = 4
[13:59:59.675] INFO: run 1 of 1
[14:00:13.550] INFO: Test took 13874ms.
[14:00:13.706] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:00:13.749] INFO: dacScan step from 43 .. 54
[14:00:13.749] INFO: dacScan split into 1 runs with ntrig = 4
[14:00:13.749] INFO: run 1 of 1
[14:00:24.739] INFO: Test took 10990ms.
[14:00:24.908] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:00:49.294] INFO: ---> TrimStepCorr1a extremal thresholds: 2.828159 .. 39.899971
[14:00:49.442] INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 2 .. 49 (20/-1) hits flags = 16 (plus default)
[14:00:49.456] INFO: dacScan step from 2 .. 21
[14:00:49.456] INFO: dacScan split into 1 runs with ntrig = 4
[14:00:49.456] INFO: run 1 of 1
[14:01:01.657] INFO: Test took 12201ms.
[14:01:01.693] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:01.693] INFO: dacScan step from 22 .. 41
[14:01:01.693] INFO: dacScan split into 1 runs with ntrig = 4
[14:01:01.693] INFO: run 1 of 1
[14:01:15.221] INFO: Test took 13528ms.
[14:01:15.346] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:15.379] INFO: dacScan step from 42 .. 49
[14:01:15.379] INFO: dacScan split into 1 runs with ntrig = 4
[14:01:15.379] INFO: run 1 of 1
[14:01:23.648] INFO: Test took 8269ms.
[14:01:23.748] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:44.610] INFO: ---> TrimStepCorr1b extremal thresholds: 2.828159 .. 39.045207
[14:01:44.741] INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 2 .. 49 (20/-1) hits flags = 16 (plus default)
[14:01:44.755] INFO: dacScan step from 2 .. 21
[14:01:44.755] INFO: dacScan split into 1 runs with ntrig = 4
[14:01:44.755] INFO: run 1 of 1
[14:01:56.908] INFO: Test took 12153ms.
[14:01:56.940] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:56.940] INFO: dacScan step from 22 .. 41
[14:01:56.940] INFO: dacScan split into 1 runs with ntrig = 4
[14:01:56.940] INFO: run 1 of 1
[14:02:10.328] INFO: Test took 13388ms.
[14:02:10.463] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:02:10.506] INFO: dacScan step from 42 .. 49
[14:02:10.506] INFO: dacScan split into 1 runs with ntrig = 4
[14:02:10.506] INFO: run 1 of 1
[14:02:18.807] INFO: Test took 8301ms.
[14:02:18.913] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:02:40.501] INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[14:02:40.501] INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20/5) hits flags = 16 (plus default)
[14:02:40.516] INFO: dacScan step from 15 .. 34
[14:02:40.516] INFO: dacScan split into 2 runs with ntrig = 5
[14:02:40.516] INFO: run 1 of 2
[14:02:54.356] INFO: Test took 13840ms.
[14:02:54.433] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:02:54.447] INFO: run 2 of 2
[14:03:08.222] INFO: Test took 13775ms.
[14:03:08.298] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:08.317] INFO: dacScan step from 35 .. 54
[14:03:08.317] INFO: dacScan split into 2 runs with ntrig = 5
[14:03:08.317] INFO: run 1 of 2
[14:03:26.709] INFO: Test took 18392ms.
[14:03:27.036] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:27.121] INFO: run 2 of 2
[14:03:45.538] INFO: Test took 18416ms.
[14:03:45.859] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:45.945] INFO: dacScan step from 55 .. 55
[14:03:45.945] INFO: dacScan split into 2 runs with ntrig = 5
[14:03:45.945] INFO: run 1 of 2
[14:03:49.605] INFO: Test took 3660ms.
[14:03:49.626] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:49.633] INFO: run 2 of 2
[14:03:53.335] INFO: Test took 3701ms.
[14:03:53.359] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:13.803] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C0.dat
[14:04:13.803] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C1.dat
[14:04:13.803] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C2.dat
[14:04:13.803] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C3.dat
[14:04:13.804] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C4.dat
[14:04:13.804] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C5.dat
[14:04:13.804] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C6.dat
[14:04:13.804] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C7.dat
[14:04:13.804] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C8.dat
[14:04:13.804] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C9.dat
[14:04:13.804] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C10.dat
[14:04:13.804] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C11.dat
[14:04:13.804] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C12.dat
[14:04:13.804] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C13.dat
[14:04:13.805] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C14.dat
[14:04:13.805] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C15.dat
[14:04:13.805] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C0.dat
[14:04:13.819] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C1.dat
[14:04:13.834] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C2.dat
[14:04:13.849] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C3.dat
[14:04:13.863] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C4.dat
[14:04:13.878] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C5.dat
[14:04:13.893] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C6.dat
[14:04:13.909] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C7.dat
[14:04:13.922] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C8.dat
[14:04:13.937] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C9.dat
[14:04:13.952] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C10.dat
[14:04:13.965] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C11.dat
[14:04:13.980] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C12.dat
[14:04:13.993] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C13.dat
[14:04:14.008] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C14.dat
[14:04:14.022] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C15.dat
[14:04:14.036] INFO: PixTestTrim::trimTest() done
[14:04:14.036] INFO: vtrim: 123 116 109 133 127 101 116 116 112 144 106 130 111 102 118 115
[14:04:14.037] INFO: vthrcomp: 90 98 92 97 99 87 100 89 92 106 95 106 89 95 98 100
[14:04:14.037] INFO: vcal mean: 35.06 35.05 35.05 34.97 35.05 35.07 35.09 35.01 35.07 35.11 34.98 35.10 35.07 35.02 35.04 35.11
[14:04:14.037] INFO: vcal RMS: 1.23 1.18 1.02 1.26 1.10 1.11 1.29 1.29 1.09 1.07 1.14 1.22 1.07 1.26 1.10 1.17
[14:04:14.037] INFO: bits mean: 9.65 9.55 9.44 9.60 10.18 10.21 9.94 9.56 10.11 9.25 10.04 8.79 9.54 9.89 10.12 9.48
[14:04:14.037] INFO: bits RMS: 2.56 2.66 2.62 2.61 2.51 2.37 2.43 2.54 2.33 2.44 2.69 2.46 2.60 2.64 2.44 2.47
[14:04:14.047] INFO: ----------------------------------------------------------------------
[14:04:14.048] INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[14:04:14.048] INFO: ----------------------------------------------------------------------
[14:04:14.051] INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[14:04:14.067] INFO: dacScan step from 0 .. 19
[14:04:14.067] INFO: dacScan split into 2 runs with ntrig = 5
[14:04:14.067] INFO: run 1 of 2
[14:04:27.568] INFO: Test took 13501ms.
[14:04:27.609] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:27.609] INFO: run 2 of 2
[14:04:41.108] INFO: Test took 13499ms.
[14:04:41.149] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:41.149] INFO: dacScan step from 20 .. 39
[14:04:41.149] INFO: dacScan split into 2 runs with ntrig = 5
[14:04:41.149] INFO: run 1 of 2
[14:04:54.579] INFO: Test took 13430ms.
[14:04:54.623] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:54.623] INFO: run 2 of 2
[14:05:08.101] INFO: Test took 13478ms.
[14:05:08.146] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:08.146] INFO: dacScan step from 40 .. 59
[14:05:08.146] INFO: dacScan split into 2 runs with ntrig = 5
[14:05:08.146] INFO: run 1 of 2
[14:05:21.737] INFO: Test took 13591ms.
[14:05:21.776] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:21.776] INFO: run 2 of 2
[14:05:35.266] INFO: Test took 13490ms.
[14:05:35.310] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:35.310] INFO: dacScan step from 60 .. 79
[14:05:35.310] INFO: dacScan split into 2 runs with ntrig = 5
[14:05:35.310] INFO: run 1 of 2
[14:05:48.877] INFO: Test took 13567ms.
[14:05:48.916] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:48.916] INFO: run 2 of 2
[14:06:02.365] INFO: Test took 13448ms.
[14:06:02.408] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:02.408] INFO: dacScan step from 80 .. 99
[14:06:02.408] INFO: dacScan split into 2 runs with ntrig = 5
[14:06:02.408] INFO: run 1 of 2
[14:06:15.953] INFO: Test took 13545ms.
[14:06:15.997] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:15.998] INFO: run 2 of 2
[14:06:29.502] INFO: Test took 13504ms.
[14:06:29.547] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:29.547] INFO: dacScan step from 100 .. 119
[14:06:29.548] INFO: dacScan split into 2 runs with ntrig = 5
[14:06:29.548] INFO: run 1 of 2
[14:06:44.399] INFO: Test took 14851ms.
[14:06:44.552] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:44.577] INFO: run 2 of 2
[14:06:59.344] INFO: Test took 14767ms.
[14:06:59.465] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:59.485] INFO: dacScan step from 120 .. 139
[14:06:59.485] INFO: dacScan split into 2 runs with ntrig = 5
[14:06:59.485] INFO: run 1 of 2
[14:07:18.708] INFO: Test took 19223ms.
[14:07:18.943] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:07:18.001] INFO: run 2 of 2
[14:07:38.121] INFO: Test took 19120ms.
[14:07:38.363] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:07:38.425] INFO: dacScan step from 140 .. 159
[14:07:38.425] INFO: dacScan split into 2 runs with ntrig = 5
[14:07:38.425] INFO: run 1 of 2
[14:07:57.488] INFO: Test took 19063ms.
[14:07:57.746] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:07:57.817] INFO: run 2 of 2
[14:08:16.663] INFO: Test took 18846ms.
[14:08:16.932] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:08:16.001] INFO: dacScan step from 160 .. 179
[14:08:16.001] INFO: dacScan split into 2 runs with ntrig = 5
[14:08:16.001] INFO: run 1 of 2
[14:08:35.917] INFO: Test took 18916ms.
[14:08:36.223] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:08:36.297] INFO: run 2 of 2
[14:08:55.369] INFO: Test took 19072ms.
[14:08:55.633] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:08:55.703] INFO: dacScan step from 180 .. 199
[14:08:55.703] INFO: dacScan split into 2 runs with ntrig = 5
[14:08:55.703] INFO: run 1 of 2
[14:09:15.396] INFO: Test took 19692ms.
[14:09:15.656] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:15.723] INFO: run 2 of 2
[14:09:35.276] INFO: Test took 19554ms.
[14:09:35.538] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:10.426] INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 181 (20/5) hits flags = 16 (plus default)
[14:10:10.444] INFO: dacScan step from 0 .. 19
[14:10:10.444] INFO: dacScan split into 2 runs with ntrig = 5
[14:10:10.444] INFO: run 1 of 2
[14:10:23.952] INFO: Test took 13508ms.
[14:10:23.991] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:23.991] INFO: run 2 of 2
[14:10:37.349] INFO: Test took 13357ms.
[14:10:37.389] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:37.389] INFO: dacScan step from 20 .. 39
[14:10:37.389] INFO: dacScan split into 2 runs with ntrig = 5
[14:10:37.389] INFO: run 1 of 2
[14:10:50.839] INFO: Test took 13449ms.
[14:10:50.887] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:50.887] INFO: run 2 of 2
[14:11:04.402] INFO: Test took 13515ms.
[14:11:04.444] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:11:04.444] INFO: dacScan step from 40 .. 59
[14:11:04.444] INFO: dacScan split into 2 runs with ntrig = 5
[14:11:04.444] INFO: run 1 of 2
[14:11:17.842] INFO: Test took 13398ms.
[14:11:17.879] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:11:17.880] INFO: run 2 of 2
[14:11:31.270] INFO: Test took 13390ms.
[14:11:31.308] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:11:31.308] INFO: dacScan step from 60 .. 79
[14:11:31.308] INFO: dacScan split into 2 runs with ntrig = 5
[14:11:31.308] INFO: run 1 of 2
[14:11:44.754] INFO: Test took 13445ms.
[14:11:44.796] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:11:44.796] INFO: run 2 of 2
[14:11:58.070] INFO: Test took 13273ms.
[14:11:58.108] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:11:58.108] INFO: dacScan step from 80 .. 99
[14:11:58.108] INFO: dacScan split into 2 runs with ntrig = 5
[14:11:58.108] INFO: run 1 of 2
[14:12:11.729] INFO: Test took 13621ms.
[14:12:11.789] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:11.793] INFO: run 2 of 2
[14:12:25.453] INFO: Test took 13660ms.
[14:12:25.513] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:25.517] INFO: dacScan step from 100 .. 119
[14:12:25.517] INFO: dacScan split into 2 runs with ntrig = 5
[14:12:25.517] INFO: run 1 of 2
[14:12:41.668] INFO: Test took 16151ms.
[14:12:41.887] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:41.933] INFO: run 2 of 2
[14:12:58.071] INFO: Test took 16138ms.
[14:12:58.287] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:58.329] INFO: dacScan step from 120 .. 139
[14:12:58.329] INFO: dacScan split into 2 runs with ntrig = 5
[14:12:58.330] INFO: run 1 of 2
[14:13:18.392] INFO: Test took 20062ms.
[14:13:18.648] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:18.715] INFO: run 2 of 2
[14:13:38.658] INFO: Test took 19943ms.
[14:13:38.914] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:38.985] INFO: dacScan step from 140 .. 159
[14:13:38.985] INFO: dacScan split into 2 runs with ntrig = 5
[14:13:38.985] INFO: run 1 of 2
[14:13:58.005] INFO: Test took 19019ms.
[14:13:58.266] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:58.334] INFO: run 2 of 2
[14:14:17.330] INFO: Test took 18996ms.
[14:14:17.606] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:14:17.680] INFO: dacScan step from 160 .. 179
[14:14:17.680] INFO: dacScan split into 2 runs with ntrig = 5
[14:14:17.680] INFO: run 1 of 2
[14:14:36.592] INFO: Test took 18912ms.
[14:14:36.909] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:14:36.986] INFO: run 2 of 2
[14:14:56.116] INFO: Test took 19130ms.
[14:14:56.379] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:14:56.449] INFO: dacScan step from 180 .. 181
[14:14:56.449] INFO: dacScan split into 2 runs with ntrig = 5
[14:14:56.449] INFO: run 1 of 2
[14:15:00.860] INFO: Test took 4411ms.
[14:15:00.891] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:00.901] INFO: run 2 of 2
[14:15:05.247] INFO: Test took 4345ms.
[14:15:05.281] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:39.632] INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 169 (20/5) hits flags = 16 (plus default)
[14:15:39.646] INFO: dacScan step from 0 .. 19
[14:15:39.646] INFO: dacScan split into 2 runs with ntrig = 5
[14:15:39.646] INFO: run 1 of 2
[14:15:53.116] INFO: Test took 13470ms.
[14:15:53.157] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:53.157] INFO: run 2 of 2
[14:16:06.639] INFO: Test took 13482ms.
[14:16:06.680] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:06.680] INFO: dacScan step from 20 .. 39
[14:16:06.680] INFO: dacScan split into 2 runs with ntrig = 5
[14:16:06.680] INFO: run 1 of 2
[14:16:20.070] INFO: Test took 13390ms.
[14:16:20.112] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:20.112] INFO: run 2 of 2
[14:16:33.541] INFO: Test took 13429ms.
[14:16:33.581] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:33.581] INFO: dacScan step from 40 .. 59
[14:16:33.581] INFO: dacScan split into 2 runs with ntrig = 5
[14:16:33.581] INFO: run 1 of 2
[14:16:47.007] INFO: Test took 13426ms.
[14:16:47.048] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:47.048] INFO: run 2 of 2
[14:17:00.556] INFO: Test took 13507ms.
[14:17:00.593] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:00.593] INFO: dacScan step from 60 .. 79
[14:17:00.593] INFO: dacScan split into 2 runs with ntrig = 5
[14:17:00.593] INFO: run 1 of 2
[14:17:14.019] INFO: Test took 13426ms.
[14:17:14.059] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:14.059] INFO: run 2 of 2
[14:17:27.460] INFO: Test took 13401ms.
[14:17:27.498] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:27.498] INFO: dacScan step from 80 .. 99
[14:17:27.498] INFO: dacScan split into 2 runs with ntrig = 5
[14:17:27.498] INFO: run 1 of 2
[14:17:41.076] INFO: Test took 13577ms.
[14:17:41.137] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:41.143] INFO: run 2 of 2
[14:17:54.744] INFO: Test took 13600ms.
[14:17:54.810] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:54.815] INFO: dacScan step from 100 .. 119
[14:17:54.815] INFO: dacScan split into 2 runs with ntrig = 5
[14:17:54.815] INFO: run 1 of 2
[14:18:11.011] INFO: Test took 16196ms.
[14:18:11.224] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:18:11.270] INFO: run 2 of 2
[14:18:27.498] INFO: Test took 16228ms.
[14:18:27.723] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:18:27.773] INFO: dacScan step from 120 .. 139
[14:18:27.773] INFO: dacScan split into 2 runs with ntrig = 5
[14:18:27.773] INFO: run 1 of 2
[14:18:47.912] INFO: Test took 20139ms.
[14:18:48.192] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:18:48.258] INFO: run 2 of 2
[14:19:08.370] INFO: Test took 20111ms.
[14:19:08.619] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:08.685] INFO: dacScan step from 140 .. 159
[14:19:08.685] INFO: dacScan split into 2 runs with ntrig = 5
[14:19:08.685] INFO: run 1 of 2
[14:19:27.723] INFO: Test took 19038ms.
[14:19:27.983] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:28.050] INFO: run 2 of 2
[14:19:47.130] INFO: Test took 19079ms.
[14:19:47.395] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:47.462] INFO: dacScan step from 160 .. 169
[14:19:47.462] INFO: dacScan split into 2 runs with ntrig = 5
[14:19:47.462] INFO: run 1 of 2
[14:19:58.293] INFO: Test took 10830ms.
[14:19:58.423] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:58.463] INFO: run 2 of 2
[14:20:09.268] INFO: Test took 10805ms.
[14:20:09.395] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:43.116] INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 168 (20/5) hits flags = 16 (plus default)
[14:20:43.131] INFO: dacScan step from 0 .. 19
[14:20:43.131] INFO: dacScan split into 2 runs with ntrig = 5
[14:20:43.131] INFO: run 1 of 2
[14:20:56.681] INFO: Test took 13550ms.
[14:20:56.723] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:56.723] INFO: run 2 of 2
[14:21:10.235] INFO: Test took 13511ms.
[14:21:10.274] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:10.274] INFO: dacScan step from 20 .. 39
[14:21:10.274] INFO: dacScan split into 2 runs with ntrig = 5
[14:21:10.274] INFO: run 1 of 2
[14:21:23.690] INFO: Test took 13416ms.
[14:21:23.729] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:23.729] INFO: run 2 of 2
[14:21:37.043] INFO: Test took 13314ms.
[14:21:37.083] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:37.083] INFO: dacScan step from 40 .. 59
[14:21:37.083] INFO: dacScan split into 2 runs with ntrig = 5
[14:21:37.083] INFO: run 1 of 2
[14:21:50.445] INFO: Test took 13362ms.
[14:21:50.485] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:50.485] INFO: run 2 of 2
[14:22:03.945] INFO: Test took 13460ms.
[14:22:03.985] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:03.985] INFO: dacScan step from 60 .. 79
[14:22:03.985] INFO: dacScan split into 2 runs with ntrig = 5
[14:22:03.985] INFO: run 1 of 2
[14:22:17.343] INFO: Test took 13357ms.
[14:22:17.380] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:17.381] INFO: run 2 of 2
[14:22:30.689] INFO: Test took 13308ms.
[14:22:30.725] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:30.725] INFO: dacScan step from 80 .. 99
[14:22:30.725] INFO: dacScan split into 2 runs with ntrig = 5
[14:22:30.725] INFO: run 1 of 2
[14:22:44.372] INFO: Test took 13647ms.
[14:22:44.435] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:44.442] INFO: run 2 of 2
[14:22:58.011] INFO: Test took 13569ms.
[14:22:58.073] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:58.079] INFO: dacScan step from 100 .. 119
[14:22:58.079] INFO: dacScan split into 2 runs with ntrig = 5
[14:22:58.079] INFO: run 1 of 2
[14:23:14.087] INFO: Test took 16007ms.
[14:23:14.279] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:14.321] INFO: run 2 of 2
[14:23:30.485] INFO: Test took 16164ms.
[14:23:30.711] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:30.756] INFO: dacScan step from 120 .. 139
[14:23:30.756] INFO: dacScan split into 2 runs with ntrig = 5
[14:23:30.756] INFO: run 1 of 2
[14:23:50.788] INFO: Test took 20032ms.
[14:23:51.099] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:51.179] INFO: run 2 of 2
[14:24:11.303] INFO: Test took 20124ms.
[14:24:11.555] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:11.622] INFO: dacScan step from 140 .. 159
[14:24:11.622] INFO: dacScan split into 2 runs with ntrig = 5
[14:24:11.622] INFO: run 1 of 2
[14:24:30.640] INFO: Test took 19017ms.
[14:24:30.901] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:30.972] INFO: run 2 of 2
[14:24:49.886] INFO: Test took 18914ms.
[14:24:50.143] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:50.211] INFO: dacScan step from 160 .. 168
[14:24:50.211] INFO: dacScan split into 2 runs with ntrig = 5
[14:24:50.211] INFO: run 1 of 2
[14:25:00.163] INFO: Test took 9952ms.
[14:25:00.287] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:00.323] INFO: run 2 of 2
[14:25:10.361] INFO: Test took 10038ms.
[14:25:10.478] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:41.147] INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 168 (20/5) hits flags = 16 (plus default)
[14:25:41.165] INFO: dacScan step from 0 .. 19
[14:25:41.165] INFO: dacScan split into 2 runs with ntrig = 5
[14:25:41.165] INFO: run 1 of 2
[14:25:54.639] INFO: Test took 13474ms.
[14:25:54.675] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:54.675] INFO: run 2 of 2
[14:26:08.079] INFO: Test took 13403ms.
[14:26:08.117] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:08.118] INFO: dacScan step from 20 .. 39
[14:26:08.118] INFO: dacScan split into 2 runs with ntrig = 5
[14:26:08.118] INFO: run 1 of 2
[14:26:21.608] INFO: Test took 13490ms.
[14:26:21.655] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:21.655] INFO: run 2 of 2
[14:26:35.221] INFO: Test took 13566ms.
[14:26:35.264] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:35.264] INFO: dacScan step from 40 .. 59
[14:26:35.264] INFO: dacScan split into 2 runs with ntrig = 5
[14:26:35.264] INFO: run 1 of 2
[14:26:48.749] INFO: Test took 13484ms.
[14:26:48.794] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:48.794] INFO: run 2 of 2
[14:27:02.363] INFO: Test took 13568ms.
[14:27:02.402] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:02.402] INFO: dacScan step from 60 .. 79
[14:27:02.402] INFO: dacScan split into 2 runs with ntrig = 5
[14:27:02.402] INFO: run 1 of 2
[14:27:15.962] INFO: Test took 13560ms.
[14:27:15.001] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:15.001] INFO: run 2 of 2
[14:27:29.418] INFO: Test took 13417ms.
[14:27:29.457] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:29.457] INFO: dacScan step from 80 .. 99
[14:27:29.457] INFO: dacScan split into 2 runs with ntrig = 5
[14:27:29.457] INFO: run 1 of 2
[14:27:42.990] INFO: Test took 13533ms.
[14:27:43.051] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:43.058] INFO: run 2 of 2
[14:27:56.636] INFO: Test took 13578ms.
[14:27:56.696] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:56.703] INFO: dacScan step from 100 .. 119
[14:27:56.703] INFO: dacScan split into 2 runs with ntrig = 5
[14:27:56.703] INFO: run 1 of 2
[14:28:12.799] INFO: Test took 16096ms.
[14:28:12.992] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:28:13.037] INFO: run 2 of 2
[14:28:29.211] INFO: Test took 16174ms.
[14:28:29.424] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:28:29.471] INFO: dacScan step from 120 .. 139
[14:28:29.471] INFO: dacScan split into 2 runs with ntrig = 5
[14:28:29.471] INFO: run 1 of 2
[14:28:49.644] INFO: Test took 20173ms.
[14:28:49.942] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:28:50.023] INFO: run 2 of 2
[14:29:10.144] INFO: Test took 20121ms.
[14:29:10.409] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:29:10.478] INFO: dacScan step from 140 .. 159
[14:29:10.478] INFO: dacScan split into 2 runs with ntrig = 5
[14:29:10.478] INFO: run 1 of 2
[14:29:29.578] INFO: Test took 19100ms.
[14:29:29.840] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:29:29.907] INFO: run 2 of 2
[14:29:48.900] INFO: Test took 18993ms.
[14:29:49.160] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:29:49.228] INFO: dacScan step from 160 .. 168
[14:29:49.228] INFO: dacScan split into 2 runs with ntrig = 5
[14:29:49.228] INFO: run 1 of 2
[14:29:59.272] INFO: Test took 10044ms.
[14:29:59.395] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:29:59.428] INFO: run 2 of 2
[14:30:09.522] INFO: Test took 10094ms.
[14:30:09.637] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:30:40.778] INFO: PixTestTrim::trimBitTest() done
[14:30:40.780] INFO: PixTestTrim::doTest() done, duration: 2924 seconds
[14:30:41.752] INFO: ######################################################################
[14:30:41.752] INFO: PixTestPhOptimization::doTest() Ntrig = 10
[14:30:41.752] INFO: ######################################################################
[14:30:46.032] INFO: Test took 4279ms.
[14:30:46.065] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:30:50.482] INFO: Test took 4216ms.
[14:30:50.615] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:30:54.806] INFO: Test took 4175ms.
[14:30:54.922] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:30:54.932] INFO: The DUT currently contains the following objects:
[14:30:54.932] INFO: 2 TBM Cores tbm08c (2 ON)
[14:30:54.932] INFO: TBM Core alpha (0): 7 registers set
[14:30:54.932] INFO: TBM Core beta (1): 7 registers set
[14:30:54.932] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:30:54.932] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:54.932] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:54.932] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:54.932] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:54.932] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:54.932] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:54.932] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:54.932] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:54.932] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:54.932] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:54.932] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:54.932] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:54.932] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:54.932] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:54.932] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:54.932] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:56.027] INFO: Test took 1095ms.
[14:30:56.029] INFO: The DUT currently contains the following objects:
[14:30:56.029] INFO: 2 TBM Cores tbm08c (2 ON)
[14:30:56.029] INFO: TBM Core alpha (0): 7 registers set
[14:30:56.029] INFO: TBM Core beta (1): 7 registers set
[14:30:56.029] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:30:56.029] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:56.029] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:56.029] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:56.029] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:56.029] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:56.029] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:56.029] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:56.029] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:56.029] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:56.029] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:56.029] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:56.029] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:56.029] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:56.029] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:56.029] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:56.029] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:57.125] INFO: Test took 1096ms.
[14:30:57.128] INFO: The DUT currently contains the following objects:
[14:30:57.128] INFO: 2 TBM Cores tbm08c (2 ON)
[14:30:57.128] INFO: TBM Core alpha (0): 7 registers set
[14:30:57.128] INFO: TBM Core beta (1): 7 registers set
[14:30:57.128] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:30:57.128] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:57.128] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:57.128] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:57.128] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:57.128] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:57.128] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:57.128] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:57.128] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:57.128] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:57.128] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:57.128] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:57.128] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:57.128] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:57.128] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:57.128] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:57.128] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:58.221] INFO: Test took 1094ms.
[14:30:58.223] INFO: The DUT currently contains the following objects:
[14:30:58.223] INFO: 2 TBM Cores tbm08c (2 ON)
[14:30:58.223] INFO: TBM Core alpha (0): 7 registers set
[14:30:58.223] INFO: TBM Core beta (1): 7 registers set
[14:30:58.223] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:30:58.223] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:58.223] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:58.223] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:58.223] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:58.223] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:58.223] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:58.223] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:58.223] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:58.223] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:58.223] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:58.223] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:58.223] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:58.223] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:58.223] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:58.223] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:58.223] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:59.320] INFO: Test took 1097ms.
[14:30:59.322] INFO: The DUT currently contains the following objects:
[14:30:59.322] INFO: 2 TBM Cores tbm08c (2 ON)
[14:30:59.322] INFO: TBM Core alpha (0): 7 registers set
[14:30:59.322] INFO: TBM Core beta (1): 7 registers set
[14:30:59.322] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:30:59.322] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:59.322] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:59.322] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:59.322] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:59.322] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:59.322] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:59.322] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:59.322] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:59.323] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:59.323] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:59.323] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:59.323] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:59.323] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:59.323] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:59.323] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:30:59.323] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:00.418] INFO: Test took 1095ms.
[14:31:00.420] INFO: The DUT currently contains the following objects:
[14:31:00.420] INFO: 2 TBM Cores tbm08c (2 ON)
[14:31:00.420] INFO: TBM Core alpha (0): 7 registers set
[14:31:00.420] INFO: TBM Core beta (1): 7 registers set
[14:31:00.420] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:31:00.420] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:00.420] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:00.420] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:00.420] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:00.420] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:00.420] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:00.420] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:00.420] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:00.420] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:00.420] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:00.420] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:00.420] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:00.420] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:00.420] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:00.420] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:00.420] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:01.517] INFO: Test took 1096ms.
[14:31:01.519] INFO: The DUT currently contains the following objects:
[14:31:01.519] INFO: 2 TBM Cores tbm08c (2 ON)
[14:31:01.519] INFO: TBM Core alpha (0): 7 registers set
[14:31:01.519] INFO: TBM Core beta (1): 7 registers set
[14:31:01.519] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:31:01.519] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:01.520] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:01.520] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:01.520] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:01.520] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:01.520] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:01.520] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:01.520] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:01.520] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:01.520] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:01.520] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:01.520] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:01.520] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:01.520] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:01.520] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:01.520] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:02.617] INFO: Test took 1097ms.
[14:31:02.619] INFO: The DUT currently contains the following objects:
[14:31:02.619] INFO: 2 TBM Cores tbm08c (2 ON)
[14:31:02.619] INFO: TBM Core alpha (0): 7 registers set
[14:31:02.619] INFO: TBM Core beta (1): 7 registers set
[14:31:02.619] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:31:02.619] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:02.619] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:02.619] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:02.619] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:02.619] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:02.619] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:02.619] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:02.620] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:02.620] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:02.620] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:02.620] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:02.620] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:02.620] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:02.620] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:02.620] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:02.620] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:03.716] INFO: Test took 1096ms.
[14:31:03.718] INFO: The DUT currently contains the following objects:
[14:31:03.718] INFO: 2 TBM Cores tbm08c (2 ON)
[14:31:03.718] INFO: TBM Core alpha (0): 7 registers set
[14:31:03.718] INFO: TBM Core beta (1): 7 registers set
[14:31:03.718] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:31:03.718] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:03.718] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:03.718] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:03.718] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:03.718] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:03.718] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:03.718] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:03.718] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:03.718] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:03.718] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:03.718] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:03.718] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:03.718] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:03.718] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:03.718] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:03.718] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:04.815] INFO: Test took 1097ms.
[14:31:04.818] INFO: The DUT currently contains the following objects:
[14:31:04.818] INFO: 2 TBM Cores tbm08c (2 ON)
[14:31:04.818] INFO: TBM Core alpha (0): 7 registers set
[14:31:04.818] INFO: TBM Core beta (1): 7 registers set
[14:31:04.818] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:31:04.818] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:04.818] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:04.818] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:04.818] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:04.818] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:04.818] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:04.818] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:04.818] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:04.818] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:04.818] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:04.818] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:04.818] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:04.818] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:04.818] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:04.818] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:04.818] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:05.910] INFO: Test took 1092ms.
[14:31:05.911] INFO: The DUT currently contains the following objects:
[14:31:05.911] INFO: 2 TBM Cores tbm08c (2 ON)
[14:31:05.911] INFO: TBM Core alpha (0): 7 registers set
[14:31:05.911] INFO: TBM Core beta (1): 7 registers set
[14:31:05.911] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:31:05.911] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:05.911] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:05.911] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:05.911] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:05.911] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:05.911] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:05.911] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:05.911] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:05.911] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:05.911] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:05.911] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:05.911] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:05.911] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:05.911] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:05.911] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:05.911] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:07.005] INFO: Test took 1093ms.
[14:31:07.007] INFO: The DUT currently contains the following objects:
[14:31:07.007] INFO: 2 TBM Cores tbm08c (2 ON)
[14:31:07.007] INFO: TBM Core alpha (0): 7 registers set
[14:31:07.007] INFO: TBM Core beta (1): 7 registers set
[14:31:07.007] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:31:07.007] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:07.007] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:07.007] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:07.007] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:07.007] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:07.007] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:07.007] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:07.007] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:07.007] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:07.007] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:07.007] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:07.007] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:07.007] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:07.007] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:07.007] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:07.007] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:08.104] INFO: Test took 1097ms.
[14:31:08.106] INFO: The DUT currently contains the following objects:
[14:31:08.106] INFO: 2 TBM Cores tbm08c (2 ON)
[14:31:08.106] INFO: TBM Core alpha (0): 7 registers set
[14:31:08.106] INFO: TBM Core beta (1): 7 registers set
[14:31:08.106] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:31:08.106] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:08.106] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:08.106] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:08.106] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:08.107] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:08.107] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:08.107] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:08.107] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:08.107] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:08.107] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:08.107] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:08.107] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:08.107] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:08.107] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:08.107] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:08.107] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:09.204] INFO: Test took 1097ms.
[14:31:09.205] INFO: The DUT currently contains the following objects:
[14:31:09.205] INFO: 2 TBM Cores tbm08c (2 ON)
[14:31:09.205] INFO: TBM Core alpha (0): 7 registers set
[14:31:09.205] INFO: TBM Core beta (1): 7 registers set
[14:31:09.205] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:31:09.205] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:09.205] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:09.205] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:09.205] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:09.205] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:09.205] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:09.205] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:09.206] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:09.206] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:09.206] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:09.206] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:09.206] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:09.206] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:09.206] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:09.206] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:09.206] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:10.299] INFO: Test took 1093ms.
[14:31:10.301] INFO: The DUT currently contains the following objects:
[14:31:10.301] INFO: 2 TBM Cores tbm08c (2 ON)
[14:31:10.301] INFO: TBM Core alpha (0): 7 registers set
[14:31:10.301] INFO: TBM Core beta (1): 7 registers set
[14:31:10.301] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:31:10.301] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:10.301] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:10.301] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:10.301] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:10.301] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:10.301] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:10.301] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:10.301] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:10.301] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:10.301] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:10.301] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:10.301] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:10.301] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:10.301] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:10.301] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:10.301] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:11.398] INFO: Test took 1097ms.
[14:31:11.399] INFO: The DUT currently contains the following objects:
[14:31:11.400] INFO: 2 TBM Cores tbm08c (2 ON)
[14:31:11.400] INFO: TBM Core alpha (0): 7 registers set
[14:31:11.400] INFO: TBM Core beta (1): 7 registers set
[14:31:11.400] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:31:11.400] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:11.400] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:11.400] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:11.400] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:11.400] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:11.400] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:11.400] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:11.400] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:11.400] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:11.400] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:11.400] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:11.400] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:11.400] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:11.400] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:11.400] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:11.400] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:31:12.493] INFO: Test took 1093ms.
[14:31:12.497] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:24.530] INFO: Test took 192033ms.
[14:34:27.558] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:37:38.753] INFO: Test took 191195ms.
[14:37:41.675] INFO: safety margin for low PH: adding 0, margin is now 20
[14:37:41.683] INFO: safety margin for low PH: adding 0, margin is now 20
[14:37:41.691] INFO: safety margin for low PH: adding 0, margin is now 20
[14:37:41.699] INFO: safety margin for low PH: adding 0, margin is now 20
[14:37:41.707] INFO: safety margin for low PH: adding 0, margin is now 20
[14:37:41.715] INFO: safety margin for low PH: adding 0, margin is now 20
[14:37:41.723] INFO: safety margin for low PH: adding 1, margin is now 21
[14:37:41.731] INFO: safety margin for low PH: adding 2, margin is now 22
[14:37:41.739] INFO: safety margin for low PH: adding 3, margin is now 23
[14:37:41.747] INFO: safety margin for low PH: adding 4, margin is now 24
[14:37:41.754] INFO: safety margin for low PH: adding 5, margin is now 25
[14:37:41.762] INFO: safety margin for low PH: adding 0, margin is now 20
[14:37:41.770] INFO: safety margin for low PH: adding 0, margin is now 20
[14:37:41.778] INFO: safety margin for low PH: adding 0, margin is now 20
[14:37:41.786] INFO: safety margin for low PH: adding 0, margin is now 20
[14:37:41.793] INFO: safety margin for low PH: adding 0, margin is now 20
[14:37:41.801] INFO: safety margin for low PH: adding 0, margin is now 20
[14:37:41.809] INFO: safety margin for low PH: adding 0, margin is now 20
[14:37:41.817] INFO: safety margin for low PH: adding 0, margin is now 20
[14:37:41.825] INFO: safety margin for low PH: adding 0, margin is now 20
[14:37:41.832] INFO: safety margin for low PH: adding 0, margin is now 20
[14:37:41.884] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C0.dat
[14:37:41.884] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C1.dat
[14:37:41.884] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C2.dat
[14:37:41.885] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C3.dat
[14:37:41.885] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C4.dat
[14:37:41.885] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C5.dat
[14:37:41.886] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C6.dat
[14:37:41.886] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C7.dat
[14:37:41.887] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C8.dat
[14:37:41.887] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C9.dat
[14:37:41.887] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C10.dat
[14:37:41.888] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C11.dat
[14:37:41.888] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C12.dat
[14:37:41.888] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C13.dat
[14:37:41.889] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C14.dat
[14:37:41.889] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C15.dat
[14:37:45.979] INFO: Test took 4085ms.
[14:37:50.332] INFO: Test took 4024ms.
[14:37:54.724] INFO: Test took 4069ms.
[14:37:55.079] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:37:55.983] INFO: Test took 904ms.
[14:37:55.988] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:37:57.081] INFO: Test took 1093ms.
[14:37:57.085] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:37:58.177] INFO: Test took 1092ms.
[14:37:58.180] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:37:59.275] INFO: Test took 1095ms.
[14:37:59.280] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:00.376] INFO: Test took 1096ms.
[14:38:00.382] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:01.475] INFO: Test took 1094ms.
[14:38:01.481] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:02.575] INFO: Test took 1094ms.
[14:38:02.579] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:03.673] INFO: Test took 1094ms.
[14:38:03.677] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:04.772] INFO: Test took 1095ms.
[14:38:04.776] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:05.868] INFO: Test took 1092ms.
[14:38:05.872] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:06.966] INFO: Test took 1095ms.
[14:38:06.970] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:08.063] INFO: Test took 1093ms.
[14:38:08.067] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:09.163] INFO: Test took 1096ms.
[14:38:09.167] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:10.272] INFO: Test took 1105ms.
[14:38:10.277] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:11.372] INFO: Test took 1095ms.
[14:38:11.377] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:12.468] INFO: Test took 1091ms.
[14:38:12.471] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:13.570] INFO: Test took 1099ms.
[14:38:13.574] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:14.667] INFO: Test took 1093ms.
[14:38:14.671] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:15.766] INFO: Test took 1095ms.
[14:38:15.770] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:16.867] INFO: Test took 1097ms.
[14:38:16.872] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:17.966] INFO: Test took 1095ms.
[14:38:17.970] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:19.065] INFO: Test took 1095ms.
[14:38:19.068] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:20.167] INFO: Test took 1099ms.
[14:38:20.173] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:21.265] INFO: Test took 1093ms.
[14:38:21.269] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:22.367] INFO: Test took 1099ms.
[14:38:22.372] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:23.468] INFO: Test took 1096ms.
[14:38:23.473] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:24.566] INFO: Test took 1093ms.
[14:38:24.572] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:25.665] INFO: Test took 1094ms.
[14:38:25.669] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:26.764] INFO: Test took 1095ms.
[14:38:26.770] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:27.863] INFO: Test took 1094ms.
[14:38:27.867] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:28.963] INFO: Test took 1097ms.
[14:38:28.968] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:30.064] INFO: Test took 1097ms.
[14:38:30.625] INFO: PixTestPhOptimization::doTest() done, duration: 468 seconds
[14:38:30.625] INFO: PH scale (per ROC): 73 79 85 80 79 80 79 73 86 80 81 80 81 86 75 81
[14:38:30.625] INFO: PH offset (per ROC): 155 156 157 161 153 145 159 155 154 176 157 155 170 154 165 176
[14:38:30.851] INFO: ######################################################################
[14:38:30.851] INFO: PixTestGainPedestal::fullTest() ntrig = 10
[14:38:30.851] INFO: ######################################################################
[14:38:30.868] INFO: scanning low vcal = 10
[14:38:34.551] INFO: Test took 3683ms.
[14:38:34.558] INFO: scanning low vcal = 20
[14:38:38.258] INFO: Test took 3700ms.
[14:38:38.265] INFO: scanning low vcal = 30
[14:38:41.981] INFO: Test took 3716ms.
[14:38:41.998] INFO: scanning low vcal = 40
[14:38:46.163] INFO: Test took 4165ms.
[14:38:46.292] INFO: scanning low vcal = 50
[14:38:50.500] INFO: Test took 4208ms.
[14:38:50.635] INFO: scanning low vcal = 60
[14:38:54.867] INFO: Test took 4231ms.
[14:38:54.002] INFO: scanning low vcal = 70
[14:38:59.215] INFO: Test took 4213ms.
[14:38:59.341] INFO: scanning low vcal = 80
[14:39:03.491] INFO: Test took 4150ms.
[14:39:03.612] INFO: scanning low vcal = 90
[14:39:07.801] INFO: Test took 4189ms.
[14:39:07.925] INFO: scanning low vcal = 100
[14:39:12.095] INFO: Test took 4170ms.
[14:39:12.218] INFO: scanning low vcal = 110
[14:39:16.364] INFO: Test took 4145ms.
[14:39:16.481] INFO: scanning low vcal = 120
[14:39:20.633] INFO: Test took 4152ms.
[14:39:20.746] INFO: scanning low vcal = 130
[14:39:24.968] INFO: Test took 4222ms.
[14:39:25.081] INFO: scanning low vcal = 140
[14:39:29.327] INFO: Test took 4246ms.
[14:39:29.443] INFO: scanning low vcal = 150
[14:39:33.600] INFO: Test took 4157ms.
[14:39:33.716] INFO: scanning low vcal = 160
[14:39:37.845] INFO: Test took 4129ms.
[14:39:37.956] INFO: scanning low vcal = 170
[14:39:42.119] INFO: Test took 4163ms.
[14:39:42.234] INFO: scanning low vcal = 180
[14:39:46.353] INFO: Test took 4119ms.
[14:39:46.464] INFO: scanning low vcal = 190
[14:39:50.562] INFO: Test took 4098ms.
[14:39:50.677] INFO: scanning low vcal = 200
[14:39:54.789] INFO: Test took 4112ms.
[14:39:54.901] INFO: scanning low vcal = 210
[14:39:59.046] INFO: Test took 4145ms.
[14:39:59.159] INFO: scanning low vcal = 220
[14:40:03.257] INFO: Test took 4098ms.
[14:40:03.370] INFO: scanning low vcal = 230
[14:40:07.504] INFO: Test took 4134ms.
[14:40:07.619] INFO: scanning low vcal = 240
[14:40:11.751] INFO: Test took 4132ms.
[14:40:11.865] INFO: scanning low vcal = 250
[14:40:16.015] INFO: Test took 4150ms.
[14:40:16.128] INFO: scanning high vcal = 30 (= 210 in low range)
[14:40:20.285] INFO: Test took 4157ms.
[14:40:20.402] INFO: scanning high vcal = 50 (= 350 in low range)
[14:40:24.543] INFO: Test took 4141ms.
[14:40:24.657] INFO: scanning high vcal = 70 (= 490 in low range)
[14:40:28.833] INFO: Test took 4176ms.
[14:40:28.946] INFO: scanning high vcal = 90 (= 630 in low range)
[14:40:33.082] INFO: Test took 4136ms.
[14:40:33.194] INFO: scanning high vcal = 200 (= 1400 in low range)
[14:40:37.331] INFO: Test took 4137ms.
[14:40:38.038] INFO: PixTestGainPedestal::measure() done
[14:41:28.014] INFO: PixTestGainPedestal::fit() done
[14:41:28.014] INFO: non-linearity mean: 0.955 0.955 0.955 0.954 0.956 0.963 0.950 0.956 0.950 0.956 0.956 0.966 0.957 0.960 0.956 0.960
[14:41:28.014] INFO: non-linearity RMS: 0.007 0.006 0.006 0.005 0.006 0.004 0.007 0.005 0.006 0.006 0.006 0.005 0.006 0.005 0.005 0.006
[14:41:28.014] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C0.dat
[14:41:28.046] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C1.dat
[14:41:28.071] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C2.dat
[14:41:28.098] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C3.dat
[14:41:28.124] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C4.dat
[14:41:28.150] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C5.dat
[14:41:28.176] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C6.dat
[14:41:28.202] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C7.dat
[14:41:28.229] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C8.dat
[14:41:28.254] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C9.dat
[14:41:28.280] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C10.dat
[14:41:28.308] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C11.dat
[14:41:28.334] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C12.dat
[14:41:28.360] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C13.dat
[14:41:28.386] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C14.dat
[14:41:28.413] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C15.dat
[14:41:28.438] INFO: PixTestGainPedestal::doTest() done, duration: 177 seconds
[14:41:28.448] INFO: readReadbackCal: /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C15.dat
[14:41:28.449] INFO: PixTestReadback::doTest() start.
[14:41:28.450] INFO: PixTestReadback::RES sent once
[14:41:44.002] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C0.dat
[14:41:44.003] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C1.dat
[14:41:44.003] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C2.dat
[14:41:44.003] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C3.dat
[14:41:44.003] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C4.dat
[14:41:44.003] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C5.dat
[14:41:44.003] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C6.dat
[14:41:44.003] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C7.dat
[14:41:45.003] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C8.dat
[14:41:45.003] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C9.dat
[14:41:45.003] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C10.dat
[14:41:45.004] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C11.dat
[14:41:45.004] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C12.dat
[14:41:45.004] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C13.dat
[14:41:45.004] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C14.dat
[14:41:45.004] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C15.dat
[14:41:45.044] INFO: PixTestPattern:: pg_setup set to default.
[14:41:45.045] INFO: PixTestReadback::RES sent once
[14:42:01.499] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C0.dat
[14:42:01.499] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C1.dat
[14:42:01.499] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C2.dat
[14:42:01.499] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C3.dat
[14:42:01.500] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C4.dat
[14:42:01.500] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C5.dat
[14:42:01.500] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C6.dat
[14:42:01.500] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C7.dat
[14:42:01.500] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C8.dat
[14:42:01.500] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C9.dat
[14:42:01.500] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C10.dat
[14:42:01.501] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C11.dat
[14:42:01.501] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C12.dat
[14:42:01.501] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C13.dat
[14:42:01.501] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C14.dat
[14:42:01.501] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C15.dat
[14:42:01.541] INFO: PixTestPattern:: pg_setup set to default.
[14:42:01.541] INFO: PixTestReadback::RES sent once
[14:42:14.287] INFO: PixTestPattern:: pg_setup set to default.
[14:42:14.287] INFO: Vbg will be calibrated using Vd calibration
[14:42:14.287] INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 150.5calibrated Vbg = 1.23328 :::*/*/*/*/
[14:42:14.287] INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 147.1calibrated Vbg = 1.22064 :::*/*/*/*/
[14:42:14.287] INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 153.1calibrated Vbg = 1.23499 :::*/*/*/*/
[14:42:14.287] INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 151.7calibrated Vbg = 1.23682 :::*/*/*/*/
[14:42:14.288] INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 154.1calibrated Vbg = 1.2448 :::*/*/*/*/
[14:42:14.288] INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 155.1calibrated Vbg = 1.23943 :::*/*/*/*/
[14:42:14.288] INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 166.1calibrated Vbg = 1.23455 :::*/*/*/*/
[14:42:14.288] INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 150.9calibrated Vbg = 1.24423 :::*/*/*/*/
[14:42:14.288] INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 150.4calibrated Vbg = 1.24405 :::*/*/*/*/
[14:42:14.288] INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 151.3calibrated Vbg = 1.23468 :::*/*/*/*/
[14:42:14.288] INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 152.3calibrated Vbg = 1.23747 :::*/*/*/*/
[14:42:14.288] INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 150.6calibrated Vbg = 1.24366 :::*/*/*/*/
[14:42:14.288] INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 158.9calibrated Vbg = 1.24307 :::*/*/*/*/
[14:42:14.288] INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 141.1calibrated Vbg = 1.2454 :::*/*/*/*/
[14:42:14.288] INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 156.4calibrated Vbg = 1.23631 :::*/*/*/*/
[14:42:14.288] INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 156calibrated Vbg = 1.22815 :::*/*/*/*/
[14:42:14.292] INFO: PixTestReadback::RES sent once
[14:46:09.374] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C0.dat
[14:46:09.374] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C1.dat
[14:46:09.374] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C2.dat
[14:46:09.374] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C3.dat
[14:46:09.374] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C4.dat
[14:46:09.375] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C5.dat
[14:46:09.375] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C6.dat
[14:46:09.375] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C7.dat
[14:46:09.375] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C8.dat
[14:46:09.375] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C9.dat
[14:46:09.375] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C10.dat
[14:46:09.375] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C11.dat
[14:46:09.375] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C12.dat
[14:46:09.375] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C13.dat
[14:46:09.375] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C14.dat
[14:46:09.376] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4556_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C15.dat
[14:46:09.413] INFO: PixTestPattern:: pg_setup set to default.
[14:46:09.416] INFO: PixTestReadback::doTest() done
[14:46:09.432] INFO: enter test to run
[14:46:09.432] INFO: test: q no parameter change
[14:46:09.595] QUIET: Connection to board 178 closed.
[14:46:09.598] INFO: pXar: this is the end, my friend