[10:22:11.066] <TB3> INFO: *** Welcome to pxar ***
[10:22:11.066] <TB3> INFO: *** Today: 2015/08/24
[10:22:11.066] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C15.dat
[10:22:11.068] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//tbmParameters_C0b.dat
[10:22:11.068] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//defaultMaskFile.dat
[10:22:11.068] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters_C15.dat
[10:22:11.155] <TB3> INFO: clk: 4
[10:22:11.155] <TB3> INFO: ctr: 4
[10:22:11.155] <TB3> INFO: sda: 19
[10:22:11.155] <TB3> INFO: tin: 9
[10:22:11.155] <TB3> INFO: level: 15
[10:22:11.155] <TB3> INFO: triggerdelay: 0
[10:22:11.155] <TB3> QUIET: Instanciating API for pxar prod-01+86~g1838649
[10:22:11.155] <TB3> INFO: Log level: INFO
[10:22:11.163] <TB3> INFO: Found DTB DTB_WZ4I6J
[10:22:11.173] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[10:22:11.176] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[10:22:11.179] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[10:22:12.709] <TB3> INFO: DUT info:
[10:22:12.709] <TB3> INFO: The DUT currently contains the following objects:
[10:22:12.709] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[10:22:12.710] <TB3> INFO: TBM Core alpha (0): 7 registers set
[10:22:12.710] <TB3> INFO: TBM Core beta (1): 7 registers set
[10:22:12.710] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:22:12.710] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:22:12.710] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:22:12.710] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:22:12.710] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:22:12.710] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:22:12.710] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:22:12.710] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:22:12.710] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:22:12.710] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:22:12.710] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:22:12.710] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:22:12.710] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:22:12.710] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:22:12.710] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:22:12.710] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:22:12.710] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:22:13.112] <TB3> INFO: enter 'restricted' command line mode
[10:22:13.112] <TB3> INFO: enter test to run
[10:22:13.112] <TB3> INFO: test: pretest no parameter change
[10:22:13.112] <TB3> INFO: running: pretest
[10:22:13.119] <TB3> INFO: ######################################################################
[10:22:13.119] <TB3> INFO: PixTestPretest::doTest()
[10:22:13.119] <TB3> INFO: ######################################################################
[10:22:13.120] <TB3> INFO: ----------------------------------------------------------------------
[10:22:13.120] <TB3> INFO: PixTestPretest::programROC()
[10:22:13.120] <TB3> INFO: ----------------------------------------------------------------------
[10:22:31.138] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[10:22:31.138] <TB3> INFO: IA differences per ROC: 19.3 16.9 19.3 16.9 18.5 18.5 19.3 20.9 18.5 19.3 20.1 19.3 18.5 16.9 19.3 19.3
[10:22:31.215] <TB3> INFO: ----------------------------------------------------------------------
[10:22:31.215] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[10:22:31.215] <TB3> INFO: ----------------------------------------------------------------------
[10:22:50.782] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 378.6 mA = 23.6625 mA/ROC
[10:22:50.785] <TB3> INFO: ----------------------------------------------------------------------
[10:22:50.785] <TB3> INFO: PixTestPretest::findWorkingPixel()
[10:22:50.786] <TB3> INFO: ----------------------------------------------------------------------
[10:22:59.095] <TB3> INFO: Test took 8304ms.
[10:22:59.398] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[10:22:59.439] <TB3> INFO: ----------------------------------------------------------------------
[10:22:59.439] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[10:22:59.439] <TB3> INFO: ----------------------------------------------------------------------
[10:23:07.754] <TB3> INFO: Test took 8311ms.
[10:23:08.065] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[10:23:08.065] <TB3> INFO: CalDel: 158 134 143 118 161 130 129 124 138 148 174 153 162 125 169 144
[10:23:08.065] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[10:23:08.069] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C0.dat
[10:23:08.069] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C1.dat
[10:23:08.069] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C2.dat
[10:23:08.069] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C3.dat
[10:23:08.070] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C4.dat
[10:23:08.070] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C5.dat
[10:23:08.070] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C6.dat
[10:23:08.070] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C7.dat
[10:23:08.070] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C8.dat
[10:23:08.071] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C9.dat
[10:23:08.071] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C10.dat
[10:23:08.071] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C11.dat
[10:23:08.071] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C12.dat
[10:23:08.072] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C13.dat
[10:23:08.072] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C14.dat
[10:23:08.072] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C15.dat
[10:23:08.072] <TB3> INFO: write tbm parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//tbmParameters_C0a.dat
[10:23:08.072] <TB3> INFO: write tbm parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//tbmParameters_C0b.dat
[10:23:08.072] <TB3> INFO: PixTestPretest::doTest() done, duration: 54 seconds
[10:23:08.159] <TB3> INFO: enter test to run
[10:23:08.159] <TB3> INFO: test: fulltest no parameter change
[10:23:08.159] <TB3> INFO: running: fulltest
[10:23:08.159] <TB3> INFO: ######################################################################
[10:23:08.159] <TB3> INFO: PixTestFullTest::doTest()
[10:23:08.159] <TB3> INFO: ######################################################################
[10:23:08.160] <TB3> INFO: ######################################################################
[10:23:08.160] <TB3> INFO: PixTestAlive::doTest()
[10:23:08.160] <TB3> INFO: ######################################################################
[10:23:08.162] <TB3> INFO: ----------------------------------------------------------------------
[10:23:08.162] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:23:08.162] <TB3> INFO: ----------------------------------------------------------------------
[10:23:11.601] <TB3> INFO: Test took 3438ms.
[10:23:11.621] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:11.850] <TB3> INFO: PixTestAlive::aliveTest() done
[10:23:11.850] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[10:23:11.851] <TB3> INFO: ----------------------------------------------------------------------
[10:23:11.851] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:23:11.851] <TB3> INFO: ----------------------------------------------------------------------
[10:23:14.577] <TB3> INFO: Test took 2724ms.
[10:23:14.581] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:14.582] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:23:14.813] <TB3> INFO: PixTestAlive::maskTest() done
[10:23:14.813] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:23:14.814] <TB3> INFO: ----------------------------------------------------------------------
[10:23:14.814] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:23:14.814] <TB3> INFO: ----------------------------------------------------------------------
[10:23:18.261] <TB3> INFO: Test took 3446ms.
[10:23:18.287] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:18.519] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[10:23:18.519] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:23:18.519] <TB3> INFO: PixTestAlive::doTest() done, duration: 10 seconds
[10:23:18.534] <TB3> INFO: ######################################################################
[10:23:18.534] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[10:23:18.534] <TB3> INFO: ######################################################################
[10:23:18.537] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30/5) hits flags = 2 (plus default)
[10:23:18.550] <TB3> INFO: dacScan step from 0 .. 29
[10:23:18.550] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:23:18.550] <TB3> INFO: run 1 of 1
[10:23:39.938] <TB3> INFO: Test took 21388ms.
[10:23:39.974] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:39.974] <TB3> INFO: dacScan step from 30 .. 59
[10:23:39.974] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:23:39.974] <TB3> INFO: run 1 of 1
[10:24:03.250] <TB3> INFO: Test took 23276ms.
[10:24:03.335] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:24:03.368] <TB3> INFO: dacScan step from 60 .. 89
[10:24:03.368] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:24:03.368] <TB3> INFO: run 1 of 1
[10:24:30.340] <TB3> INFO: Test took 26972ms.
[10:24:30.590] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:24:30.679] <TB3> INFO: dacScan step from 90 .. 119
[10:24:30.679] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:24:30.679] <TB3> INFO: run 1 of 1
[10:24:57.734] <TB3> INFO: Test took 27055ms.
[10:24:58.002] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:24:58.084] <TB3> INFO: dacScan step from 120 .. 149
[10:24:58.084] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:24:58.084] <TB3> INFO: run 1 of 1
[10:25:21.960] <TB3> INFO: Test took 23876ms.
[10:25:22.195] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:25:45.372] <TB3> INFO: PixTestBBMap::doTest() done, duration: 146 seconds
[10:25:45.372] <TB3> INFO: number of dead bumps (per ROC): 10 0 0 0 0 0 0 0 0 1 2 4 6 0 2 12
[10:25:45.372] <TB3> INFO: separation cut (per ROC): 71 100 99 92 88 91 87 90 67 85 76 91 70 94 83 96
[10:25:45.443] <TB3> INFO: ######################################################################
[10:25:45.443] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = 4, ntrig/step = 50
[10:25:45.443] <TB3> INFO: ######################################################################
[10:25:45.443] <TB3> INFO: ----------------------------------------------------------------------
[10:25:45.443] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = 4, ntrig/step = 50
[10:25:45.443] <TB3> INFO: ----------------------------------------------------------------------
[10:25:45.443] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (4/50) hits flags = 16 (plus default)
[10:25:45.451] <TB3> INFO: dacScan step from 0 .. 3
[10:25:45.452] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:25:45.452] <TB3> INFO: run 1 of 1
[10:26:04.375] <TB3> INFO: Test took 18923ms.
[10:26:04.408] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:26:04.408] <TB3> INFO: dacScan step from 4 .. 7
[10:26:04.408] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:26:04.408] <TB3> INFO: run 1 of 1
[10:26:23.469] <TB3> INFO: Test took 19061ms.
[10:26:23.494] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:26:23.499] <TB3> INFO: dacScan step from 8 .. 11
[10:26:23.499] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:26:23.499] <TB3> INFO: run 1 of 1
[10:26:42.472] <TB3> INFO: Test took 18973ms.
[10:26:42.498] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:26:42.498] <TB3> INFO: dacScan step from 12 .. 15
[10:26:42.498] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:26:42.498] <TB3> INFO: run 1 of 1
[10:27:01.571] <TB3> INFO: Test took 19073ms.
[10:27:01.599] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:27:01.599] <TB3> INFO: dacScan step from 16 .. 19
[10:27:01.599] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:27:01.599] <TB3> INFO: run 1 of 1
[10:27:20.531] <TB3> INFO: Test took 18932ms.
[10:27:20.558] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:27:20.558] <TB3> INFO: dacScan step from 20 .. 23
[10:27:20.558] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:27:20.559] <TB3> INFO: run 1 of 1
[10:27:39.589] <TB3> INFO: Test took 19030ms.
[10:27:39.618] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:27:39.618] <TB3> INFO: dacScan step from 24 .. 27
[10:27:39.618] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:27:39.618] <TB3> INFO: run 1 of 1
[10:27:58.543] <TB3> INFO: Test took 18925ms.
[10:27:58.574] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:27:58.574] <TB3> INFO: dacScan step from 28 .. 31
[10:27:58.574] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:27:58.574] <TB3> INFO: run 1 of 1
[10:28:17.450] <TB3> INFO: Test took 18876ms.
[10:28:17.478] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:17.478] <TB3> INFO: dacScan step from 32 .. 35
[10:28:17.478] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:28:17.478] <TB3> INFO: run 1 of 1
[10:28:36.345] <TB3> INFO: Test took 18867ms.
[10:28:36.375] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:36.375] <TB3> INFO: dacScan step from 36 .. 39
[10:28:36.375] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:28:36.375] <TB3> INFO: run 1 of 1
[10:28:55.220] <TB3> INFO: Test took 18845ms.
[10:28:55.248] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:55.248] <TB3> INFO: dacScan step from 40 .. 43
[10:28:55.248] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:28:55.248] <TB3> INFO: run 1 of 1
[10:29:14.318] <TB3> INFO: Test took 19070ms.
[10:29:14.348] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:14.348] <TB3> INFO: dacScan step from 44 .. 47
[10:29:14.348] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:29:14.348] <TB3> INFO: run 1 of 1
[10:29:33.332] <TB3> INFO: Test took 18984ms.
[10:29:33.361] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:33.361] <TB3> INFO: dacScan step from 48 .. 51
[10:29:33.361] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:29:33.361] <TB3> INFO: run 1 of 1
[10:29:52.395] <TB3> INFO: Test took 19034ms.
[10:29:52.425] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:52.426] <TB3> INFO: dacScan step from 52 .. 55
[10:29:52.426] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:29:52.426] <TB3> INFO: run 1 of 1
[10:30:11.500] <TB3> INFO: Test took 19074ms.
[10:30:11.529] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:11.529] <TB3> INFO: dacScan step from 56 .. 59
[10:30:11.529] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:30:11.529] <TB3> INFO: run 1 of 1
[10:30:30.563] <TB3> INFO: Test took 19034ms.
[10:30:30.593] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:30.593] <TB3> INFO: dacScan step from 60 .. 63
[10:30:30.593] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:30:30.593] <TB3> INFO: run 1 of 1
[10:30:49.615] <TB3> INFO: Test took 19022ms.
[10:30:49.648] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:49.648] <TB3> INFO: dacScan step from 64 .. 67
[10:30:49.648] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:30:49.648] <TB3> INFO: run 1 of 1
[10:31:08.669] <TB3> INFO: Test took 19021ms.
[10:31:08.702] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:08.703] <TB3> INFO: dacScan step from 68 .. 71
[10:31:08.703] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:31:08.703] <TB3> INFO: run 1 of 1
[10:31:27.996] <TB3> INFO: Test took 19293ms.
[10:31:28.043] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:28.045] <TB3> INFO: dacScan step from 72 .. 75
[10:31:28.045] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:31:28.045] <TB3> INFO: run 1 of 1
[10:31:48.155] <TB3> INFO: Test took 20110ms.
[10:31:48.220] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:48.222] <TB3> INFO: dacScan step from 76 .. 79
[10:31:48.222] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:31:48.222] <TB3> INFO: run 1 of 1
[10:32:09.637] <TB3> INFO: Test took 21415ms.
[10:32:09.733] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:09.737] <TB3> INFO: dacScan step from 80 .. 83
[10:32:09.737] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:32:09.737] <TB3> INFO: run 1 of 1
[10:32:33.646] <TB3> INFO: Test took 23909ms.
[10:32:33.796] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:33.802] <TB3> INFO: dacScan step from 84 .. 87
[10:32:33.802] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:32:33.802] <TB3> INFO: run 1 of 1
[10:32:59.705] <TB3> INFO: Test took 25903ms.
[10:32:59.891] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:59.898] <TB3> INFO: dacScan step from 88 .. 91
[10:32:59.898] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:32:59.898] <TB3> INFO: run 1 of 1
[10:33:27.186] <TB3> INFO: Test took 27288ms.
[10:33:27.392] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:27.401] <TB3> INFO: dacScan step from 92 .. 95
[10:33:27.401] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:33:27.401] <TB3> INFO: run 1 of 1
[10:33:55.286] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[10:33:55.286] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[10:33:55.850] <TB3> INFO: Test took 28449ms.
[10:33:56.065] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:56.073] <TB3> INFO: dacScan step from 96 .. 99
[10:33:56.074] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:33:56.074] <TB3> INFO: run 1 of 1
[10:34:23.776] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (3) != Token Chain Length (4)
[10:34:24.821] <TB3> INFO: Test took 28747ms.
[10:34:25.063] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:34:25.073] <TB3> INFO: dacScan step from 100 .. 103
[10:34:25.073] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:34:25.073] <TB3> INFO: run 1 of 1
[10:34:53.244] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[10:34:53.244] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[10:34:54.373] <TB3> INFO: Test took 29300ms.
[10:34:54.601] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:34:54.611] <TB3> INFO: dacScan step from 104 .. 107
[10:34:54.611] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:34:54.611] <TB3> INFO: run 1 of 1
[10:35:22.567] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[10:35:22.567] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[10:35:23.838] <TB3> INFO: Test took 29227ms.
[10:35:24.126] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:35:24.140] <TB3> INFO: dacScan step from 108 .. 111
[10:35:24.140] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:35:24.140] <TB3> INFO: run 1 of 1
[10:35:52.230] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[10:35:52.230] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[10:35:53.496] <TB3> INFO: Test took 29356ms.
[10:35:53.737] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:35:53.746] <TB3> INFO: dacScan step from 112 .. 115
[10:35:53.746] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:35:53.746] <TB3> INFO: run 1 of 1
[10:36:21.733] <TB3> ERROR: <datapipe.cc/CheckEventID:L453> Channel 3 Event ID mismatch: local ID (199) != TBM ID (0)
[10:36:21.733] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (3) != Token Chain Length (4)
[10:36:21.733] <TB3> ERROR: <datapipe.cc/CheckEventID:L453> Channel 3 Event ID mismatch: local ID (1) != TBM ID (200)
[10:36:23.001] <TB3> INFO: Test took 29254ms.
[10:36:23.237] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:23.246] <TB3> INFO: dacScan step from 116 .. 119
[10:36:23.246] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:36:23.246] <TB3> INFO: run 1 of 1
[10:36:51.274] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[10:36:51.274] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[10:36:52.503] <TB3> INFO: Test took 29257ms.
[10:36:52.788] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:52.799] <TB3> INFO: dacScan step from 120 .. 123
[10:36:52.799] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:36:52.799] <TB3> INFO: run 1 of 1
[10:37:22.182] <TB3> INFO: Test took 29383ms.
[10:37:22.415] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:22.424] <TB3> INFO: dacScan step from 124 .. 127
[10:37:22.424] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:37:22.424] <TB3> INFO: run 1 of 1
[10:37:51.637] <TB3> INFO: Test took 29213ms.
[10:37:51.887] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:51.896] <TB3> INFO: dacScan step from 128 .. 131
[10:37:51.896] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:37:51.896] <TB3> INFO: run 1 of 1
[10:38:20.968] <TB3> INFO: Test took 29072ms.
[10:38:21.195] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:21.204] <TB3> INFO: dacScan step from 132 .. 135
[10:38:21.204] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:38:21.204] <TB3> INFO: run 1 of 1
[10:38:50.437] <TB3> INFO: Test took 29233ms.
[10:38:50.721] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:50.730] <TB3> INFO: dacScan step from 136 .. 139
[10:38:50.730] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:38:50.730] <TB3> INFO: run 1 of 1
[10:39:19.942] <TB3> INFO: Test took 29212ms.
[10:39:20.174] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:39:20.188] <TB3> INFO: dacScan step from 140 .. 143
[10:39:20.188] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:39:20.188] <TB3> INFO: run 1 of 1
[10:39:49.697] <TB3> INFO: Test took 29509ms.
[10:39:49.916] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:39:49.925] <TB3> INFO: dacScan step from 144 .. 147
[10:39:49.925] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:39:49.925] <TB3> INFO: run 1 of 1
[10:40:19.383] <TB3> INFO: Test took 29458ms.
[10:40:19.674] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:19.686] <TB3> INFO: dacScan step from 148 .. 149
[10:40:19.686] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:40:19.686] <TB3> INFO: run 1 of 1
[10:40:35.678] <TB3> INFO: Test took 15992ms.
[10:40:35.800] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:35.806] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:40:37.258] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:40:38.742] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:40:40.295] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:40:41.669] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:40:43.128] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:40:44.551] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:40:46.084] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:40:47.567] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:40:49.105] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:40:50.577] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:40:52.106] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:40:53.561] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:40:55.101] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:40:56.591] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:40:58.128] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:40:59.609] <TB3> INFO: PixTestScurves::scurves() done
[10:40:59.609] <TB3> INFO: Vcal mean: 87.15 102.59 95.26 82.38 78.17 88.89 88.45 85.40 73.54 81.40 78.41 90.83 80.50 86.82 82.98 92.74
[10:40:59.609] <TB3> INFO: Vcal RMS: 5.95 6.52 5.93 4.87 5.10 5.39 6.06 4.88 4.51 5.26 4.29 5.33 4.92 5.31 4.87 6.44
[10:40:59.609] <TB3> INFO: PixTestScurves::fullTest() done, duration: 914 seconds
[10:40:59.681] <TB3> INFO: ######################################################################
[10:40:59.681] <TB3> INFO: PixTestTrim::doTest()
[10:40:59.681] <TB3> INFO: ######################################################################
[10:40:59.682] <TB3> INFO: ----------------------------------------------------------------------
[10:40:59.682] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:40:59.682] <TB3> INFO: ----------------------------------------------------------------------
[10:40:59.765] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:40:59.765] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[10:40:59.774] <TB3> INFO: dacScan step from 0 .. 19
[10:40:59.774] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:40:59.774] <TB3> INFO: run 1 of 1
[10:41:14.744] <TB3> INFO: Test took 14970ms.
[10:41:14.765] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:14.766] <TB3> INFO: dacScan step from 20 .. 39
[10:41:14.766] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:41:14.766] <TB3> INFO: run 1 of 1
[10:41:29.041] <TB3> INFO: Test took 14275ms.
[10:41:29.064] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:29.064] <TB3> INFO: dacScan step from 40 .. 59
[10:41:29.064] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:41:29.064] <TB3> INFO: run 1 of 1
[10:41:44.031] <TB3> INFO: Test took 14967ms.
[10:41:44.052] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:44.053] <TB3> INFO: dacScan step from 60 .. 79
[10:41:44.053] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:41:44.053] <TB3> INFO: run 1 of 1
[10:41:58.828] <TB3> INFO: Test took 14774ms.
[10:41:58.850] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:58.850] <TB3> INFO: dacScan step from 80 .. 99
[10:41:58.850] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:41:58.850] <TB3> INFO: run 1 of 1
[10:42:14.349] <TB3> INFO: Test took 15498ms.
[10:42:14.452] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:42:14.466] <TB3> INFO: dacScan step from 100 .. 119
[10:42:14.466] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:42:14.466] <TB3> INFO: run 1 of 1
[10:42:34.047] <TB3> INFO: Test took 19581ms.
[10:42:34.231] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:42:34.262] <TB3> INFO: dacScan step from 120 .. 139
[10:42:34.262] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:42:34.262] <TB3> INFO: run 1 of 1
[10:42:52.476] <TB3> INFO: Test took 18214ms.
[10:42:52.635] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:42:52.660] <TB3> INFO: dacScan step from 140 .. 159
[10:42:52.660] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:42:52.660] <TB3> INFO: run 1 of 1
[10:43:08.422] <TB3> INFO: Test took 15762ms.
[10:43:08.484] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:30.063] <TB3> INFO: ROC 0 VthrComp = 85
[10:43:30.063] <TB3> INFO: ROC 1 VthrComp = 98
[10:43:30.063] <TB3> INFO: ROC 2 VthrComp = 98
[10:43:30.063] <TB3> INFO: ROC 3 VthrComp = 87
[10:43:30.064] <TB3> INFO: ROC 4 VthrComp = 81
[10:43:30.065] <TB3> INFO: ROC 5 VthrComp = 91
[10:43:30.065] <TB3> INFO: ROC 6 VthrComp = 89
[10:43:30.065] <TB3> INFO: ROC 7 VthrComp = 89
[10:43:30.065] <TB3> INFO: ROC 8 VthrComp = 78
[10:43:30.065] <TB3> INFO: ROC 9 VthrComp = 84
[10:43:30.065] <TB3> INFO: ROC 10 VthrComp = 82
[10:43:30.065] <TB3> INFO: ROC 11 VthrComp = 91
[10:43:30.066] <TB3> INFO: ROC 12 VthrComp = 80
[10:43:30.066] <TB3> INFO: ROC 13 VthrComp = 92
[10:43:30.066] <TB3> INFO: ROC 14 VthrComp = 85
[10:43:30.066] <TB3> INFO: ROC 15 VthrComp = 93
[10:43:30.066] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:43:30.066] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[10:43:30.077] <TB3> INFO: dacScan step from 0 .. 19
[10:43:30.077] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:43:30.077] <TB3> INFO: run 1 of 1
[10:43:45.205] <TB3> INFO: Test took 15128ms.
[10:43:45.232] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:45.232] <TB3> INFO: dacScan step from 20 .. 39
[10:43:45.232] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:43:45.232] <TB3> INFO: run 1 of 1
[10:43:59.755] <TB3> INFO: Test took 14523ms.
[10:43:59.784] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:59.786] <TB3> INFO: dacScan step from 40 .. 59
[10:43:59.786] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:43:59.787] <TB3> INFO: run 1 of 1
[10:44:18.032] <TB3> INFO: Test took 18245ms.
[10:44:18.184] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:18.210] <TB3> INFO: dacScan step from 60 .. 79
[10:44:18.210] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:44:18.210] <TB3> INFO: run 1 of 1
[10:44:37.362] <TB3> INFO: Test took 19152ms.
[10:44:37.548] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:37.587] <TB3> INFO: dacScan step from 80 .. 99
[10:44:37.587] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:44:37.587] <TB3> INFO: run 1 of 1
[10:44:56.854] <TB3> INFO: Test took 19266ms.
[10:44:57.023] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:57.055] <TB3> INFO: dacScan step from 100 .. 119
[10:44:57.055] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:44:57.055] <TB3> INFO: run 1 of 1
[10:45:17.244] <TB3> INFO: Test took 20189ms.
[10:45:17.439] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:45:17.476] <TB3> INFO: dacScan step from 120 .. 139
[10:45:17.476] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:45:17.476] <TB3> INFO: run 1 of 1
[10:45:37.709] <TB3> INFO: Test took 20233ms.
[10:45:37.916] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:45:37.956] <TB3> INFO: dacScan step from 140 .. 159
[10:45:37.956] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:45:37.956] <TB3> INFO: run 1 of 1
[10:45:58.143] <TB3> INFO: Test took 20187ms.
[10:45:58.334] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:46:26.115] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 63.5992 for pixel 38/1 mean/min/max = 47.405/31.2047/63.6053
[10:46:26.115] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 64.5506 for pixel 20/64 mean/min/max = 47.8494/30.6436/65.0553
[10:46:26.115] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 61.0141 for pixel 46/66 mean/min/max = 46.535/32.0228/61.0472
[10:46:26.115] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 57.8502 for pixel 0/57 mean/min/max = 44.8473/31.6905/58.0041
[10:46:26.116] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 60.0788 for pixel 18/7 mean/min/max = 45.7521/31.4112/60.0929
[10:46:26.116] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 58.5833 for pixel 48/77 mean/min/max = 45.8071/32.9873/58.627
[10:46:26.117] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 62.4597 for pixel 4/74 mean/min/max = 47.405/32.3126/62.4973
[10:46:26.117] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 57.9783 for pixel 19/62 mean/min/max = 45.5646/33.1013/58.0278
[10:46:26.117] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 58.1666 for pixel 2/42 mean/min/max = 46.3588/34.4536/58.2641
[10:46:26.118] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 60.3536 for pixel 0/38 mean/min/max = 45.7159/31.0703/60.3614
[10:46:26.118] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 57.5171 for pixel 22/14 mean/min/max = 45.1855/32.7785/57.5924
[10:46:26.118] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 60.5592 for pixel 24/6 mean/min/max = 46.849/33.1332/60.5647
[10:46:26.119] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 61.1024 for pixel 2/8 mean/min/max = 46.6761/32.2407/61.1114
[10:46:26.119] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 58.4295 for pixel 0/17 mean/min/max = 45.4145/32.3076/58.5215
[10:46:26.120] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 59.3864 for pixel 18/0 mean/min/max = 45.9224/32.3847/59.46
[10:46:26.120] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 61.0566 for pixel 34/2 mean/min/max = 46.0696/30.7477/61.3915
[10:46:26.120] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:47:56.134] <TB3> INFO: Test took 90014ms.
[10:47:57.649] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[10:47:57.657] <TB3> INFO: dacScan step from 0 .. 19
[10:47:57.657] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[10:47:57.657] <TB3> INFO: run 1 of 2
[10:48:12.803] <TB3> INFO: Test took 15146ms.
[10:48:12.837] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:12.839] <TB3> INFO: run 2 of 2
[10:48:28.063] <TB3> INFO: Test took 15224ms.
[10:48:28.097] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:28.098] <TB3> INFO: dacScan step from 20 .. 39
[10:48:28.098] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[10:48:28.098] <TB3> INFO: run 1 of 2
[10:48:46.242] <TB3> INFO: Test took 18143ms.
[10:48:46.395] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:46.429] <TB3> INFO: run 2 of 2
[10:49:04.257] <TB3> INFO: Test took 17827ms.
[10:49:04.421] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:04.451] <TB3> INFO: dacScan step from 40 .. 59
[10:49:04.451] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[10:49:04.451] <TB3> INFO: run 1 of 2
[10:49:24.812] <TB3> INFO: Test took 20360ms.
[10:49:24.987] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:25.020] <TB3> INFO: run 2 of 2
[10:49:45.026] <TB3> INFO: Test took 20005ms.
[10:49:45.203] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:45.236] <TB3> INFO: dacScan step from 60 .. 79
[10:49:45.236] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[10:49:45.236] <TB3> INFO: run 1 of 2
[10:50:05.526] <TB3> INFO: Test took 20290ms.
[10:50:05.696] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:05.730] <TB3> INFO: run 2 of 2
[10:50:26.002] <TB3> INFO: Test took 20272ms.
[10:50:26.179] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:26.217] <TB3> INFO: dacScan step from 80 .. 99
[10:50:26.217] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[10:50:26.217] <TB3> INFO: run 1 of 2
[10:50:46.447] <TB3> INFO: Test took 20230ms.
[10:50:46.623] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:46.656] <TB3> INFO: run 2 of 2
[10:51:06.975] <TB3> INFO: Test took 20318ms.
[10:51:07.162] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:07.196] <TB3> INFO: dacScan step from 100 .. 119
[10:51:07.196] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[10:51:07.196] <TB3> INFO: run 1 of 2
[10:51:27.378] <TB3> INFO: Test took 20182ms.
[10:51:27.551] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:27.588] <TB3> INFO: run 2 of 2
[10:51:47.865] <TB3> INFO: Test took 20277ms.
[10:51:48.056] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:48.092] <TB3> INFO: dacScan step from 120 .. 139
[10:51:48.092] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[10:51:48.092] <TB3> INFO: run 1 of 2
[10:52:08.235] <TB3> INFO: Test took 20143ms.
[10:52:08.409] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:08.445] <TB3> INFO: run 2 of 2
[10:52:28.498] <TB3> INFO: Test took 20053ms.
[10:52:28.660] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:28.690] <TB3> INFO: dacScan step from 140 .. 159
[10:52:28.690] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[10:52:28.690] <TB3> INFO: run 1 of 2
[10:52:48.815] <TB3> INFO: Test took 20125ms.
[10:52:49.029] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:49.064] <TB3> INFO: run 2 of 2
[10:53:09.025] <TB3> INFO: Test took 19960ms.
[10:53:09.203] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:09.235] <TB3> INFO: dacScan step from 160 .. 179
[10:53:09.236] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[10:53:09.236] <TB3> INFO: run 1 of 2
[10:53:29.476] <TB3> INFO: Test took 20240ms.
[10:53:29.655] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:29.688] <TB3> INFO: run 2 of 2
[10:53:49.998] <TB3> INFO: Test took 20310ms.
[10:53:50.201] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:50.241] <TB3> INFO: dacScan step from 180 .. 199
[10:53:50.241] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[10:53:50.241] <TB3> INFO: run 1 of 2
[10:54:10.480] <TB3> INFO: Test took 20239ms.
[10:54:10.659] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:10.710] <TB3> INFO: run 2 of 2
[10:54:30.578] <TB3> INFO: Test took 19868ms.
[10:54:30.757] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:56.623] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.023253 .. 255.000000
[10:54:56.703] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 255 (20/-1) hits flags = 16 (plus default)
[10:54:56.711] <TB3> INFO: dacScan step from 0 .. 19
[10:54:56.711] <TB3> INFO: dacScan split into 1 runs with ntrig = 4
[10:54:56.711] <TB3> INFO: run 1 of 1
[10:55:09.745] <TB3> INFO: Test took 13034ms.
[10:55:09.765] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:09.765] <TB3> INFO: dacScan step from 20 .. 39
[10:55:09.765] <TB3> INFO: dacScan split into 1 runs with ntrig = 4
[10:55:09.765] <TB3> INFO: run 1 of 1
[10:55:23.962] <TB3> INFO: Test took 14197ms.
[10:55:24.046] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:24.068] <TB3> INFO: dacScan step from 40 .. 59
[10:55:24.069] <TB3> INFO: dacScan split into 1 runs with ntrig = 4
[10:55:24.069] <TB3> INFO: run 1 of 1
[10:55:41.204] <TB3> INFO: Test took 17135ms.
[10:55:41.352] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:41.381] <TB3> INFO: dacScan step from 60 .. 79
[10:55:41.381] <TB3> INFO: dacScan split into 1 runs with ntrig = 4
[10:55:41.381] <TB3> INFO: run 1 of 1
[10:55:58.124] <TB3> INFO: Test took 16743ms.
[10:55:58.266] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:58.306] <TB3> INFO: dacScan step from 80 .. 99
[10:55:58.307] <TB3> INFO: dacScan split into 1 runs with ntrig = 4
[10:55:58.307] <TB3> INFO: run 1 of 1
[10:56:14.886] <TB3> INFO: Test took 16579ms.
[10:56:15.027] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:15.060] <TB3> INFO: dacScan step from 100 .. 119
[10:56:15.060] <TB3> INFO: dacScan split into 1 runs with ntrig = 4
[10:56:15.060] <TB3> INFO: run 1 of 1
[10:56:32.203] <TB3> INFO: Test took 17143ms.
[10:56:32.343] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:32.375] <TB3> INFO: dacScan step from 120 .. 139
[10:56:32.375] <TB3> INFO: dacScan split into 1 runs with ntrig = 4
[10:56:32.375] <TB3> INFO: run 1 of 1
[10:56:48.929] <TB3> INFO: Test took 16554ms.
[10:56:49.065] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:49.098] <TB3> INFO: dacScan step from 140 .. 159
[10:56:49.098] <TB3> INFO: dacScan split into 1 runs with ntrig = 4
[10:56:49.098] <TB3> INFO: run 1 of 1
[10:57:05.629] <TB3> INFO: Test took 16531ms.
[10:57:05.764] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:05.796] <TB3> INFO: dacScan step from 160 .. 179
[10:57:05.796] <TB3> INFO: dacScan split into 1 runs with ntrig = 4
[10:57:05.796] <TB3> INFO: run 1 of 1
[10:57:23.538] <TB3> INFO: Test took 17742ms.
[10:57:23.685] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:23.717] <TB3> INFO: dacScan step from 180 .. 199
[10:57:23.718] <TB3> INFO: dacScan split into 1 runs with ntrig = 4
[10:57:23.718] <TB3> INFO: run 1 of 1
[10:57:42.036] <TB3> INFO: Test took 18318ms.
[10:57:42.259] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:42.313] <TB3> INFO: dacScan step from 200 .. 219
[10:57:42.313] <TB3> INFO: dacScan split into 1 runs with ntrig = 4
[10:57:42.313] <TB3> INFO: run 1 of 1
[10:57:59.235] <TB3> INFO: Test took 16922ms.
[10:57:59.389] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:59.424] <TB3> INFO: dacScan step from 220 .. 239
[10:57:59.424] <TB3> INFO: dacScan split into 1 runs with ntrig = 4
[10:57:59.424] <TB3> INFO: run 1 of 1
[10:58:17.031] <TB3> INFO: Test took 17607ms.
[10:58:17.190] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:58:17.222] <TB3> INFO: dacScan step from 240 .. 255
[10:58:17.222] <TB3> INFO: dacScan split into 1 runs with ntrig = 4
[10:58:17.222] <TB3> INFO: run 1 of 1
[10:58:31.844] <TB3> INFO: Test took 14622ms.
[10:58:31.964] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:59:03.298] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 12.651331 .. 44.266192
[10:59:03.377] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 2 .. 54 (20/-1) hits flags = 16 (plus default)
[10:59:03.386] <TB3> INFO: dacScan step from 2 .. 21
[10:59:03.386] <TB3> INFO: dacScan split into 1 runs with ntrig = 4
[10:59:03.386] <TB3> INFO: run 1 of 1
[10:59:16.983] <TB3> INFO: Test took 13597ms.
[10:59:17.009] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:59:17.009] <TB3> INFO: dacScan step from 22 .. 41
[10:59:17.009] <TB3> INFO: dacScan split into 1 runs with ntrig = 4
[10:59:17.009] <TB3> INFO: run 1 of 1
[10:59:32.253] <TB3> INFO: Test took 15244ms.
[10:59:32.355] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:59:32.375] <TB3> INFO: dacScan step from 42 .. 54
[10:59:32.375] <TB3> INFO: dacScan split into 1 runs with ntrig = 4
[10:59:32.375] <TB3> INFO: run 1 of 1
[10:59:44.777] <TB3> INFO: Test took 12402ms.
[10:59:44.884] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:00:02.085] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 19.699960 .. 41.343851
[11:00:02.181] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 9 .. 51 (20/-1) hits flags = 16 (plus default)
[11:00:02.190] <TB3> INFO: dacScan step from 9 .. 28
[11:00:02.190] <TB3> INFO: dacScan split into 1 runs with ntrig = 4
[11:00:02.190] <TB3> INFO: run 1 of 1
[11:00:15.331] <TB3> INFO: Test took 13140ms.
[11:00:15.358] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:00:15.358] <TB3> INFO: dacScan step from 29 .. 48
[11:00:15.358] <TB3> INFO: dacScan split into 1 runs with ntrig = 4
[11:00:15.358] <TB3> INFO: run 1 of 1
[11:00:30.836] <TB3> INFO: Test took 15478ms.
[11:00:30.972] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:00:31.003] <TB3> INFO: dacScan step from 49 .. 51
[11:00:31.003] <TB3> INFO: dacScan split into 1 runs with ntrig = 4
[11:00:31.003] <TB3> INFO: run 1 of 1
[11:00:35.888] <TB3> INFO: Test took 4885ms.
[11:00:35.910] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:00:50.609] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 3.500000 .. 40.003330
[11:00:50.699] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 3 .. 50 (20/-1) hits flags = 16 (plus default)
[11:00:50.708] <TB3> INFO: dacScan step from 3 .. 22
[11:00:50.708] <TB3> INFO: dacScan split into 1 runs with ntrig = 4
[11:00:50.708] <TB3> INFO: run 1 of 1
[11:01:03.747] <TB3> INFO: Test took 13039ms.
[11:01:03.766] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:03.766] <TB3> INFO: dacScan step from 23 .. 42
[11:01:03.766] <TB3> INFO: dacScan split into 1 runs with ntrig = 4
[11:01:03.766] <TB3> INFO: run 1 of 1
[11:01:18.176] <TB3> INFO: Test took 14410ms.
[11:01:18.270] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:18.290] <TB3> INFO: dacScan step from 43 .. 50
[11:01:18.290] <TB3> INFO: dacScan split into 1 runs with ntrig = 4
[11:01:18.290] <TB3> INFO: run 1 of 1
[11:01:27.041] <TB3> INFO: Test took 8751ms.
[11:01:27.100] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:42.752] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[11:01:42.752] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20/5) hits flags = 16 (plus default)
[11:01:42.763] <TB3> INFO: dacScan step from 15 .. 34
[11:01:42.763] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:01:42.763] <TB3> INFO: run 1 of 2
[11:01:58.183] <TB3> INFO: Test took 15420ms.
[11:01:58.233] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:58.241] <TB3> INFO: run 2 of 2
[11:02:12.748] <TB3> INFO: Test took 14506ms.
[11:02:12.786] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:12.793] <TB3> INFO: dacScan step from 35 .. 54
[11:02:12.793] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:02:12.793] <TB3> INFO: run 1 of 2
[11:02:32.799] <TB3> INFO: Test took 20006ms.
[11:02:32.990] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:33.026] <TB3> INFO: run 2 of 2
[11:02:53.007] <TB3> INFO: Test took 19981ms.
[11:02:53.189] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:53.221] <TB3> INFO: dacScan step from 55 .. 55
[11:02:53.222] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:02:53.222] <TB3> INFO: run 1 of 2
[11:02:56.885] <TB3> INFO: Test took 3663ms.
[11:02:56.900] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:56.904] <TB3> INFO: run 2 of 2
[11:03:00.645] <TB3> INFO: Test took 3741ms.
[11:03:00.661] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:03:15.299] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C0.dat
[11:03:15.299] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C1.dat
[11:03:15.299] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C2.dat
[11:03:15.299] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C3.dat
[11:03:15.299] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C4.dat
[11:03:15.299] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C5.dat
[11:03:15.299] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C6.dat
[11:03:15.299] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C7.dat
[11:03:15.299] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C8.dat
[11:03:15.299] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C9.dat
[11:03:15.300] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C10.dat
[11:03:15.300] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C11.dat
[11:03:15.300] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C12.dat
[11:03:15.300] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C13.dat
[11:03:15.300] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C14.dat
[11:03:15.300] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C15.dat
[11:03:15.300] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C0.dat
[11:03:15.306] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C1.dat
[11:03:15.312] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C2.dat
[11:03:15.318] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C3.dat
[11:03:15.324] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C4.dat
[11:03:15.330] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C5.dat
[11:03:15.336] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C6.dat
[11:03:15.342] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C7.dat
[11:03:15.348] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C8.dat
[11:03:15.354] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C9.dat
[11:03:15.360] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C10.dat
[11:03:15.366] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C11.dat
[11:03:15.372] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C12.dat
[11:03:15.378] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C13.dat
[11:03:15.384] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C14.dat
[11:03:15.390] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C15.dat
[11:03:15.396] <TB3> INFO: PixTestTrim::trimTest() done
[11:03:15.396] <TB3> INFO: vtrim: 120 111 117 107 101 100 105 103 100 103 103 102 101 100 95 119
[11:03:15.396] <TB3> INFO: vthrcomp: 85 98 98 87 81 91 89 89 78 84 82 91 80 92 85 93
[11:03:15.396] <TB3> INFO: vcal mean: 35.00 35.06 35.04 35.02 35.02 35.06 35.00 34.99 35.03 35.03 35.04 35.03 35.03 35.03 35.06 35.12
[11:03:15.396] <TB3> INFO: vcal RMS: 1.46 1.49 1.16 1.00 0.94 0.98 1.05 1.08 0.93 0.99 0.98 1.15 1.22 0.96 1.04 3.61
[11:03:15.396] <TB3> INFO: bits mean: 9.75 9.81 9.52 9.97 9.75 9.55 9.34 9.48 9.50 9.78 10.06 9.52 9.94 9.53 9.63 10.24
[11:03:15.396] <TB3> INFO: bits RMS: 2.55 2.54 2.63 2.58 2.63 2.55 2.63 2.54 2.31 2.70 2.37 2.45 2.38 2.67 2.60 2.49
[11:03:15.403] <TB3> INFO: ----------------------------------------------------------------------
[11:03:15.403] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[11:03:15.403] <TB3> INFO: ----------------------------------------------------------------------
[11:03:15.406] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[11:03:15.414] <TB3> INFO: dacScan step from 0 .. 19
[11:03:15.414] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:03:15.414] <TB3> INFO: run 1 of 2
[11:03:30.406] <TB3> INFO: Test took 14993ms.
[11:03:30.435] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:03:30.435] <TB3> INFO: run 2 of 2
[11:03:45.600] <TB3> INFO: Test took 15165ms.
[11:03:45.623] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:03:45.623] <TB3> INFO: dacScan step from 20 .. 39
[11:03:45.623] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:03:45.623] <TB3> INFO: run 1 of 2
[11:04:00.029] <TB3> INFO: Test took 14406ms.
[11:04:00.051] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:04:00.051] <TB3> INFO: run 2 of 2
[11:04:14.557] <TB3> INFO: Test took 14506ms.
[11:04:14.580] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:04:14.581] <TB3> INFO: dacScan step from 40 .. 59
[11:04:14.581] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:04:14.581] <TB3> INFO: run 1 of 2
[11:04:29.619] <TB3> INFO: Test took 15038ms.
[11:04:29.645] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:04:29.645] <TB3> INFO: run 2 of 2
[11:04:44.784] <TB3> INFO: Test took 15139ms.
[11:04:44.811] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:04:44.811] <TB3> INFO: dacScan step from 60 .. 79
[11:04:44.811] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:04:44.811] <TB3> INFO: run 1 of 2
[11:04:59.985] <TB3> INFO: Test took 15174ms.
[11:05:00.014] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:00.014] <TB3> INFO: run 2 of 2
[11:05:15.029] <TB3> INFO: Test took 15015ms.
[11:05:15.059] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:15.059] <TB3> INFO: dacScan step from 80 .. 99
[11:05:15.059] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:05:15.059] <TB3> INFO: run 1 of 2
[11:05:30.233] <TB3> INFO: Test took 15173ms.
[11:05:30.274] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:30.278] <TB3> INFO: run 2 of 2
[11:05:45.006] <TB3> INFO: Test took 14728ms.
[11:05:45.040] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:45.042] <TB3> INFO: dacScan step from 100 .. 119
[11:05:45.042] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:05:45.042] <TB3> INFO: run 1 of 2
[11:06:02.910] <TB3> INFO: Test took 17867ms.
[11:06:03.037] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:06:03.059] <TB3> INFO: run 2 of 2
[11:06:19.815] <TB3> INFO: Test took 16756ms.
[11:06:19.948] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:06:19.973] <TB3> INFO: dacScan step from 120 .. 139
[11:06:19.973] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:06:19.973] <TB3> INFO: run 1 of 2
[11:06:38.615] <TB3> INFO: Test took 18642ms.
[11:06:38.810] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:06:38.846] <TB3> INFO: run 2 of 2
[11:06:57.589] <TB3> INFO: Test took 18743ms.
[11:06:57.756] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:06:57.787] <TB3> INFO: dacScan step from 140 .. 159
[11:06:57.787] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:06:57.787] <TB3> INFO: run 1 of 2
[11:07:17.124] <TB3> INFO: Test took 19337ms.
[11:07:17.282] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:07:17.312] <TB3> INFO: run 2 of 2
[11:07:37.513] <TB3> INFO: Test took 20201ms.
[11:07:37.684] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:07:37.727] <TB3> INFO: dacScan step from 160 .. 179
[11:07:37.727] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:07:37.727] <TB3> INFO: run 1 of 2
[11:07:58.175] <TB3> INFO: Test took 20448ms.
[11:07:58.360] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:07:58.389] <TB3> INFO: run 2 of 2
[11:08:17.151] <TB3> INFO: Test took 18762ms.
[11:08:17.319] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:08:17.352] <TB3> INFO: dacScan step from 180 .. 199
[11:08:17.352] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:08:17.352] <TB3> INFO: run 1 of 2
[11:08:38.317] <TB3> INFO: Test took 20965ms.
[11:08:38.490] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:08:38.530] <TB3> INFO: run 2 of 2
[11:08:59.642] <TB3> INFO: Test took 21112ms.
[11:08:59.805] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:25.635] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 172 (20/5) hits flags = 16 (plus default)
[11:09:25.644] <TB3> INFO: dacScan step from 0 .. 19
[11:09:25.644] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:09:25.644] <TB3> INFO: run 1 of 2
[11:09:40.358] <TB3> INFO: Test took 14714ms.
[11:09:40.379] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:40.379] <TB3> INFO: run 2 of 2
[11:09:54.932] <TB3> INFO: Test took 14553ms.
[11:09:54.955] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:54.956] <TB3> INFO: dacScan step from 20 .. 39
[11:09:54.956] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:09:54.956] <TB3> INFO: run 1 of 2
[11:10:09.999] <TB3> INFO: Test took 15043ms.
[11:10:10.023] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:10.023] <TB3> INFO: run 2 of 2
[11:10:25.178] <TB3> INFO: Test took 15155ms.
[11:10:25.211] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:25.211] <TB3> INFO: dacScan step from 40 .. 59
[11:10:25.211] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:10:25.211] <TB3> INFO: run 1 of 2
[11:10:40.356] <TB3> INFO: Test took 15145ms.
[11:10:40.382] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:40.382] <TB3> INFO: run 2 of 2
[11:10:55.613] <TB3> INFO: Test took 15231ms.
[11:10:55.635] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:55.635] <TB3> INFO: dacScan step from 60 .. 79
[11:10:55.635] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:10:55.635] <TB3> INFO: run 1 of 2
[11:11:10.782] <TB3> INFO: Test took 15146ms.
[11:11:10.812] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:11:10.812] <TB3> INFO: run 2 of 2
[11:11:26.011] <TB3> INFO: Test took 15199ms.
[11:11:26.035] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:11:26.035] <TB3> INFO: dacScan step from 80 .. 99
[11:11:26.035] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:11:26.035] <TB3> INFO: run 1 of 2
[11:11:42.178] <TB3> INFO: Test took 16143ms.
[11:11:42.263] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:11:42.273] <TB3> INFO: run 2 of 2
[11:11:57.938] <TB3> INFO: Test took 15664ms.
[11:11:58.012] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:11:58.021] <TB3> INFO: dacScan step from 100 .. 119
[11:11:58.021] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:11:58.021] <TB3> INFO: run 1 of 2
[11:12:16.080] <TB3> INFO: Test took 18059ms.
[11:12:16.244] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:12:16.274] <TB3> INFO: run 2 of 2
[11:12:35.345] <TB3> INFO: Test took 19071ms.
[11:12:35.509] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:12:35.535] <TB3> INFO: dacScan step from 120 .. 139
[11:12:35.535] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:12:35.535] <TB3> INFO: run 1 of 2
[11:12:55.752] <TB3> INFO: Test took 20217ms.
[11:12:55.927] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:12:55.963] <TB3> INFO: run 2 of 2
[11:13:16.190] <TB3> INFO: Test took 20226ms.
[11:13:16.366] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:16.404] <TB3> INFO: dacScan step from 140 .. 159
[11:13:16.404] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:13:16.404] <TB3> INFO: run 1 of 2
[11:13:36.396] <TB3> INFO: Test took 19992ms.
[11:13:36.588] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:36.623] <TB3> INFO: run 2 of 2
[11:13:56.724] <TB3> INFO: Test took 20101ms.
[11:13:56.889] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:56.921] <TB3> INFO: dacScan step from 160 .. 172
[11:13:56.921] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:13:56.921] <TB3> INFO: run 1 of 2
[11:14:10.921] <TB3> INFO: Test took 14000ms.
[11:14:11.034] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:11.055] <TB3> INFO: run 2 of 2
[11:14:25.239] <TB3> INFO: Test took 14184ms.
[11:14:25.341] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:48.675] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 159 (20/5) hits flags = 16 (plus default)
[11:14:48.684] <TB3> INFO: dacScan step from 0 .. 19
[11:14:48.684] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:14:48.684] <TB3> INFO: run 1 of 2
[11:15:03.825] <TB3> INFO: Test took 15141ms.
[11:15:03.852] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:03.852] <TB3> INFO: run 2 of 2
[11:15:18.817] <TB3> INFO: Test took 14965ms.
[11:15:18.842] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:18.842] <TB3> INFO: dacScan step from 20 .. 39
[11:15:18.842] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:15:18.842] <TB3> INFO: run 1 of 2
[11:15:34.011] <TB3> INFO: Test took 15169ms.
[11:15:34.041] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:34.041] <TB3> INFO: run 2 of 2
[11:15:49.239] <TB3> INFO: Test took 15198ms.
[11:15:49.265] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:49.265] <TB3> INFO: dacScan step from 40 .. 59
[11:15:49.265] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:15:49.265] <TB3> INFO: run 1 of 2
[11:16:03.711] <TB3> INFO: Test took 14446ms.
[11:16:03.733] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:03.733] <TB3> INFO: run 2 of 2
[11:16:18.145] <TB3> INFO: Test took 14412ms.
[11:16:18.166] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:18.166] <TB3> INFO: dacScan step from 60 .. 79
[11:16:18.166] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:16:18.166] <TB3> INFO: run 1 of 2
[11:16:33.240] <TB3> INFO: Test took 15074ms.
[11:16:33.264] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:33.264] <TB3> INFO: run 2 of 2
[11:16:48.452] <TB3> INFO: Test took 15188ms.
[11:16:48.475] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:48.475] <TB3> INFO: dacScan step from 80 .. 99
[11:16:48.475] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:16:48.475] <TB3> INFO: run 1 of 2
[11:17:03.749] <TB3> INFO: Test took 15274ms.
[11:17:03.821] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:03.831] <TB3> INFO: run 2 of 2
[11:17:19.127] <TB3> INFO: Test took 15296ms.
[11:17:19.198] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:19.207] <TB3> INFO: dacScan step from 100 .. 119
[11:17:19.207] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:17:19.207] <TB3> INFO: run 1 of 2
[11:17:37.570] <TB3> INFO: Test took 18363ms.
[11:17:37.747] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:37.778] <TB3> INFO: run 2 of 2
[11:17:57.211] <TB3> INFO: Test took 19433ms.
[11:17:57.376] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:57.404] <TB3> INFO: dacScan step from 120 .. 139
[11:17:57.404] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:17:57.404] <TB3> INFO: run 1 of 2
[11:18:17.684] <TB3> INFO: Test took 20280ms.
[11:18:17.852] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:17.883] <TB3> INFO: run 2 of 2
[11:18:38.173] <TB3> INFO: Test took 20290ms.
[11:18:38.342] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:38.371] <TB3> INFO: dacScan step from 140 .. 159
[11:18:38.371] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:18:38.371] <TB3> INFO: run 1 of 2
[11:18:58.492] <TB3> INFO: Test took 20121ms.
[11:18:58.659] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:58.691] <TB3> INFO: run 2 of 2
[11:19:18.886] <TB3> INFO: Test took 20195ms.
[11:19:19.055] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:42.965] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 158 (20/5) hits flags = 16 (plus default)
[11:19:42.977] <TB3> INFO: dacScan step from 0 .. 19
[11:19:42.977] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:19:42.977] <TB3> INFO: run 1 of 2
[11:19:58.004] <TB3> INFO: Test took 15027ms.
[11:19:58.030] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:58.030] <TB3> INFO: run 2 of 2
[11:20:13.144] <TB3> INFO: Test took 15113ms.
[11:20:13.171] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:13.171] <TB3> INFO: dacScan step from 20 .. 39
[11:20:13.171] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:20:13.171] <TB3> INFO: run 1 of 2
[11:20:28.310] <TB3> INFO: Test took 15139ms.
[11:20:28.334] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:28.334] <TB3> INFO: run 2 of 2
[11:20:43.498] <TB3> INFO: Test took 15163ms.
[11:20:43.524] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:43.524] <TB3> INFO: dacScan step from 40 .. 59
[11:20:43.524] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:20:43.524] <TB3> INFO: run 1 of 2
[11:20:58.540] <TB3> INFO: Test took 15016ms.
[11:20:58.564] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:58.564] <TB3> INFO: run 2 of 2
[11:21:13.673] <TB3> INFO: Test took 15109ms.
[11:21:13.695] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:13.695] <TB3> INFO: dacScan step from 60 .. 79
[11:21:13.695] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:21:13.696] <TB3> INFO: run 1 of 2
[11:21:28.713] <TB3> INFO: Test took 15017ms.
[11:21:28.736] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:28.736] <TB3> INFO: run 2 of 2
[11:21:43.250] <TB3> INFO: Test took 14514ms.
[11:21:43.279] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:43.279] <TB3> INFO: dacScan step from 80 .. 99
[11:21:43.279] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:21:43.279] <TB3> INFO: run 1 of 2
[11:21:58.801] <TB3> INFO: Test took 15521ms.
[11:21:58.903] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:58.917] <TB3> INFO: run 2 of 2
[11:22:14.271] <TB3> INFO: Test took 15354ms.
[11:22:14.346] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:14.356] <TB3> INFO: dacScan step from 100 .. 119
[11:22:14.356] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:22:14.356] <TB3> INFO: run 1 of 2
[11:22:32.450] <TB3> INFO: Test took 18094ms.
[11:22:32.617] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:32.651] <TB3> INFO: run 2 of 2
[11:22:52.010] <TB3> INFO: Test took 19359ms.
[11:22:52.190] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:52.220] <TB3> INFO: dacScan step from 120 .. 139
[11:22:52.220] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:22:52.220] <TB3> INFO: run 1 of 2
[11:23:12.463] <TB3> INFO: Test took 20243ms.
[11:23:12.639] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:12.672] <TB3> INFO: run 2 of 2
[11:23:32.781] <TB3> INFO: Test took 20109ms.
[11:23:32.961] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:32.993] <TB3> INFO: dacScan step from 140 .. 158
[11:23:32.993] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:23:32.993] <TB3> INFO: run 1 of 2
[11:23:52.165] <TB3> INFO: Test took 19172ms.
[11:23:52.321] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:52.352] <TB3> INFO: run 2 of 2
[11:24:10.726] <TB3> INFO: Test took 18374ms.
[11:24:10.897] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:24:33.782] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 158 (20/5) hits flags = 16 (plus default)
[11:24:33.791] <TB3> INFO: dacScan step from 0 .. 19
[11:24:33.791] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:24:33.791] <TB3> INFO: run 1 of 2
[11:24:48.176] <TB3> INFO: Test took 14385ms.
[11:24:48.198] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:24:48.198] <TB3> INFO: run 2 of 2
[11:25:02.657] <TB3> INFO: Test took 14459ms.
[11:25:02.677] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:02.677] <TB3> INFO: dacScan step from 20 .. 39
[11:25:02.677] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:25:02.677] <TB3> INFO: run 1 of 2
[11:25:17.145] <TB3> INFO: Test took 14467ms.
[11:25:17.167] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:17.167] <TB3> INFO: run 2 of 2
[11:25:31.631] <TB3> INFO: Test took 14463ms.
[11:25:31.654] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:31.654] <TB3> INFO: dacScan step from 40 .. 59
[11:25:31.654] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:25:31.654] <TB3> INFO: run 1 of 2
[11:25:46.793] <TB3> INFO: Test took 15139ms.
[11:25:46.821] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:46.821] <TB3> INFO: run 2 of 2
[11:26:01.769] <TB3> INFO: Test took 14947ms.
[11:26:01.792] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:01.793] <TB3> INFO: dacScan step from 60 .. 79
[11:26:01.793] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:26:01.793] <TB3> INFO: run 1 of 2
[11:26:16.255] <TB3> INFO: Test took 14462ms.
[11:26:16.277] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:16.277] <TB3> INFO: run 2 of 2
[11:26:30.594] <TB3> INFO: Test took 14317ms.
[11:26:30.616] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:30.616] <TB3> INFO: dacScan step from 80 .. 99
[11:26:30.616] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:26:30.616] <TB3> INFO: run 1 of 2
[11:26:45.887] <TB3> INFO: Test took 15271ms.
[11:26:45.959] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:45.970] <TB3> INFO: run 2 of 2
[11:27:02.233] <TB3> INFO: Test took 16263ms.
[11:27:02.312] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:02.323] <TB3> INFO: dacScan step from 100 .. 119
[11:27:02.323] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:27:02.323] <TB3> INFO: run 1 of 2
[11:27:21.716] <TB3> INFO: Test took 19393ms.
[11:27:21.880] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:21.914] <TB3> INFO: run 2 of 2
[11:27:41.456] <TB3> INFO: Test took 19542ms.
[11:27:41.613] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:41.641] <TB3> INFO: dacScan step from 120 .. 139
[11:27:41.641] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:27:41.641] <TB3> INFO: run 1 of 2
[11:28:01.969] <TB3> INFO: Test took 20328ms.
[11:28:02.133] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:02.164] <TB3> INFO: run 2 of 2
[11:28:21.345] <TB3> INFO: Test took 19181ms.
[11:28:21.516] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:21.557] <TB3> INFO: dacScan step from 140 .. 158
[11:28:21.557] <TB3> INFO: dacScan split into 2 runs with ntrig = 5
[11:28:21.557] <TB3> INFO: run 1 of 2
[11:28:39.841] <TB3> INFO: Test took 18284ms.
[11:28:39.996] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:40.025] <TB3> INFO: run 2 of 2
[11:28:59.258] <TB3> INFO: Test took 19233ms.
[11:28:59.442] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:25.584] <TB3> INFO: PixTestTrim::trimBitTest() done
[11:29:25.585] <TB3> INFO: PixTestTrim::doTest() done, duration: 2905 seconds
[11:29:26.298] <TB3> INFO: ######################################################################
[11:29:26.298] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[11:29:26.298] <TB3> INFO: ######################################################################
[11:29:29.775] <TB3> INFO: Test took 3475ms.
[11:29:29.798] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:33.497] <TB3> INFO: Test took 3502ms.
[11:29:33.568] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:37.038] <TB3> INFO: Test took 3460ms.
[11:29:37.101] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:37.108] <TB3> INFO: The DUT currently contains the following objects:
[11:29:37.108] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:29:37.108] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:29:37.108] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:29:37.108] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:29:37.108] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:37.108] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:37.108] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:37.108] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:37.108] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:37.108] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:37.108] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:37.108] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:37.108] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:37.108] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:37.108] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:37.108] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:37.108] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:37.108] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:37.108] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:37.108] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:38.216] <TB3> INFO: Test took 1108ms.
[11:29:38.217] <TB3> INFO: The DUT currently contains the following objects:
[11:29:38.217] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:29:38.217] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:29:38.217] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:29:38.217] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:29:38.217] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:38.217] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:38.217] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:38.218] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:38.218] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:38.218] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:38.218] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:38.218] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:38.218] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:38.218] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:38.218] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:38.218] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:38.218] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:38.218] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:38.218] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:38.218] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:39.325] <TB3> INFO: Test took 1107ms.
[11:29:39.326] <TB3> INFO: The DUT currently contains the following objects:
[11:29:39.326] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:29:39.326] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:29:39.326] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:29:39.326] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:29:39.326] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:39.326] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:39.326] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:39.326] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:39.326] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:39.326] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:39.326] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:39.327] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:39.327] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:39.327] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:39.327] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:39.327] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:39.327] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:39.327] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:39.327] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:39.327] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:40.433] <TB3> INFO: Test took 1106ms.
[11:29:40.434] <TB3> INFO: The DUT currently contains the following objects:
[11:29:40.434] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:29:40.434] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:29:40.434] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:29:40.434] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:29:40.434] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:40.434] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:40.434] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:40.435] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:40.435] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:40.435] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:40.435] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:40.435] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:40.435] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:40.435] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:40.435] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:40.435] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:40.435] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:40.435] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:40.435] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:40.435] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:41.542] <TB3> INFO: Test took 1107ms.
[11:29:41.543] <TB3> INFO: The DUT currently contains the following objects:
[11:29:41.543] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:29:41.543] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:29:41.543] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:29:41.543] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:29:41.543] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:41.543] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:41.543] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:41.543] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:41.543] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:41.543] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:41.543] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:41.543] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:41.543] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:41.543] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:41.543] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:41.543] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:41.543] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:41.543] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:41.543] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:41.543] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:42.652] <TB3> INFO: Test took 1109ms.
[11:29:42.656] <TB3> INFO: The DUT currently contains the following objects:
[11:29:42.656] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:29:42.656] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:29:42.656] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:29:42.656] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:29:42.656] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:42.656] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:42.656] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:42.656] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:42.656] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:42.656] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:42.656] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:42.656] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:42.656] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:42.656] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:42.656] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:42.656] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:42.656] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:42.656] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:42.656] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:42.656] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:43.764] <TB3> INFO: Test took 1108ms.
[11:29:43.767] <TB3> INFO: The DUT currently contains the following objects:
[11:29:43.767] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:29:43.767] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:29:43.767] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:29:43.767] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:29:43.767] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:43.767] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:43.767] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:43.767] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:43.767] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:43.767] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:43.767] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:43.767] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:43.767] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:43.767] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:43.767] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:43.767] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:43.767] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:43.767] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:43.767] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:43.768] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:44.874] <TB3> INFO: Test took 1106ms.
[11:29:44.875] <TB3> INFO: The DUT currently contains the following objects:
[11:29:44.875] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:29:44.875] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:29:44.875] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:29:44.875] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:29:44.875] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:44.875] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:44.875] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:44.875] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:44.875] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:44.875] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:44.875] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:44.875] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:44.875] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:44.875] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:44.875] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:44.875] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:44.875] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:44.875] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:44.875] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:44.875] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:45.983] <TB3> INFO: Test took 1108ms.
[11:29:45.985] <TB3> INFO: The DUT currently contains the following objects:
[11:29:45.985] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:29:45.985] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:29:45.985] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:29:45.985] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:29:45.985] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:45.985] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:45.985] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:45.985] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:45.985] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:45.985] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:45.985] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:45.985] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:45.985] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:45.985] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:45.986] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:45.986] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:45.986] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:45.986] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:45.986] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:45.986] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:47.095] <TB3> INFO: Test took 1109ms.
[11:29:47.097] <TB3> INFO: The DUT currently contains the following objects:
[11:29:47.097] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:29:47.097] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:29:47.097] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:29:47.097] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:29:47.097] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:47.097] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:47.097] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:47.097] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:47.097] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:47.097] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:47.097] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:47.097] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:47.097] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:47.097] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:47.097] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:47.097] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:47.098] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:47.098] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:47.098] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:47.098] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:48.205] <TB3> INFO: Test took 1107ms.
[11:29:48.207] <TB3> INFO: The DUT currently contains the following objects:
[11:29:48.207] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:29:48.207] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:29:48.207] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:29:48.207] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:29:48.207] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:48.207] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:48.207] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:48.207] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:48.207] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:48.207] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:48.207] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:48.207] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:48.207] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:48.207] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:48.207] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:48.207] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:48.208] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:48.208] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:48.208] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:48.208] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:49.317] <TB3> INFO: Test took 1109ms.
[11:29:49.318] <TB3> INFO: The DUT currently contains the following objects:
[11:29:49.318] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:29:49.318] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:29:49.318] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:29:49.318] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:29:49.318] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:49.318] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:49.318] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:49.318] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:49.318] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:49.318] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:49.319] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:49.319] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:49.319] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:49.319] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:49.319] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:49.319] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:49.319] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:49.319] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:49.319] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:49.319] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:50.426] <TB3> INFO: Test took 1107ms.
[11:29:50.427] <TB3> INFO: The DUT currently contains the following objects:
[11:29:50.427] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:29:50.427] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:29:50.427] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:29:50.427] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:29:50.427] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:50.427] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:50.427] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:50.427] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:50.427] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:50.427] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:50.427] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:50.427] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:50.427] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:50.427] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:50.427] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:50.427] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:50.427] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:50.427] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:50.427] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:50.427] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:51.537] <TB3> INFO: Test took 1110ms.
[11:29:51.539] <TB3> INFO: The DUT currently contains the following objects:
[11:29:51.539] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:29:51.539] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:29:51.539] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:29:51.539] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:29:51.539] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:51.539] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:51.539] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:51.539] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:51.539] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:51.539] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:51.539] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:51.539] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:51.539] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:51.539] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:51.539] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:51.539] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:51.539] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:51.539] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:51.540] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:51.540] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:52.647] <TB3> INFO: Test took 1107ms.
[11:29:52.648] <TB3> INFO: The DUT currently contains the following objects:
[11:29:52.648] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:29:52.648] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:29:52.648] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:29:52.648] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:29:52.648] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:52.648] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:52.648] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:52.648] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:52.648] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:52.648] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:52.648] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:52.648] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:52.648] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:52.648] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:52.648] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:52.648] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:52.648] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:52.648] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:52.649] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:52.649] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:53.756] <TB3> INFO: Test took 1107ms.
[11:29:53.757] <TB3> INFO: The DUT currently contains the following objects:
[11:29:53.757] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:29:53.758] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:29:53.758] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:29:53.758] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:29:53.758] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:53.758] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:53.758] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:53.758] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:53.758] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:53.758] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:53.758] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:53.758] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:53.758] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:53.758] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:53.758] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:53.758] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:53.758] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:53.758] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:53.759] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:53.759] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:29:54.866] <TB3> INFO: Test took 1107ms.
[11:29:54.871] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:33:40.273] <TB3> INFO: Test took 225403ms.
[11:33:41.833] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:24.999] <TB3> INFO: Test took 223166ms.
[11:37:26.666] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:26.674] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:26.681] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:26.689] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:26.697] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:26.706] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:26.714] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:26.722] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:26.729] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:26.736] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:26.743] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:26.750] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:26.757] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:26.764] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[11:37:26.771] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[11:37:26.778] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[11:37:26.785] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[11:37:26.792] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[11:37:26.799] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[11:37:26.805] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[11:37:26.812] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[11:37:26.819] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:26.826] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:26.833] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:26.840] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[11:37:26.847] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[11:37:26.854] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[11:37:26.861] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[11:37:26.868] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[11:37:26.908] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C0.dat
[11:37:26.909] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C1.dat
[11:37:26.909] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C2.dat
[11:37:26.909] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C3.dat
[11:37:26.909] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C4.dat
[11:37:26.909] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C5.dat
[11:37:26.909] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C6.dat
[11:37:26.909] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C7.dat
[11:37:26.909] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C8.dat
[11:37:26.910] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C9.dat
[11:37:26.910] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C10.dat
[11:37:26.910] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C11.dat
[11:37:26.910] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C12.dat
[11:37:26.910] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C13.dat
[11:37:26.910] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C14.dat
[11:37:26.910] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C15.dat
[11:37:30.359] <TB3> INFO: Test took 3445ms.
[11:37:33.951] <TB3> INFO: Test took 3320ms.
[11:37:37.509] <TB3> INFO: Test took 3288ms.
[11:37:37.826] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:38.740] <TB3> INFO: Test took 914ms.
[11:37:38.742] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:39.844] <TB3> INFO: Test took 1102ms.
[11:37:39.846] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:40.947] <TB3> INFO: Test took 1101ms.
[11:37:40.949] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:42.051] <TB3> INFO: Test took 1102ms.
[11:37:42.053] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:43.156] <TB3> INFO: Test took 1103ms.
[11:37:43.158] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:44.261] <TB3> INFO: Test took 1103ms.
[11:37:44.264] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:45.365] <TB3> INFO: Test took 1101ms.
[11:37:45.369] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:46.469] <TB3> INFO: Test took 1101ms.
[11:37:46.471] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:47.574] <TB3> INFO: Test took 1103ms.
[11:37:47.577] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:48.679] <TB3> INFO: Test took 1102ms.
[11:37:48.682] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:49.785] <TB3> INFO: Test took 1103ms.
[11:37:49.788] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:50.891] <TB3> INFO: Test took 1103ms.
[11:37:50.893] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:51.997] <TB3> INFO: Test took 1104ms.
[11:37:51.999] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:53.101] <TB3> INFO: Test took 1102ms.
[11:37:53.103] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:54.206] <TB3> INFO: Test took 1103ms.
[11:37:54.210] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:55.310] <TB3> INFO: Test took 1101ms.
[11:37:55.312] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:56.417] <TB3> INFO: Test took 1105ms.
[11:37:56.420] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:57.521] <TB3> INFO: Test took 1101ms.
[11:37:57.523] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:58.624] <TB3> INFO: Test took 1101ms.
[11:37:58.627] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:59.729] <TB3> INFO: Test took 1102ms.
[11:37:59.732] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:00.832] <TB3> INFO: Test took 1100ms.
[11:38:00.834] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:01.937] <TB3> INFO: Test took 1103ms.
[11:38:01.939] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:03.042] <TB3> INFO: Test took 1103ms.
[11:38:03.045] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:04.147] <TB3> INFO: Test took 1102ms.
[11:38:04.149] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:05.250] <TB3> INFO: Test took 1101ms.
[11:38:05.252] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:06.352] <TB3> INFO: Test took 1100ms.
[11:38:06.355] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:07.455] <TB3> INFO: Test took 1100ms.
[11:38:07.458] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:08.558] <TB3> INFO: Test took 1100ms.
[11:38:08.559] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:09.670] <TB3> INFO: Test took 1111ms.
[11:38:09.673] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:10.783] <TB3> INFO: Test took 1110ms.
[11:38:10.786] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:11.894] <TB3> INFO: Test took 1108ms.
[11:38:11.897] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:13.005] <TB3> INFO: Test took 1108ms.
[11:38:13.551] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 527 seconds
[11:38:13.551] <TB3> INFO: PH scale (per ROC): 75 69 81 90 80 78 81 86 95 81 80 77 80 82 81 80
[11:38:13.551] <TB3> INFO: PH offset (per ROC): 146 171 166 143 164 160 166 151 146 164 145 156 144 158 150 165
[11:38:13.741] <TB3> INFO: ######################################################################
[11:38:13.741] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:38:13.741] <TB3> INFO: ######################################################################
[11:38:13.751] <TB3> INFO: scanning low vcal = 10
[11:38:17.591] <TB3> INFO: Test took 3840ms.
[11:38:17.597] <TB3> INFO: scanning low vcal = 20
[11:38:21.460] <TB3> INFO: Test took 3863ms.
[11:38:21.465] <TB3> INFO: scanning low vcal = 30
[11:38:25.337] <TB3> INFO: Test took 3872ms.
[11:38:25.344] <TB3> INFO: scanning low vcal = 40
[11:38:29.710] <TB3> INFO: Test took 4366ms.
[11:38:29.769] <TB3> INFO: scanning low vcal = 50
[11:38:34.145] <TB3> INFO: Test took 4376ms.
[11:38:34.207] <TB3> INFO: scanning low vcal = 60
[11:38:38.563] <TB3> INFO: Test took 4356ms.
[11:38:38.625] <TB3> INFO: scanning low vcal = 70
[11:38:42.993] <TB3> INFO: Test took 4368ms.
[11:38:43.049] <TB3> INFO: scanning low vcal = 80
[11:38:47.422] <TB3> INFO: Test took 4373ms.
[11:38:47.487] <TB3> INFO: scanning low vcal = 90
[11:38:51.848] <TB3> INFO: Test took 4361ms.
[11:38:51.907] <TB3> INFO: scanning low vcal = 100
[11:38:56.262] <TB3> INFO: Test took 4355ms.
[11:38:56.329] <TB3> INFO: scanning low vcal = 110
[11:39:00.697] <TB3> INFO: Test took 4368ms.
[11:39:00.759] <TB3> INFO: scanning low vcal = 120
[11:39:05.135] <TB3> INFO: Test took 4376ms.
[11:39:05.198] <TB3> INFO: scanning low vcal = 130
[11:39:09.534] <TB3> INFO: Test took 4336ms.
[11:39:09.596] <TB3> INFO: scanning low vcal = 140
[11:39:13.953] <TB3> INFO: Test took 4357ms.
[11:39:14.008] <TB3> INFO: scanning low vcal = 150
[11:39:18.350] <TB3> INFO: Test took 4342ms.
[11:39:18.418] <TB3> INFO: scanning low vcal = 160
[11:39:22.781] <TB3> INFO: Test took 4363ms.
[11:39:22.848] <TB3> INFO: scanning low vcal = 170
[11:39:27.216] <TB3> INFO: Test took 4368ms.
[11:39:27.292] <TB3> INFO: scanning low vcal = 180
[11:39:31.681] <TB3> INFO: Test took 4389ms.
[11:39:31.737] <TB3> INFO: scanning low vcal = 190
[11:39:36.196] <TB3> INFO: Test took 4459ms.
[11:39:36.259] <TB3> INFO: scanning low vcal = 200
[11:39:40.626] <TB3> INFO: Test took 4367ms.
[11:39:40.689] <TB3> INFO: scanning low vcal = 210
[11:39:45.052] <TB3> INFO: Test took 4363ms.
[11:39:45.117] <TB3> INFO: scanning low vcal = 220
[11:39:49.446] <TB3> INFO: Test took 4329ms.
[11:39:49.508] <TB3> INFO: scanning low vcal = 230
[11:39:53.885] <TB3> INFO: Test took 4377ms.
[11:39:53.951] <TB3> INFO: scanning low vcal = 240
[11:39:58.312] <TB3> INFO: Test took 4361ms.
[11:39:58.370] <TB3> INFO: scanning low vcal = 250
[11:40:02.684] <TB3> INFO: Test took 4314ms.
[11:40:02.755] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[11:40:07.099] <TB3> INFO: Test took 4344ms.
[11:40:07.160] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[11:40:11.509] <TB3> INFO: Test took 4349ms.
[11:40:11.576] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[11:40:15.771] <TB3> INFO: Test took 4195ms.
[11:40:15.826] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[11:40:20.025] <TB3> INFO: Test took 4199ms.
[11:40:20.090] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:40:24.288] <TB3> INFO: Test took 4198ms.
[11:40:24.788] <TB3> INFO: PixTestGainPedestal::measure() done
[11:40:58.531] <TB3> INFO: PixTestGainPedestal::fit() done
[11:40:58.531] <TB3> INFO: non-linearity mean: 0.957 0.958 0.960 0.952 0.956 0.953 0.958 0.961 0.954 0.953 0.955 0.955 0.962 0.959 0.959 0.960
[11:40:58.531] <TB3> INFO: non-linearity RMS: 0.006 0.006 0.005 0.006 0.005 0.006 0.006 0.006 0.006 0.006 0.005 0.006 0.004 0.006 0.007 0.005
[11:40:58.531] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[11:40:58.552] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[11:40:58.572] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[11:40:58.592] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[11:40:58.613] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[11:40:58.633] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[11:40:58.653] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[11:40:58.673] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[11:40:58.693] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[11:40:58.713] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[11:40:58.734] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[11:40:58.754] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[11:40:58.774] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[11:40:58.794] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[11:40:58.815] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[11:40:58.836] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[11:40:58.856] <TB3> INFO: PixTestGainPedestal::doTest() done, duration: 165 seconds
[11:40:58.863] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C15.dat
[11:40:58.864] <TB3> INFO: PixTestReadback::doTest() start.
[11:40:58.866] <TB3> INFO: PixTestReadback::RES sent once
[11:41:15.388] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C0.dat
[11:41:15.389] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C1.dat
[11:41:15.389] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C2.dat
[11:41:15.389] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C3.dat
[11:41:15.389] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C4.dat
[11:41:15.389] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C5.dat
[11:41:15.389] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C6.dat
[11:41:15.389] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C7.dat
[11:41:15.389] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C8.dat
[11:41:15.389] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C9.dat
[11:41:15.389] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C10.dat
[11:41:15.389] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C11.dat
[11:41:15.390] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C12.dat
[11:41:15.390] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C13.dat
[11:41:15.390] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C14.dat
[11:41:15.390] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C15.dat
[11:41:15.427] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:41:15.427] <TB3> INFO: PixTestReadback::RES sent once
[11:41:31.889] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C0.dat
[11:41:31.889] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C1.dat
[11:41:31.890] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C2.dat
[11:41:31.890] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C3.dat
[11:41:31.890] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C4.dat
[11:41:31.890] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C5.dat
[11:41:31.890] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C6.dat
[11:41:31.891] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C7.dat
[11:41:31.891] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C8.dat
[11:41:31.891] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C9.dat
[11:41:31.891] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C10.dat
[11:41:31.892] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C11.dat
[11:41:31.892] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C12.dat
[11:41:31.892] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C13.dat
[11:41:31.892] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C14.dat
[11:41:31.892] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C15.dat
[11:41:31.925] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:41:31.926] <TB3> INFO: PixTestReadback::RES sent once
[11:41:44.651] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:41:44.652] <TB3> INFO: Vbg will be calibrated using Vd calibration
[11:41:44.652] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 152.6calibrated Vbg = 1.1912 :::*/*/*/*/
[11:41:44.652] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 156.4calibrated Vbg = 1.18694 :::*/*/*/*/
[11:41:44.652] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 159.2calibrated Vbg = 1.1914 :::*/*/*/*/
[11:41:44.652] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 255calibrated Vbg = 1.1725 :::*/*/*/*/
[11:41:44.652] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 149calibrated Vbg = 1.20313 :::*/*/*/*/
[11:41:44.652] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 153calibrated Vbg = 1.20735 :::*/*/*/*/
[11:41:44.652] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 151.1calibrated Vbg = 1.21111 :::*/*/*/*/
[11:41:44.652] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 255calibrated Vbg = 1.1725 :::*/*/*/*/
[11:41:44.652] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 146.9calibrated Vbg = 1.2018 :::*/*/*/*/
[11:41:44.652] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 162.5calibrated Vbg = 1.20414 :::*/*/*/*/
[11:41:44.652] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 154.5calibrated Vbg = 1.20418 :::*/*/*/*/
[11:41:44.652] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 255calibrated Vbg = 1.1725 :::*/*/*/*/
[11:41:44.652] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 145.9calibrated Vbg = 1.19772 :::*/*/*/*/
[11:41:44.652] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 167.3calibrated Vbg = 1.19676 :::*/*/*/*/
[11:41:44.652] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 160.8calibrated Vbg = 1.20037 :::*/*/*/*/
[11:41:44.652] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 247.8calibrated Vbg = 1.10629 :::*/*/*/*/
[11:41:44.655] <TB3> INFO: PixTestReadback::RES sent once
[11:45:39.420] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C0.dat
[11:45:39.420] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C1.dat
[11:45:39.420] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C2.dat
[11:45:39.420] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C3.dat
[11:45:39.420] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C4.dat
[11:45:39.420] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C5.dat
[11:45:39.420] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C6.dat
[11:45:39.420] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C7.dat
[11:45:39.420] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C8.dat
[11:45:39.420] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C9.dat
[11:45:39.420] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C10.dat
[11:45:39.420] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C11.dat
[11:45:39.421] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C12.dat
[11:45:39.421] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C13.dat
[11:45:39.421] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C14.dat
[11:45:39.421] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2083_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C15.dat
[11:45:39.452] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:45:39.453] <TB3> INFO: PixTestReadback::doTest() done
[11:45:39.465] <TB3> INFO: enter test to run
[11:45:39.466] <TB3> INFO: test: exit no parameter change
[11:45:40.013] <TB3> QUIET: Connection to board 170 closed.
[11:45:40.093] <TB3> INFO: pXar: this is the end, my friend