[07:26:10.269] <TB1> INFO: *** Welcome to pxar ***
[07:26:10.269] <TB1> INFO: *** Today: 2015/08/24
[07:26:10.269] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C15.dat
[07:26:10.269] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//tbmParameters_C0b.dat
[07:26:10.269] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//defaultMaskFile.dat
[07:26:10.269] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters_C15.dat
[07:26:10.347] <TB1> INFO: clk: 4
[07:26:10.347] <TB1> INFO: ctr: 4
[07:26:10.347] <TB1> INFO: sda: 19
[07:26:10.347] <TB1> INFO: tin: 9
[07:26:10.347] <TB1> INFO: level: 15
[07:26:10.347] <TB1> INFO: triggerdelay: 0
[07:26:10.347] <TB1> QUIET: Instanciating API for pxar prod-01+86~g1838649
[07:26:10.347] <TB1> INFO: Log level: INFO
[07:26:10.354] <TB1> INFO: Found DTB DTB_WXBYFL
[07:26:10.368] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[07:26:10.371] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[07:26:10.374] <TB1> INFO: RPC call hashes of host and DTB match: 447413373
[07:26:11.903] <TB1> INFO: DUT info:
[07:26:11.903] <TB1> INFO: The DUT currently contains the following objects:
[07:26:11.903] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[07:26:11.903] <TB1> INFO: TBM Core alpha (0): 7 registers set
[07:26:11.903] <TB1> INFO: TBM Core beta (1): 7 registers set
[07:26:11.903] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:26:11.904] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:11.904] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:11.904] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:11.904] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:11.904] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:11.904] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:11.904] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:11.904] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:11.904] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:11.904] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:11.904] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:11.904] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:11.904] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:11.904] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:11.904] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:11.904] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:12.305] <TB1> INFO: enter 'restricted' command line mode
[07:26:12.305] <TB1> INFO: enter test to run
[07:26:12.305] <TB1> INFO: test: pretest no parameter change
[07:26:12.305] <TB1> INFO: running: pretest
[07:26:12.312] <TB1> INFO: ######################################################################
[07:26:12.312] <TB1> INFO: PixTestPretest::doTest()
[07:26:12.312] <TB1> INFO: ######################################################################
[07:26:12.314] <TB1> INFO: ----------------------------------------------------------------------
[07:26:12.314] <TB1> INFO: PixTestPretest::programROC()
[07:26:12.314] <TB1> INFO: ----------------------------------------------------------------------
[07:26:30.337] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[07:26:30.337] <TB1> INFO: IA differences per ROC: 18.5 19.3 17.7 18.5 22.5 20.1 20.1 16.9 16.9 18.5 16.9 19.3 18.5 20.1 20.1 20.9
[07:26:30.424] <TB1> INFO: ----------------------------------------------------------------------
[07:26:30.424] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[07:26:30.424] <TB1> INFO: ----------------------------------------------------------------------
[07:26:50.014] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 388.3 mA = 24.2687 mA/ROC
[07:26:50.019] <TB1> INFO: ----------------------------------------------------------------------
[07:26:50.019] <TB1> INFO: PixTestPretest::findWorkingPixel()
[07:26:50.019] <TB1> INFO: ----------------------------------------------------------------------
[07:26:58.357] <TB1> INFO: Test took 8335ms.
[07:26:58.666] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[07:26:58.718] <TB1> INFO: ----------------------------------------------------------------------
[07:26:58.718] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[07:26:58.718] <TB1> INFO: ----------------------------------------------------------------------
[07:27:07.025] <TB1> INFO: Test took 8300ms.
[07:27:07.346] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[07:27:07.346] <TB1> INFO: CalDel: 131 143 149 137 153 135 137 141 126 154 122 133 133 129 162 178
[07:27:07.346] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[07:27:07.350] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C0.dat
[07:27:07.350] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C1.dat
[07:27:07.351] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C2.dat
[07:27:07.351] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C3.dat
[07:27:07.351] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C4.dat
[07:27:07.352] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C5.dat
[07:27:07.352] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C6.dat
[07:27:07.352] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C7.dat
[07:27:07.353] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C8.dat
[07:27:07.353] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C9.dat
[07:27:07.353] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C10.dat
[07:27:07.354] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C11.dat
[07:27:07.354] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C12.dat
[07:27:07.354] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C13.dat
[07:27:07.354] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C14.dat
[07:27:07.355] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C15.dat
[07:27:07.355] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//tbmParameters_C0a.dat
[07:27:07.355] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//tbmParameters_C0b.dat
[07:27:07.355] <TB1> INFO: PixTestPretest::doTest() done, duration: 55 seconds
[07:27:07.444] <TB1> INFO: enter test to run
[07:27:07.444] <TB1> INFO: test: fulltest no parameter change
[07:27:07.444] <TB1> INFO: running: fulltest
[07:27:07.445] <TB1> INFO: ######################################################################
[07:27:07.445] <TB1> INFO: PixTestFullTest::doTest()
[07:27:07.445] <TB1> INFO: ######################################################################
[07:27:07.447] <TB1> INFO: ######################################################################
[07:27:07.447] <TB1> INFO: PixTestAlive::doTest()
[07:27:07.447] <TB1> INFO: ######################################################################
[07:27:07.450] <TB1> INFO: ----------------------------------------------------------------------
[07:27:07.450] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:27:07.450] <TB1> INFO: ----------------------------------------------------------------------
[07:27:10.894] <TB1> INFO: Test took 3441ms.
[07:27:10.921] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:27:11.162] <TB1> INFO: PixTestAlive::aliveTest() done
[07:27:11.162] <TB1> INFO: number of dead pixels (per ROC): 1 1 0 2 0 0 0 0 0 0 0 2 0 0 0 0
[07:27:11.163] <TB1> INFO: ----------------------------------------------------------------------
[07:27:11.163] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:27:11.163] <TB1> INFO: ----------------------------------------------------------------------
[07:27:13.900] <TB1> INFO: Test took 2734ms.
[07:27:13.904] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:27:13.905] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[07:27:14.145] <TB1> INFO: PixTestAlive::maskTest() done
[07:27:14.146] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:27:14.147] <TB1> INFO: ----------------------------------------------------------------------
[07:27:14.148] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:27:14.148] <TB1> INFO: ----------------------------------------------------------------------
[07:27:17.595] <TB1> INFO: Test took 3445ms.
[07:27:17.618] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:27:17.859] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[07:27:17.859] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:27:17.860] <TB1> INFO: PixTestAlive::doTest() done, duration: 10 seconds
[07:27:17.870] <TB1> INFO: ######################################################################
[07:27:17.870] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[07:27:17.870] <TB1> INFO: ######################################################################
[07:27:17.874] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30/5) hits flags = 2 (plus default)
[07:27:17.884] <TB1> INFO: dacScan step from 0 .. 29
[07:27:17.884] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:27:17.884] <TB1> INFO: run 1 of 1
[07:27:39.326] <TB1> INFO: Test took 21442ms.
[07:27:39.359] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:27:39.359] <TB1> INFO: dacScan step from 30 .. 59
[07:27:39.359] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:27:39.359] <TB1> INFO: run 1 of 1
[07:28:02.374] <TB1> INFO: Test took 23015ms.
[07:28:02.482] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:28:02.495] <TB1> INFO: dacScan step from 60 .. 89
[07:28:02.495] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:28:02.495] <TB1> INFO: run 1 of 1
[07:28:31.468] <TB1> INFO: Test took 28973ms.
[07:28:31.787] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:28:31.842] <TB1> INFO: dacScan step from 90 .. 119
[07:28:31.842] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:28:31.842] <TB1> INFO: run 1 of 1
[07:29:01.027] <TB1> INFO: Test took 29184ms.
[07:29:01.312] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:29:01.367] <TB1> INFO: dacScan step from 120 .. 149
[07:29:01.367] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:29:01.367] <TB1> INFO: run 1 of 1
[07:29:25.283] <TB1> INFO: Test took 23916ms.
[07:29:25.454] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:29:48.885] <TB1> INFO: PixTestBBMap::doTest() done, duration: 151 seconds
[07:29:48.885] <TB1> INFO: number of dead bumps (per ROC): 0 0 0 1 0 0 0 1 0 0 3 1 0 0 1 2
[07:29:48.885] <TB1> INFO: separation cut (per ROC): 86 88 85 87 109 101 76 89 95 75 82 93 110 92 75 89
[07:29:48.960] <TB1> INFO: ######################################################################
[07:29:48.960] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = 4, ntrig/step = 50
[07:29:48.960] <TB1> INFO: ######################################################################
[07:29:48.960] <TB1> INFO: ----------------------------------------------------------------------
[07:29:48.960] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = 4, ntrig/step = 50
[07:29:48.960] <TB1> INFO: ----------------------------------------------------------------------
[07:29:48.960] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (4/50) hits flags = 16 (plus default)
[07:29:48.969] <TB1> INFO: dacScan step from 0 .. 3
[07:29:48.969] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:29:48.969] <TB1> INFO: run 1 of 1
[07:30:06.506] <TB1> INFO: Test took 17537ms.
[07:30:06.531] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:30:06.531] <TB1> INFO: dacScan step from 4 .. 7
[07:30:06.531] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:30:06.531] <TB1> INFO: run 1 of 1
[07:30:25.628] <TB1> INFO: Test took 19097ms.
[07:30:25.658] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:30:25.658] <TB1> INFO: dacScan step from 8 .. 11
[07:30:25.658] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:30:25.658] <TB1> INFO: run 1 of 1
[07:30:44.749] <TB1> INFO: Test took 19091ms.
[07:30:44.778] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:30:44.778] <TB1> INFO: dacScan step from 12 .. 15
[07:30:44.778] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:30:44.778] <TB1> INFO: run 1 of 1
[07:31:03.961] <TB1> INFO: Test took 19183ms.
[07:31:03.989] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:31:03.989] <TB1> INFO: dacScan step from 16 .. 19
[07:31:03.989] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:31:03.989] <TB1> INFO: run 1 of 1
[07:31:23.066] <TB1> INFO: Test took 19077ms.
[07:31:23.097] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:31:23.097] <TB1> INFO: dacScan step from 20 .. 23
[07:31:23.097] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:31:23.097] <TB1> INFO: run 1 of 1
[07:31:42.161] <TB1> INFO: Test took 19064ms.
[07:31:42.189] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:31:42.189] <TB1> INFO: dacScan step from 24 .. 27
[07:31:42.189] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:31:42.189] <TB1> INFO: run 1 of 1
[07:32:01.093] <TB1> INFO: Test took 18904ms.
[07:32:01.120] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:01.120] <TB1> INFO: dacScan step from 28 .. 31
[07:32:01.120] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:32:01.120] <TB1> INFO: run 1 of 1
[07:32:20.111] <TB1> INFO: Test took 18991ms.
[07:32:20.139] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:20.139] <TB1> INFO: dacScan step from 32 .. 35
[07:32:20.139] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:32:20.139] <TB1> INFO: run 1 of 1
[07:32:39.168] <TB1> INFO: Test took 19029ms.
[07:32:39.197] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:39.198] <TB1> INFO: dacScan step from 36 .. 39
[07:32:39.198] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:32:39.198] <TB1> INFO: run 1 of 1
[07:32:58.237] <TB1> INFO: Test took 19040ms.
[07:32:58.268] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:58.268] <TB1> INFO: dacScan step from 40 .. 43
[07:32:58.268] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:32:58.268] <TB1> INFO: run 1 of 1
[07:33:17.426] <TB1> INFO: Test took 19158ms.
[07:33:17.460] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:33:17.460] <TB1> INFO: dacScan step from 44 .. 47
[07:33:17.460] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:33:17.460] <TB1> INFO: run 1 of 1
[07:33:36.633] <TB1> INFO: Test took 19173ms.
[07:33:36.660] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:33:36.660] <TB1> INFO: dacScan step from 48 .. 51
[07:33:36.660] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:33:36.660] <TB1> INFO: run 1 of 1
[07:33:55.813] <TB1> INFO: Test took 19153ms.
[07:33:55.841] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:33:55.841] <TB1> INFO: dacScan step from 52 .. 55
[07:33:55.841] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:33:55.841] <TB1> INFO: run 1 of 1
[07:34:14.952] <TB1> INFO: Test took 19111ms.
[07:34:14.981] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:34:14.981] <TB1> INFO: dacScan step from 56 .. 59
[07:34:14.981] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:34:14.981] <TB1> INFO: run 1 of 1
[07:34:34.123] <TB1> INFO: Test took 19142ms.
[07:34:34.151] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:34:34.151] <TB1> INFO: dacScan step from 60 .. 63
[07:34:34.151] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:34:34.151] <TB1> INFO: run 1 of 1
[07:34:53.372] <TB1> INFO: Test took 19221ms.
[07:34:53.406] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:34:53.407] <TB1> INFO: dacScan step from 64 .. 67
[07:34:53.407] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:34:53.407] <TB1> INFO: run 1 of 1
[07:35:12.935] <TB1> INFO: Test took 19528ms.
[07:35:12.983] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:35:12.985] <TB1> INFO: dacScan step from 68 .. 71
[07:35:12.985] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:35:12.985] <TB1> INFO: run 1 of 1
[07:35:33.339] <TB1> INFO: Test took 20354ms.
[07:35:33.411] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:35:33.414] <TB1> INFO: dacScan step from 72 .. 75
[07:35:33.414] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:35:33.414] <TB1> INFO: run 1 of 1
[07:35:54.806] <TB1> INFO: Test took 21392ms.
[07:35:54.902] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:35:54.907] <TB1> INFO: dacScan step from 76 .. 79
[07:35:54.907] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:35:54.907] <TB1> INFO: run 1 of 1
[07:36:17.851] <TB1> INFO: Test took 22944ms.
[07:36:17.989] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:36:17.996] <TB1> INFO: dacScan step from 80 .. 83
[07:36:17.996] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:36:17.996] <TB1> INFO: run 1 of 1
[07:36:43.678] <TB1> INFO: Test took 25682ms.
[07:36:43.850] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:36:43.858] <TB1> INFO: dacScan step from 84 .. 87
[07:36:43.858] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:36:43.858] <TB1> INFO: run 1 of 1
[07:37:11.246] <TB1> INFO: Test took 27388ms.
[07:37:11.441] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:37:11.449] <TB1> INFO: dacScan step from 88 .. 91
[07:37:11.449] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:37:11.449] <TB1> INFO: run 1 of 1
[07:37:39.799] <TB1> INFO: Test took 28350ms.
[07:37:40.006] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:37:40.015] <TB1> INFO: dacScan step from 92 .. 95
[07:37:40.015] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:37:40.015] <TB1> INFO: run 1 of 1
[07:38:08.698] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[07:38:08.698] <TB1> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[07:38:09.264] <TB1> INFO: Test took 29249ms.
[07:38:09.498] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:38:09.508] <TB1> INFO: dacScan step from 96 .. 99
[07:38:09.508] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:38:09.508] <TB1> INFO: run 1 of 1
[07:38:38.079] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[07:38:38.079] <TB1> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[07:38:39.211] <TB1> INFO: Test took 29703ms.
[07:38:39.447] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:38:39.456] <TB1> INFO: dacScan step from 100 .. 103
[07:38:39.456] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:38:39.456] <TB1> INFO: run 1 of 1
[07:39:09.233] <TB1> INFO: Test took 29777ms.
[07:39:09.473] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:39:09.482] <TB1> INFO: dacScan step from 104 .. 107
[07:39:09.482] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:39:09.482] <TB1> INFO: run 1 of 1
[07:39:39.134] <TB1> INFO: Test took 29652ms.
[07:39:39.370] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:39:39.379] <TB1> INFO: dacScan step from 108 .. 111
[07:39:39.379] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:39:39.379] <TB1> INFO: run 1 of 1
[07:40:07.548] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[07:40:07.548] <TB1> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[07:40:08.822] <TB1> INFO: Test took 29443ms.
[07:40:09.062] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:40:09.071] <TB1> INFO: dacScan step from 112 .. 115
[07:40:09.071] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:40:09.071] <TB1> INFO: run 1 of 1
[07:40:37.394] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[07:40:37.394] <TB1> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[07:40:38.742] <TB1> INFO: Test took 29671ms.
[07:40:38.986] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:40:38.996] <TB1> INFO: dacScan step from 116 .. 119
[07:40:38.996] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:40:38.996] <TB1> INFO: run 1 of 1
[07:41:08.446] <TB1> INFO: Test took 29450ms.
[07:41:08.668] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:41:08.677] <TB1> INFO: dacScan step from 120 .. 123
[07:41:08.677] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:41:08.677] <TB1> INFO: run 1 of 1
[07:41:38.192] <TB1> INFO: Test took 29515ms.
[07:41:38.430] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:41:38.440] <TB1> INFO: dacScan step from 124 .. 127
[07:41:38.440] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:41:38.440] <TB1> INFO: run 1 of 1
[07:42:07.891] <TB1> INFO: Test took 29451ms.
[07:42:08.134] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:42:08.144] <TB1> INFO: dacScan step from 128 .. 131
[07:42:08.144] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:42:08.144] <TB1> INFO: run 1 of 1
[07:42:37.574] <TB1> INFO: Test took 29430ms.
[07:42:37.797] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:42:37.807] <TB1> INFO: dacScan step from 132 .. 135
[07:42:37.807] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:42:37.807] <TB1> INFO: run 1 of 1
[07:43:07.132] <TB1> INFO: Test took 29325ms.
[07:43:07.357] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:43:07.366] <TB1> INFO: dacScan step from 136 .. 139
[07:43:07.366] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:43:07.366] <TB1> INFO: run 1 of 1
[07:43:36.810] <TB1> INFO: Test took 29444ms.
[07:43:37.036] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:43:37.045] <TB1> INFO: dacScan step from 140 .. 143
[07:43:37.045] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:43:37.045] <TB1> INFO: run 1 of 1
[07:44:06.471] <TB1> INFO: Test took 29426ms.
[07:44:06.711] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:44:06.725] <TB1> INFO: dacScan step from 144 .. 147
[07:44:06.725] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:44:06.725] <TB1> INFO: run 1 of 1
[07:44:36.000] <TB1> INFO: Test took 29275ms.
[07:44:36.226] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:44:36.234] <TB1> INFO: dacScan step from 148 .. 149
[07:44:36.234] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:44:36.234] <TB1> INFO: run 1 of 1
[07:44:52.191] <TB1> INFO: Test took 15957ms.
[07:44:52.308] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:44:52.314] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:44:53.808] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:44:55.260] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:44:56.793] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:44:58.306] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:44:59.748] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:01.164] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:02.692] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:04.139] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:05.586] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:07.075] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:08.671] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:10.105] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:11.565] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:12.986] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:14.432] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:45:15.890] <TB1> INFO: PixTestScurves::scurves() done
[07:45:15.890] <TB1> INFO: Vcal mean: 80.93 77.11 72.38 83.59 93.72 91.97 70.18 86.98 92.98 73.31 79.46 84.68 90.59 81.33 73.87 87.12
[07:45:15.890] <TB1> INFO: Vcal RMS: 4.41 4.57 5.31 5.57 5.56 5.46 4.99 5.60 5.48 4.84 4.91 5.87 5.75 4.18 4.57 5.19
[07:45:15.890] <TB1> INFO: PixTestScurves::fullTest() done, duration: 926 seconds
[07:45:15.966] <TB1> INFO: ######################################################################
[07:45:15.966] <TB1> INFO: PixTestTrim::doTest()
[07:45:15.966] <TB1> INFO: ######################################################################
[07:45:15.968] <TB1> INFO: ----------------------------------------------------------------------
[07:45:15.968] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[07:45:15.968] <TB1> INFO: ----------------------------------------------------------------------
[07:45:16.055] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[07:45:16.055] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[07:45:16.064] <TB1> INFO: dacScan step from 0 .. 19
[07:45:16.064] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:45:16.064] <TB1> INFO: run 1 of 1
[07:45:31.256] <TB1> INFO: Test took 15192ms.
[07:45:31.278] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:45:31.278] <TB1> INFO: dacScan step from 20 .. 39
[07:45:31.278] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:45:31.278] <TB1> INFO: run 1 of 1
[07:45:46.474] <TB1> INFO: Test took 15195ms.
[07:45:46.501] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:45:46.501] <TB1> INFO: dacScan step from 40 .. 59
[07:45:46.501] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:45:46.501] <TB1> INFO: run 1 of 1
[07:46:01.692] <TB1> INFO: Test took 15191ms.
[07:46:01.722] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:46:01.722] <TB1> INFO: dacScan step from 60 .. 79
[07:46:01.722] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:46:01.722] <TB1> INFO: run 1 of 1
[07:46:16.168] <TB1> INFO: Test took 14446ms.
[07:46:16.191] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:46:16.192] <TB1> INFO: dacScan step from 80 .. 99
[07:46:16.192] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:46:16.192] <TB1> INFO: run 1 of 1
[07:46:31.769] <TB1> INFO: Test took 15577ms.
[07:46:31.850] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:46:31.862] <TB1> INFO: dacScan step from 100 .. 119
[07:46:31.862] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:46:31.862] <TB1> INFO: run 1 of 1
[07:46:51.140] <TB1> INFO: Test took 19278ms.
[07:46:51.338] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:46:51.372] <TB1> INFO: dacScan step from 120 .. 139
[07:46:51.372] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:46:51.372] <TB1> INFO: run 1 of 1
[07:47:09.973] <TB1> INFO: Test took 18601ms.
[07:47:10.112] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:47:10.134] <TB1> INFO: dacScan step from 140 .. 159
[07:47:10.134] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:47:10.134] <TB1> INFO: run 1 of 1
[07:47:25.994] <TB1> INFO: Test took 15860ms.
[07:47:26.071] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:47:49.467] <TB1> INFO: ROC 0 VthrComp = 89
[07:47:49.467] <TB1> INFO: ROC 1 VthrComp = 83
[07:47:49.467] <TB1> INFO: ROC 2 VthrComp = 76
[07:47:49.468] <TB1> INFO: ROC 3 VthrComp = 87
[07:47:49.468] <TB1> INFO: ROC 4 VthrComp = 102
[07:47:49.469] <TB1> INFO: ROC 5 VthrComp = 97
[07:47:49.469] <TB1> INFO: ROC 6 VthrComp = 77
[07:47:49.469] <TB1> INFO: ROC 7 VthrComp = 89
[07:47:49.470] <TB1> INFO: ROC 8 VthrComp = 97
[07:47:49.470] <TB1> INFO: ROC 9 VthrComp = 79
[07:47:49.470] <TB1> INFO: ROC 10 VthrComp = 80
[07:47:49.470] <TB1> INFO: ROC 11 VthrComp = 88
[07:47:49.471] <TB1> INFO: ROC 12 VthrComp = 94
[07:47:49.471] <TB1> INFO: ROC 13 VthrComp = 88
[07:47:49.471] <TB1> INFO: ROC 14 VthrComp = 77
[07:47:49.471] <TB1> INFO: ROC 15 VthrComp = 92
[07:47:49.471] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[07:47:49.471] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[07:47:49.480] <TB1> INFO: dacScan step from 0 .. 19
[07:47:49.480] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:47:49.480] <TB1> INFO: run 1 of 1
[07:48:04.674] <TB1> INFO: Test took 15194ms.
[07:48:04.701] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:48:04.701] <TB1> INFO: dacScan step from 20 .. 39
[07:48:04.701] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:48:04.701] <TB1> INFO: run 1 of 1
[07:48:19.986] <TB1> INFO: Test took 15285ms.
[07:48:20.020] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:48:20.022] <TB1> INFO: dacScan step from 40 .. 59
[07:48:20.022] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:48:20.022] <TB1> INFO: run 1 of 1
[07:48:38.944] <TB1> INFO: Test took 18922ms.
[07:48:39.099] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:48:39.126] <TB1> INFO: dacScan step from 60 .. 79
[07:48:39.126] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:48:39.126] <TB1> INFO: run 1 of 1
[07:48:57.870] <TB1> INFO: Test took 18744ms.
[07:48:58.045] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:48:58.081] <TB1> INFO: dacScan step from 80 .. 99
[07:48:58.081] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:48:58.081] <TB1> INFO: run 1 of 1
[07:49:16.958] <TB1> INFO: Test took 18877ms.
[07:49:17.127] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:49:17.164] <TB1> INFO: dacScan step from 100 .. 119
[07:49:17.164] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:49:17.164] <TB1> INFO: run 1 of 1
[07:49:37.349] <TB1> INFO: Test took 20185ms.
[07:49:37.515] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:49:37.553] <TB1> INFO: dacScan step from 120 .. 139
[07:49:37.553] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:49:37.553] <TB1> INFO: run 1 of 1
[07:49:57.832] <TB1> INFO: Test took 20279ms.
[07:49:57.998] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:49:58.028] <TB1> INFO: dacScan step from 140 .. 159
[07:49:58.028] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:49:58.028] <TB1> INFO: run 1 of 1
[07:50:17.745] <TB1> INFO: Test took 19717ms.
[07:50:17.907] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:50:42.720] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 57.2767 for pixel 25/78 mean/min/max = 45.0161/32.7187/57.3135
[07:50:42.720] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 57.0401 for pixel 25/78 mean/min/max = 44.6241/32.15/57.0983
[07:50:42.720] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 60.9561 for pixel 3/64 mean/min/max = 47.5758/34.0637/61.0879
[07:50:42.721] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 59.5602 for pixel 0/67 mean/min/max = 46.0156/32.2287/59.8025
[07:50:42.721] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 58.7417 for pixel 0/66 mean/min/max = 45.3248/31.8986/58.7511
[07:50:42.721] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 59.9185 for pixel 44/78 mean/min/max = 45.7967/31.5728/60.0207
[07:50:42.722] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 58.4537 for pixel 0/79 mean/min/max = 46.2017/33.8498/58.5535
[07:50:42.722] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 59.5394 for pixel 0/71 mean/min/max = 46.2328/32.7031/59.7625
[07:50:42.722] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 58.3057 for pixel 4/79 mean/min/max = 45.2349/32.0576/58.4121
[07:50:42.722] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 58.4355 for pixel 16/79 mean/min/max = 46.1873/33.9255/58.4491
[07:50:42.723] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 60.6383 for pixel 12/77 mean/min/max = 46.4855/32.3283/60.6427
[07:50:42.723] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 60.7388 for pixel 8/64 mean/min/max = 46.5314/32.1117/60.9512
[07:50:42.723] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 59.5832 for pixel 27/79 mean/min/max = 45.898/32.1214/59.6746
[07:50:42.723] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 56.7032 for pixel 25/1 mean/min/max = 45.0358/33.3677/56.7038
[07:50:42.724] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 57.6689 for pixel 45/0 mean/min/max = 46.3482/35.0196/57.6768
[07:50:42.724] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 58.3531 for pixel 5/4 mean/min/max = 45.4182/32.4116/58.4248
[07:50:42.724] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:52:16.241] <TB1> INFO: Test took 93517ms.
[07:52:17.678] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[07:52:17.687] <TB1> INFO: dacScan step from 0 .. 19
[07:52:17.687] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[07:52:17.687] <TB1> INFO: run 1 of 2
[07:52:32.835] <TB1> INFO: Test took 15148ms.
[07:52:32.868] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:52:32.868] <TB1> INFO: run 2 of 2
[07:52:48.122] <TB1> INFO: Test took 15253ms.
[07:52:48.149] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:52:48.150] <TB1> INFO: dacScan step from 20 .. 39
[07:52:48.150] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[07:52:48.150] <TB1> INFO: run 1 of 2
[07:53:06.083] <TB1> INFO: Test took 17933ms.
[07:53:06.210] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:53:06.232] <TB1> INFO: run 2 of 2
[07:53:24.146] <TB1> INFO: Test took 17914ms.
[07:53:24.286] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:53:24.310] <TB1> INFO: dacScan step from 40 .. 59
[07:53:24.310] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[07:53:24.310] <TB1> INFO: run 1 of 2
[07:53:44.639] <TB1> INFO: Test took 20329ms.
[07:53:44.810] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:53:44.844] <TB1> INFO: run 2 of 2
[07:54:04.968] <TB1> INFO: Test took 20124ms.
[07:54:05.136] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:54:05.169] <TB1> INFO: dacScan step from 60 .. 79
[07:54:05.169] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[07:54:05.169] <TB1> INFO: run 1 of 2
[07:54:24.233] <TB1> INFO: Test took 19064ms.
[07:54:24.399] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:54:24.455] <TB1> INFO: run 2 of 2
[07:54:43.361] <TB1> INFO: Test took 18906ms.
[07:54:43.529] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:54:43.584] <TB1> INFO: dacScan step from 80 .. 99
[07:54:43.584] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[07:54:43.584] <TB1> INFO: run 1 of 2
[07:55:02.774] <TB1> INFO: Test took 19190ms.
[07:55:02.937] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:55:02.987] <TB1> INFO: run 2 of 2
[07:55:22.211] <TB1> INFO: Test took 19224ms.
[07:55:22.385] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:55:22.436] <TB1> INFO: dacScan step from 100 .. 119
[07:55:22.436] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[07:55:22.436] <TB1> INFO: run 1 of 2
[07:55:42.223] <TB1> INFO: Test took 19786ms.
[07:55:42.395] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:55:42.425] <TB1> INFO: run 2 of 2
[07:56:02.616] <TB1> INFO: Test took 20190ms.
[07:56:02.781] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:56:02.821] <TB1> INFO: dacScan step from 120 .. 139
[07:56:02.821] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[07:56:02.821] <TB1> INFO: run 1 of 2
[07:56:23.044] <TB1> INFO: Test took 20223ms.
[07:56:23.221] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:56:23.254] <TB1> INFO: run 2 of 2
[07:56:43.523] <TB1> INFO: Test took 20269ms.
[07:56:43.708] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:56:43.742] <TB1> INFO: dacScan step from 140 .. 159
[07:56:43.742] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[07:56:43.742] <TB1> INFO: run 1 of 2
[07:57:03.986] <TB1> INFO: Test took 20244ms.
[07:57:04.157] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:57:04.189] <TB1> INFO: run 2 of 2
[07:57:24.444] <TB1> INFO: Test took 20255ms.
[07:57:24.618] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:57:24.650] <TB1> INFO: dacScan step from 160 .. 179
[07:57:24.650] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[07:57:24.650] <TB1> INFO: run 1 of 2
[07:57:44.765] <TB1> INFO: Test took 20115ms.
[07:57:45.033] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:57:45.078] <TB1> INFO: run 2 of 2
[07:58:05.530] <TB1> INFO: Test took 20452ms.
[07:58:05.702] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:58:05.733] <TB1> INFO: dacScan step from 180 .. 199
[07:58:05.733] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[07:58:05.733] <TB1> INFO: run 1 of 2
[07:58:26.862] <TB1> INFO: Test took 21129ms.
[07:58:27.022] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:58:27.053] <TB1> INFO: run 2 of 2
[07:58:47.557] <TB1> INFO: Test took 20504ms.
[07:58:47.718] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:59:12.444] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.029731 .. 150.135238
[07:59:12.523] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 160 (20/-1) hits flags = 16 (plus default)
[07:59:12.531] <TB1> INFO: dacScan step from 0 .. 19
[07:59:12.531] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[07:59:12.531] <TB1> INFO: run 1 of 1
[07:59:26.151] <TB1> INFO: Test took 13620ms.
[07:59:26.173] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:59:26.174] <TB1> INFO: dacScan step from 20 .. 39
[07:59:26.174] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[07:59:26.174] <TB1> INFO: run 1 of 1
[07:59:40.917] <TB1> INFO: Test took 14743ms.
[07:59:41.029] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:59:41.064] <TB1> INFO: dacScan step from 40 .. 59
[07:59:41.064] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[07:59:41.065] <TB1> INFO: run 1 of 1
[07:59:58.711] <TB1> INFO: Test took 17646ms.
[07:59:58.875] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:59:58.912] <TB1> INFO: dacScan step from 60 .. 79
[07:59:58.912] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[07:59:58.912] <TB1> INFO: run 1 of 1
[08:00:16.717] <TB1> INFO: Test took 17805ms.
[08:00:16.866] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:00:16.899] <TB1> INFO: dacScan step from 80 .. 99
[08:00:16.899] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[08:00:16.899] <TB1> INFO: run 1 of 1
[08:00:34.624] <TB1> INFO: Test took 17725ms.
[08:00:34.760] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:00:34.793] <TB1> INFO: dacScan step from 100 .. 119
[08:00:34.793] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[08:00:34.793] <TB1> INFO: run 1 of 1
[08:00:51.817] <TB1> INFO: Test took 17024ms.
[08:00:51.959] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:00:51.992] <TB1> INFO: dacScan step from 120 .. 139
[08:00:51.992] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[08:00:51.992] <TB1> INFO: run 1 of 1
[08:01:08.832] <TB1> INFO: Test took 16840ms.
[08:01:08.973] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:01:09.008] <TB1> INFO: dacScan step from 140 .. 159
[08:01:09.008] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[08:01:09.008] <TB1> INFO: run 1 of 1
[08:01:25.556] <TB1> INFO: Test took 16548ms.
[08:01:25.698] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:01:25.731] <TB1> INFO: dacScan step from 160 .. 160
[08:01:25.731] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[08:01:25.731] <TB1> INFO: run 1 of 1
[08:01:29.327] <TB1> INFO: Test took 3596ms.
[08:01:29.341] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:01:57.407] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 14.651866 .. 84.596657
[08:01:57.498] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 4 .. 94 (20/-1) hits flags = 16 (plus default)
[08:01:57.507] <TB1> INFO: dacScan step from 4 .. 23
[08:01:57.507] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[08:01:57.507] <TB1> INFO: run 1 of 1
[08:02:11.143] <TB1> INFO: Test took 13636ms.
[08:02:11.164] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:02:11.165] <TB1> INFO: dacScan step from 24 .. 43
[08:02:11.165] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[08:02:11.165] <TB1> INFO: run 1 of 1
[08:02:26.777] <TB1> INFO: Test took 15612ms.
[08:02:26.880] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:02:26.904] <TB1> INFO: dacScan step from 44 .. 63
[08:02:26.904] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[08:02:26.904] <TB1> INFO: run 1 of 1
[08:02:44.617] <TB1> INFO: Test took 17712ms.
[08:02:44.759] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:02:44.790] <TB1> INFO: dacScan step from 64 .. 83
[08:02:44.790] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[08:02:44.790] <TB1> INFO: run 1 of 1
[08:03:01.307] <TB1> INFO: Test took 16516ms.
[08:03:01.465] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:03:01.501] <TB1> INFO: dacScan step from 84 .. 94
[08:03:01.501] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[08:03:01.501] <TB1> INFO: run 1 of 1
[08:03:11.869] <TB1> INFO: Test took 10368ms.
[08:03:11.948] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:03:32.934] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 1.031159 .. 55.927910
[08:03:33.013] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 1 .. 65 (20/-1) hits flags = 16 (plus default)
[08:03:33.022] <TB1> INFO: dacScan step from 1 .. 20
[08:03:33.022] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[08:03:33.022] <TB1> INFO: run 1 of 1
[08:03:46.546] <TB1> INFO: Test took 13524ms.
[08:03:46.566] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:03:46.566] <TB1> INFO: dacScan step from 21 .. 40
[08:03:46.566] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[08:03:46.566] <TB1> INFO: run 1 of 1
[08:04:01.453] <TB1> INFO: Test took 14887ms.
[08:04:01.540] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:04:01.557] <TB1> INFO: dacScan step from 41 .. 60
[08:04:01.557] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[08:04:01.557] <TB1> INFO: run 1 of 1
[08:04:19.099] <TB1> INFO: Test took 17542ms.
[08:04:19.244] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:04:19.276] <TB1> INFO: dacScan step from 61 .. 65
[08:04:19.276] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[08:04:19.276] <TB1> INFO: run 1 of 1
[08:04:25.573] <TB1> INFO: Test took 6297ms.
[08:04:25.609] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:04:43.195] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 2.361817 .. 55.927910
[08:04:43.280] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 2 .. 65 (20/-1) hits flags = 16 (plus default)
[08:04:43.289] <TB1> INFO: dacScan step from 2 .. 21
[08:04:43.289] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[08:04:43.289] <TB1> INFO: run 1 of 1
[08:04:56.922] <TB1> INFO: Test took 13633ms.
[08:04:56.949] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:04:56.949] <TB1> INFO: dacScan step from 22 .. 41
[08:04:56.949] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[08:04:56.949] <TB1> INFO: run 1 of 1
[08:05:11.213] <TB1> INFO: Test took 14264ms.
[08:05:11.297] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:05:11.316] <TB1> INFO: dacScan step from 42 .. 61
[08:05:11.316] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[08:05:11.316] <TB1> INFO: run 1 of 1
[08:05:28.032] <TB1> INFO: Test took 16716ms.
[08:05:28.168] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:05:28.201] <TB1> INFO: dacScan step from 62 .. 65
[08:05:28.201] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[08:05:28.201] <TB1> INFO: run 1 of 1
[08:05:33.796] <TB1> INFO: Test took 5595ms.
[08:05:33.825] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:05:50.960] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[08:05:50.960] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20/5) hits flags = 16 (plus default)
[08:05:50.969] <TB1> INFO: dacScan step from 15 .. 34
[08:05:50.969] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:05:50.969] <TB1> INFO: run 1 of 2
[08:06:06.325] <TB1> INFO: Test took 15356ms.
[08:06:06.368] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:06:06.375] <TB1> INFO: run 2 of 2
[08:06:21.524] <TB1> INFO: Test took 15149ms.
[08:06:21.562] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:06:21.568] <TB1> INFO: dacScan step from 35 .. 54
[08:06:21.568] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:06:21.568] <TB1> INFO: run 1 of 2
[08:06:40.348] <TB1> INFO: Test took 18780ms.
[08:06:40.539] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:06:40.578] <TB1> INFO: run 2 of 2
[08:06:59.186] <TB1> INFO: Test took 18608ms.
[08:06:59.368] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:06:59.412] <TB1> INFO: dacScan step from 55 .. 55
[08:06:59.412] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:06:59.412] <TB1> INFO: run 1 of 2
[08:07:03.080] <TB1> INFO: Test took 3668ms.
[08:07:03.091] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:07:03.096] <TB1> INFO: run 2 of 2
[08:07:06.747] <TB1> INFO: Test took 3651ms.
[08:07:06.757] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:07:20.445] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:07:20.445] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:07:20.445] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:07:20.445] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:07:20.445] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:07:20.446] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:07:20.446] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:07:20.446] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:07:20.446] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:07:20.446] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:07:20.446] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:07:20.446] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:07:20.446] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:07:20.446] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:07:20.447] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:07:20.447] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:07:20.447] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C0.dat
[08:07:20.454] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C1.dat
[08:07:20.460] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C2.dat
[08:07:20.467] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C3.dat
[08:07:20.473] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C4.dat
[08:07:20.479] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C5.dat
[08:07:20.484] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C6.dat
[08:07:20.490] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C7.dat
[08:07:20.497] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C8.dat
[08:07:20.502] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C9.dat
[08:07:20.508] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C10.dat
[08:07:20.515] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C11.dat
[08:07:20.521] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C12.dat
[08:07:20.528] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C13.dat
[08:07:20.534] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C14.dat
[08:07:20.541] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C15.dat
[08:07:20.547] <TB1> INFO: PixTestTrim::trimTest() done
[08:07:20.547] <TB1> INFO: vtrim: 117 103 111 99 110 112 102 107 95 113 114 114 108 102 89 116
[08:07:20.547] <TB1> INFO: vthrcomp: 89 83 76 87 102 97 77 89 97 79 80 88 94 88 77 92
[08:07:20.547] <TB1> INFO: vcal mean: 35.01 35.01 35.05 35.03 35.03 35.00 35.05 35.02 35.03 35.07 35.01 35.08 35.07 35.05 35.05 35.02
[08:07:20.547] <TB1> INFO: vcal RMS: 1.07 1.08 0.91 1.25 1.00 1.04 0.88 1.00 0.98 0.94 1.07 1.07 1.01 0.92 0.92 0.99
[08:07:20.547] <TB1> INFO: bits mean: 9.86 9.64 8.88 9.35 9.81 9.80 8.79 9.43 9.44 9.52 9.84 9.67 9.55 9.68 9.01 10.23
[08:07:20.547] <TB1> INFO: bits RMS: 2.43 2.71 2.53 2.73 2.60 2.60 2.69 2.61 2.73 2.38 2.45 2.55 2.63 2.46 2.44 2.27
[08:07:20.557] <TB1> INFO: ----------------------------------------------------------------------
[08:07:20.557] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[08:07:20.557] <TB1> INFO: ----------------------------------------------------------------------
[08:07:20.561] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[08:07:20.570] <TB1> INFO: dacScan step from 0 .. 19
[08:07:20.570] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:07:20.570] <TB1> INFO: run 1 of 2
[08:07:35.080] <TB1> INFO: Test took 14510ms.
[08:07:35.108] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:07:35.108] <TB1> INFO: run 2 of 2
[08:07:50.322] <TB1> INFO: Test took 15214ms.
[08:07:50.351] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:07:50.351] <TB1> INFO: dacScan step from 20 .. 39
[08:07:50.352] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:07:50.352] <TB1> INFO: run 1 of 2
[08:08:05.537] <TB1> INFO: Test took 15185ms.
[08:08:05.566] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:08:05.566] <TB1> INFO: run 2 of 2
[08:08:20.062] <TB1> INFO: Test took 14496ms.
[08:08:20.086] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:08:20.086] <TB1> INFO: dacScan step from 40 .. 59
[08:08:20.086] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:08:20.086] <TB1> INFO: run 1 of 2
[08:08:34.694] <TB1> INFO: Test took 14608ms.
[08:08:34.722] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:08:34.722] <TB1> INFO: run 2 of 2
[08:08:49.426] <TB1> INFO: Test took 14703ms.
[08:08:49.452] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:08:49.452] <TB1> INFO: dacScan step from 60 .. 79
[08:08:49.452] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:08:49.452] <TB1> INFO: run 1 of 2
[08:09:04.591] <TB1> INFO: Test took 15138ms.
[08:09:04.618] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:09:04.618] <TB1> INFO: run 2 of 2
[08:09:19.826] <TB1> INFO: Test took 15208ms.
[08:09:19.854] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:09:19.854] <TB1> INFO: dacScan step from 80 .. 99
[08:09:19.854] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:09:19.854] <TB1> INFO: run 1 of 2
[08:09:35.672] <TB1> INFO: Test took 15818ms.
[08:09:35.728] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:09:35.733] <TB1> INFO: run 2 of 2
[08:09:51.339] <TB1> INFO: Test took 15605ms.
[08:09:51.392] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:09:51.397] <TB1> INFO: dacScan step from 100 .. 119
[08:09:51.397] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:09:51.397] <TB1> INFO: run 1 of 2
[08:10:09.640] <TB1> INFO: Test took 18242ms.
[08:10:09.783] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:10:09.807] <TB1> INFO: run 2 of 2
[08:10:27.978] <TB1> INFO: Test took 18171ms.
[08:10:28.123] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:10:28.151] <TB1> INFO: dacScan step from 120 .. 139
[08:10:28.151] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:10:28.151] <TB1> INFO: run 1 of 2
[08:10:48.462] <TB1> INFO: Test took 20311ms.
[08:10:48.634] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:10:48.663] <TB1> INFO: run 2 of 2
[08:11:08.958] <TB1> INFO: Test took 20295ms.
[08:11:09.126] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:11:09.157] <TB1> INFO: dacScan step from 140 .. 159
[08:11:09.157] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:11:09.157] <TB1> INFO: run 1 of 2
[08:11:29.313] <TB1> INFO: Test took 20156ms.
[08:11:29.480] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:11:29.510] <TB1> INFO: run 2 of 2
[08:11:49.737] <TB1> INFO: Test took 20227ms.
[08:11:49.918] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:11:49.955] <TB1> INFO: dacScan step from 160 .. 179
[08:11:49.955] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:11:49.955] <TB1> INFO: run 1 of 2
[08:12:10.405] <TB1> INFO: Test took 20450ms.
[08:12:10.606] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:12:10.635] <TB1> INFO: run 2 of 2
[08:12:30.938] <TB1> INFO: Test took 20302ms.
[08:12:31.109] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:12:31.147] <TB1> INFO: dacScan step from 180 .. 199
[08:12:31.147] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:12:31.147] <TB1> INFO: run 1 of 2
[08:12:52.313] <TB1> INFO: Test took 21165ms.
[08:12:52.487] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:12:52.523] <TB1> INFO: run 2 of 2
[08:13:13.647] <TB1> INFO: Test took 21124ms.
[08:13:13.813] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:13:41.755] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 175 (20/5) hits flags = 16 (plus default)
[08:13:41.764] <TB1> INFO: dacScan step from 0 .. 19
[08:13:41.764] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:13:41.764] <TB1> INFO: run 1 of 2
[08:13:56.978] <TB1> INFO: Test took 15214ms.
[08:13:57.004] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:13:57.004] <TB1> INFO: run 2 of 2
[08:14:12.189] <TB1> INFO: Test took 15185ms.
[08:14:12.214] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:14:12.214] <TB1> INFO: dacScan step from 20 .. 39
[08:14:12.214] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:14:12.214] <TB1> INFO: run 1 of 2
[08:14:27.172] <TB1> INFO: Test took 14957ms.
[08:14:27.195] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:14:27.195] <TB1> INFO: run 2 of 2
[08:14:41.599] <TB1> INFO: Test took 14404ms.
[08:14:41.621] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:14:41.621] <TB1> INFO: dacScan step from 40 .. 59
[08:14:41.621] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:14:41.621] <TB1> INFO: run 1 of 2
[08:14:56.325] <TB1> INFO: Test took 14704ms.
[08:14:56.355] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:14:56.355] <TB1> INFO: run 2 of 2
[08:15:11.462] <TB1> INFO: Test took 15107ms.
[08:15:11.484] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:15:11.484] <TB1> INFO: dacScan step from 60 .. 79
[08:15:11.484] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:15:11.484] <TB1> INFO: run 1 of 2
[08:15:26.700] <TB1> INFO: Test took 15216ms.
[08:15:26.733] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:15:26.733] <TB1> INFO: run 2 of 2
[08:15:41.949] <TB1> INFO: Test took 15216ms.
[08:15:41.976] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:15:41.976] <TB1> INFO: dacScan step from 80 .. 99
[08:15:41.976] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:15:41.976] <TB1> INFO: run 1 of 2
[08:15:58.882] <TB1> INFO: Test took 16906ms.
[08:15:58.997] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:15:59.013] <TB1> INFO: run 2 of 2
[08:16:15.925] <TB1> INFO: Test took 16912ms.
[08:16:16.029] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:16:16.042] <TB1> INFO: dacScan step from 100 .. 119
[08:16:16.042] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:16:16.042] <TB1> INFO: run 1 of 2
[08:16:34.497] <TB1> INFO: Test took 18455ms.
[08:16:34.744] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:16:34.781] <TB1> INFO: run 2 of 2
[08:16:53.332] <TB1> INFO: Test took 18551ms.
[08:16:53.503] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:16:53.550] <TB1> INFO: dacScan step from 120 .. 139
[08:16:53.551] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:16:53.551] <TB1> INFO: run 1 of 2
[08:17:13.833] <TB1> INFO: Test took 20282ms.
[08:17:14.039] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:17:14.072] <TB1> INFO: run 2 of 2
[08:17:34.377] <TB1> INFO: Test took 20305ms.
[08:17:34.575] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:17:34.603] <TB1> INFO: dacScan step from 140 .. 159
[08:17:34.603] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:17:34.603] <TB1> INFO: run 1 of 2
[08:17:54.863] <TB1> INFO: Test took 20259ms.
[08:17:55.038] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:17:55.072] <TB1> INFO: run 2 of 2
[08:18:15.442] <TB1> INFO: Test took 20370ms.
[08:18:15.612] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:15.645] <TB1> INFO: dacScan step from 160 .. 175
[08:18:15.645] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:18:15.645] <TB1> INFO: run 1 of 2
[08:18:32.546] <TB1> INFO: Test took 16901ms.
[08:18:32.679] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:32.705] <TB1> INFO: run 2 of 2
[08:18:49.525] <TB1> INFO: Test took 16820ms.
[08:18:49.662] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:13.466] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 158 (20/5) hits flags = 16 (plus default)
[08:19:13.474] <TB1> INFO: dacScan step from 0 .. 19
[08:19:13.474] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:19:13.474] <TB1> INFO: run 1 of 2
[08:19:28.432] <TB1> INFO: Test took 14958ms.
[08:19:28.458] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:28.458] <TB1> INFO: run 2 of 2
[08:19:43.597] <TB1> INFO: Test took 15139ms.
[08:19:43.619] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:43.619] <TB1> INFO: dacScan step from 20 .. 39
[08:19:43.619] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:19:43.619] <TB1> INFO: run 1 of 2
[08:19:58.790] <TB1> INFO: Test took 15171ms.
[08:19:58.821] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:58.821] <TB1> INFO: run 2 of 2
[08:20:13.221] <TB1> INFO: Test took 14400ms.
[08:20:13.242] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:20:13.242] <TB1> INFO: dacScan step from 40 .. 59
[08:20:13.242] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:20:13.242] <TB1> INFO: run 1 of 2
[08:20:27.720] <TB1> INFO: Test took 14478ms.
[08:20:27.742] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:20:27.742] <TB1> INFO: run 2 of 2
[08:20:42.918] <TB1> INFO: Test took 15176ms.
[08:20:42.944] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:20:42.944] <TB1> INFO: dacScan step from 60 .. 79
[08:20:42.944] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:20:42.944] <TB1> INFO: run 1 of 2
[08:20:58.166] <TB1> INFO: Test took 15222ms.
[08:20:58.195] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:20:58.195] <TB1> INFO: run 2 of 2
[08:21:13.260] <TB1> INFO: Test took 15064ms.
[08:21:13.293] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:21:13.294] <TB1> INFO: dacScan step from 80 .. 99
[08:21:13.294] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:21:13.294] <TB1> INFO: run 1 of 2
[08:21:30.295] <TB1> INFO: Test took 17001ms.
[08:21:30.401] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:21:30.415] <TB1> INFO: run 2 of 2
[08:21:46.965] <TB1> INFO: Test took 16550ms.
[08:21:47.059] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:21:47.072] <TB1> INFO: dacScan step from 100 .. 119
[08:21:47.072] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:21:47.072] <TB1> INFO: run 1 of 2
[08:22:05.616] <TB1> INFO: Test took 18544ms.
[08:22:05.774] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:05.804] <TB1> INFO: run 2 of 2
[08:22:25.816] <TB1> INFO: Test took 20012ms.
[08:22:25.986] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:26.017] <TB1> INFO: dacScan step from 120 .. 139
[08:22:26.017] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:22:26.017] <TB1> INFO: run 1 of 2
[08:22:46.180] <TB1> INFO: Test took 20163ms.
[08:22:46.339] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:46.368] <TB1> INFO: run 2 of 2
[08:23:05.104] <TB1> INFO: Test took 18736ms.
[08:23:05.277] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:23:05.311] <TB1> INFO: dacScan step from 140 .. 158
[08:23:05.311] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:23:05.311] <TB1> INFO: run 1 of 2
[08:23:24.636] <TB1> INFO: Test took 19325ms.
[08:23:24.788] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:23:24.815] <TB1> INFO: run 2 of 2
[08:23:44.211] <TB1> INFO: Test took 19396ms.
[08:23:44.369] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:24:06.537] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 158 (20/5) hits flags = 16 (plus default)
[08:24:06.546] <TB1> INFO: dacScan step from 0 .. 19
[08:24:06.546] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:24:06.546] <TB1> INFO: run 1 of 2
[08:24:21.128] <TB1> INFO: Test took 14582ms.
[08:24:21.157] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:24:21.157] <TB1> INFO: run 2 of 2
[08:24:35.564] <TB1> INFO: Test took 14407ms.
[08:24:35.589] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:24:35.589] <TB1> INFO: dacScan step from 20 .. 39
[08:24:35.589] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:24:35.589] <TB1> INFO: run 1 of 2
[08:24:50.144] <TB1> INFO: Test took 14555ms.
[08:24:50.167] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:24:50.167] <TB1> INFO: run 2 of 2
[08:25:04.688] <TB1> INFO: Test took 14520ms.
[08:25:04.717] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:04.717] <TB1> INFO: dacScan step from 40 .. 59
[08:25:04.717] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:25:04.717] <TB1> INFO: run 1 of 2
[08:25:19.365] <TB1> INFO: Test took 14648ms.
[08:25:19.387] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:19.387] <TB1> INFO: run 2 of 2
[08:25:33.765] <TB1> INFO: Test took 14378ms.
[08:25:33.787] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:33.787] <TB1> INFO: dacScan step from 60 .. 79
[08:25:33.787] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:25:33.787] <TB1> INFO: run 1 of 2
[08:25:48.962] <TB1> INFO: Test took 15175ms.
[08:25:48.994] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:48.994] <TB1> INFO: run 2 of 2
[08:26:04.246] <TB1> INFO: Test took 15252ms.
[08:26:04.277] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:04.278] <TB1> INFO: dacScan step from 80 .. 99
[08:26:04.278] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:26:04.278] <TB1> INFO: run 1 of 2
[08:26:21.286] <TB1> INFO: Test took 17008ms.
[08:26:21.383] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:21.397] <TB1> INFO: run 2 of 2
[08:26:37.667] <TB1> INFO: Test took 16270ms.
[08:26:37.762] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:37.775] <TB1> INFO: dacScan step from 100 .. 119
[08:26:37.775] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:26:37.775] <TB1> INFO: run 1 of 2
[08:26:56.375] <TB1> INFO: Test took 18600ms.
[08:26:56.541] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:56.570] <TB1> INFO: run 2 of 2
[08:27:16.444] <TB1> INFO: Test took 19874ms.
[08:27:16.618] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:27:16.648] <TB1> INFO: dacScan step from 120 .. 139
[08:27:16.648] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:27:16.648] <TB1> INFO: run 1 of 2
[08:27:36.828] <TB1> INFO: Test took 20179ms.
[08:27:36.986] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:27:37.013] <TB1> INFO: run 2 of 2
[08:27:57.268] <TB1> INFO: Test took 20254ms.
[08:27:57.469] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:27:57.506] <TB1> INFO: dacScan step from 140 .. 158
[08:27:57.506] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:27:57.506] <TB1> INFO: run 1 of 2
[08:28:16.897] <TB1> INFO: Test took 19391ms.
[08:28:17.087] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:28:17.128] <TB1> INFO: run 2 of 2
[08:28:35.224] <TB1> INFO: Test took 18096ms.
[08:28:35.376] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:28:57.949] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 148 (20/5) hits flags = 16 (plus default)
[08:28:57.958] <TB1> INFO: dacScan step from 0 .. 19
[08:28:57.958] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:28:57.958] <TB1> INFO: run 1 of 2
[08:29:13.097] <TB1> INFO: Test took 15139ms.
[08:29:13.122] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:29:13.122] <TB1> INFO: run 2 of 2
[08:29:28.254] <TB1> INFO: Test took 15132ms.
[08:29:28.277] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:29:28.277] <TB1> INFO: dacScan step from 20 .. 39
[08:29:28.277] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:29:28.277] <TB1> INFO: run 1 of 2
[08:29:43.303] <TB1> INFO: Test took 15026ms.
[08:29:43.333] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:29:43.333] <TB1> INFO: run 2 of 2
[08:29:58.431] <TB1> INFO: Test took 15098ms.
[08:29:58.456] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:29:58.456] <TB1> INFO: dacScan step from 40 .. 59
[08:29:58.456] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:29:58.456] <TB1> INFO: run 1 of 2
[08:30:13.589] <TB1> INFO: Test took 15133ms.
[08:30:13.615] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:30:13.615] <TB1> INFO: run 2 of 2
[08:30:27.989] <TB1> INFO: Test took 14374ms.
[08:30:28.011] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:30:28.011] <TB1> INFO: dacScan step from 60 .. 79
[08:30:28.011] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:30:28.011] <TB1> INFO: run 1 of 2
[08:30:42.463] <TB1> INFO: Test took 14452ms.
[08:30:42.494] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:30:42.494] <TB1> INFO: run 2 of 2
[08:30:57.719] <TB1> INFO: Test took 15225ms.
[08:30:57.746] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:30:57.746] <TB1> INFO: dacScan step from 80 .. 99
[08:30:57.746] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:30:57.746] <TB1> INFO: run 1 of 2
[08:31:14.658] <TB1> INFO: Test took 16912ms.
[08:31:14.754] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:31:14.767] <TB1> INFO: run 2 of 2
[08:31:31.167] <TB1> INFO: Test took 16400ms.
[08:31:31.278] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:31:31.291] <TB1> INFO: dacScan step from 100 .. 119
[08:31:31.291] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:31:31.291] <TB1> INFO: run 1 of 2
[08:31:49.833] <TB1> INFO: Test took 18542ms.
[08:31:49.997] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:31:50.028] <TB1> INFO: run 2 of 2
[08:32:09.966] <TB1> INFO: Test took 19938ms.
[08:32:10.132] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:32:10.166] <TB1> INFO: dacScan step from 120 .. 139
[08:32:10.166] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:32:10.166] <TB1> INFO: run 1 of 2
[08:32:30.390] <TB1> INFO: Test took 20224ms.
[08:32:30.556] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:32:30.586] <TB1> INFO: run 2 of 2
[08:32:50.853] <TB1> INFO: Test took 20267ms.
[08:32:51.041] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:32:51.078] <TB1> INFO: dacScan step from 140 .. 148
[08:32:51.078] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[08:32:51.078] <TB1> INFO: run 1 of 2
[08:33:01.770] <TB1> INFO: Test took 10692ms.
[08:33:01.843] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:01.858] <TB1> INFO: run 2 of 2
[08:33:12.546] <TB1> INFO: Test took 10688ms.
[08:33:12.636] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:33.511] <TB1> INFO: PixTestTrim::trimBitTest() done
[08:33:33.513] <TB1> INFO: PixTestTrim::doTest() done, duration: 2897 seconds
[08:33:34.283] <TB1> INFO: ######################################################################
[08:33:34.283] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[08:33:34.283] <TB1> INFO: ######################################################################
[08:33:37.743] <TB1> INFO: Test took 3458ms.
[08:33:37.770] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:41.420] <TB1> INFO: Test took 3451ms.
[08:33:41.495] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:44.948] <TB1> INFO: Test took 3438ms.
[08:33:45.014] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:48.350] <TB1> INFO: Test took 3323ms.
[08:33:48.414] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:51.740] <TB1> INFO: Test took 3313ms.
[08:33:51.801] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:55.150] <TB1> INFO: Test took 3336ms.
[08:33:55.216] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:55.224] <TB1> INFO: The DUT currently contains the following objects:
[08:33:55.224] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:33:55.224] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:33:55.224] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:33:55.224] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:33:55.224] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:55.224] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:55.224] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:55.224] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:55.224] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:55.224] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:55.224] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:55.224] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:55.224] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:55.224] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:55.224] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:55.224] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:55.224] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:55.224] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:55.224] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:55.224] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:56.329] <TB1> INFO: Test took 1105ms.
[08:33:56.330] <TB1> INFO: The DUT currently contains the following objects:
[08:33:56.330] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:33:56.330] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:33:56.330] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:33:56.330] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:33:56.330] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:56.330] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:56.330] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:56.330] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:56.330] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:56.330] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:56.330] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:56.330] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:56.330] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:56.330] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:56.330] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:56.330] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:56.330] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:56.330] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:56.330] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:56.330] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:57.436] <TB1> INFO: Test took 1106ms.
[08:33:57.438] <TB1> INFO: The DUT currently contains the following objects:
[08:33:57.438] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:33:57.438] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:33:57.438] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:33:57.438] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:33:57.438] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:57.438] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:57.438] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:57.438] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:57.438] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:57.438] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:57.438] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:57.438] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:57.438] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:57.438] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:57.438] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:57.438] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:57.439] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:57.439] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:57.439] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:57.439] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:58.544] <TB1> INFO: Test took 1105ms.
[08:33:58.545] <TB1> INFO: The DUT currently contains the following objects:
[08:33:58.545] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:33:58.545] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:33:58.545] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:33:58.545] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:33:58.545] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:58.545] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:58.545] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:58.545] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:58.545] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:58.545] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:58.545] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:58.545] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:58.545] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:58.545] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:58.545] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:58.545] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:58.545] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:58.545] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:58.545] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:58.545] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:59.651] <TB1> INFO: Test took 1106ms.
[08:33:59.652] <TB1> INFO: The DUT currently contains the following objects:
[08:33:59.652] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:33:59.652] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:33:59.652] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:33:59.652] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:33:59.652] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:59.652] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:59.652] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:59.652] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:59.652] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:59.652] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:59.652] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:59.652] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:59.652] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:59.652] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:59.652] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:59.652] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:59.652] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:59.652] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:59.652] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:33:59.652] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:00.759] <TB1> INFO: Test took 1107ms.
[08:34:00.760] <TB1> INFO: The DUT currently contains the following objects:
[08:34:00.760] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:34:00.760] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:34:00.760] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:34:00.760] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:34:00.760] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:00.760] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:00.760] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:00.760] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:00.760] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:00.760] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:00.760] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:00.760] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:00.760] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:00.760] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:00.760] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:00.760] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:00.760] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:00.760] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:00.760] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:00.760] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:01.866] <TB1> INFO: Test took 1106ms.
[08:34:01.867] <TB1> INFO: The DUT currently contains the following objects:
[08:34:01.867] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:34:01.867] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:34:01.867] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:34:01.867] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:34:01.867] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:01.867] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:01.867] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:01.867] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:01.867] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:01.867] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:01.868] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:01.868] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:01.868] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:01.868] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:01.868] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:01.868] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:01.868] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:01.868] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:01.868] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:01.868] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:02.974] <TB1> INFO: Test took 1106ms.
[08:34:02.975] <TB1> INFO: The DUT currently contains the following objects:
[08:34:02.975] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:34:02.975] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:34:02.975] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:34:02.975] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:34:02.975] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:02.975] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:02.975] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:02.975] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:02.975] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:02.975] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:02.975] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:02.975] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:02.975] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:02.975] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:02.975] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:02.975] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:02.975] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:02.975] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:02.975] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:02.975] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:04.079] <TB1> INFO: Test took 1104ms.
[08:34:04.079] <TB1> INFO: The DUT currently contains the following objects:
[08:34:04.079] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:34:04.079] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:34:04.079] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:34:04.079] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:34:04.079] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:04.079] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:04.079] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:04.079] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:04.079] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:04.079] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:04.079] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:04.079] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:04.079] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:04.079] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:04.079] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:04.079] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:04.079] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:04.080] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:04.080] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:04.080] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:05.185] <TB1> INFO: Test took 1105ms.
[08:34:05.186] <TB1> INFO: The DUT currently contains the following objects:
[08:34:05.186] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:34:05.186] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:34:05.186] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:34:05.186] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:34:05.186] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:05.186] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:05.186] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:05.186] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:05.186] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:05.186] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:05.186] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:05.186] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:05.186] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:05.186] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:05.186] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:05.186] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:05.186] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:05.186] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:05.186] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:05.186] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:06.291] <TB1> INFO: Test took 1105ms.
[08:34:06.292] <TB1> INFO: The DUT currently contains the following objects:
[08:34:06.292] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:34:06.292] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:34:06.292] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:34:06.292] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:34:06.292] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:06.292] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:06.292] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:06.292] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:06.292] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:06.292] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:06.292] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:06.292] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:06.292] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:06.292] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:06.292] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:06.293] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:06.293] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:06.293] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:06.293] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:06.293] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:07.396] <TB1> INFO: Test took 1103ms.
[08:34:07.397] <TB1> INFO: The DUT currently contains the following objects:
[08:34:07.397] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:34:07.397] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:34:07.397] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:34:07.397] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:34:07.397] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:07.397] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:07.397] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:07.397] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:07.397] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:07.397] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:07.397] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:07.397] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:07.397] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:07.397] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:07.397] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:07.397] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:07.398] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:07.398] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:07.398] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:07.398] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:08.502] <TB1> INFO: Test took 1104ms.
[08:34:08.503] <TB1> INFO: The DUT currently contains the following objects:
[08:34:08.503] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:34:08.503] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:34:08.503] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:34:08.503] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:34:08.503] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:08.503] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:08.503] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:08.503] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:08.503] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:08.503] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:08.503] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:08.503] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:08.503] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:08.503] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:08.503] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:08.503] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:08.503] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:08.503] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:08.503] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:08.504] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:09.616] <TB1> INFO: Test took 1112ms.
[08:34:09.617] <TB1> INFO: The DUT currently contains the following objects:
[08:34:09.617] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:34:09.617] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:34:09.617] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:34:09.617] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:34:09.617] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:09.617] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:09.617] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:09.617] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:09.617] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:09.617] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:09.617] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:09.617] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:09.617] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:09.617] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:09.617] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:09.617] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:09.617] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:09.617] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:09.617] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:09.617] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:10.728] <TB1> INFO: Test took 1111ms.
[08:34:10.728] <TB1> INFO: The DUT currently contains the following objects:
[08:34:10.728] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:34:10.728] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:34:10.728] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:34:10.728] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:34:10.728] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:10.728] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:10.728] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:10.728] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:10.728] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:10.728] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:10.728] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:10.729] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:10.729] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:10.729] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:10.729] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:10.729] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:10.729] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:10.729] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:10.729] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:10.729] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:11.840] <TB1> INFO: Test took 1111ms.
[08:34:11.841] <TB1> INFO: The DUT currently contains the following objects:
[08:34:11.841] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:34:11.841] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:34:11.841] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:34:11.841] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:34:11.841] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:11.841] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:11.841] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:11.841] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:11.841] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:11.841] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:11.842] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:11.842] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:11.842] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:11.842] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:11.842] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:11.842] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:11.842] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:11.842] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:11.842] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:11.842] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:34:12.952] <TB1> INFO: Test took 1110ms.
[08:34:12.958] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:37:56.479] <TB1> INFO: Test took 223521ms.
[08:37:58.286] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:41:44.375] <TB1> INFO: Test took 226090ms.
[08:41:46.139] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:41:46.146] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:41:46.153] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:41:46.160] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:41:46.168] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:41:46.175] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:41:46.183] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:41:46.191] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:41:46.198] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:41:46.206] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:41:46.213] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[08:41:46.221] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[08:41:46.228] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:41:46.235] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:41:46.242] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:41:46.250] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:41:46.257] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:41:46.263] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:41:46.320] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:41:46.320] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:41:46.320] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:41:46.320] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:41:46.321] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:41:46.321] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:41:46.321] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:41:46.321] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:41:46.322] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:41:46.322] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:41:46.322] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:41:46.322] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:41:46.323] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:41:46.323] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:41:46.323] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:41:46.324] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:41:50.188] <TB1> INFO: Test took 3484ms.
[08:41:53.937] <TB1> INFO: Test took 3455ms.
[08:41:57.713] <TB1> INFO: Test took 3486ms.
[08:41:58.013] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:41:58.935] <TB1> INFO: Test took 923ms.
[08:41:58.940] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:00.050] <TB1> INFO: Test took 1110ms.
[08:42:00.054] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:01.164] <TB1> INFO: Test took 1110ms.
[08:42:01.167] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:02.279] <TB1> INFO: Test took 1112ms.
[08:42:02.284] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:03.396] <TB1> INFO: Test took 1112ms.
[08:42:03.401] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:04.512] <TB1> INFO: Test took 1111ms.
[08:42:04.517] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:05.627] <TB1> INFO: Test took 1110ms.
[08:42:05.630] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:06.742] <TB1> INFO: Test took 1112ms.
[08:42:06.746] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:07.857] <TB1> INFO: Test took 1111ms.
[08:42:07.862] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:08.973] <TB1> INFO: Test took 1111ms.
[08:42:08.977] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:10.088] <TB1> INFO: Test took 1111ms.
[08:42:10.092] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:11.201] <TB1> INFO: Test took 1110ms.
[08:42:11.205] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:12.316] <TB1> INFO: Test took 1111ms.
[08:42:12.320] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:13.432] <TB1> INFO: Test took 1112ms.
[08:42:13.436] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:14.546] <TB1> INFO: Test took 1110ms.
[08:42:14.551] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:15.662] <TB1> INFO: Test took 1112ms.
[08:42:15.667] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:16.778] <TB1> INFO: Test took 1112ms.
[08:42:16.783] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:17.894] <TB1> INFO: Test took 1111ms.
[08:42:17.898] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:19.010] <TB1> INFO: Test took 1112ms.
[08:42:19.015] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:20.125] <TB1> INFO: Test took 1110ms.
[08:42:20.130] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:21.241] <TB1> INFO: Test took 1111ms.
[08:42:21.245] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:22.356] <TB1> INFO: Test took 1111ms.
[08:42:22.361] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:23.468] <TB1> INFO: Test took 1107ms.
[08:42:23.471] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:24.584] <TB1> INFO: Test took 1113ms.
[08:42:24.589] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:25.700] <TB1> INFO: Test took 1111ms.
[08:42:25.704] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:26.814] <TB1> INFO: Test took 1110ms.
[08:42:26.819] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:27.929] <TB1> INFO: Test took 1110ms.
[08:42:27.934] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:29.045] <TB1> INFO: Test took 1112ms.
[08:42:29.050] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:30.161] <TB1> INFO: Test took 1111ms.
[08:42:30.166] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:31.277] <TB1> INFO: Test took 1111ms.
[08:42:31.282] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:32.392] <TB1> INFO: Test took 1110ms.
[08:42:32.397] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:42:33.508] <TB1> INFO: Test took 1111ms.
[08:42:34.100] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 539 seconds
[08:42:34.100] <TB1> INFO: PH scale (per ROC): 80 81 86 83 79 80 94 87 81 80 78 82 80 97 87 79
[08:42:34.100] <TB1> INFO: PH offset (per ROC): 145 149 143 142 146 153 139 151 154 140 158 149 165 160 146 159
[08:42:34.303] <TB1> INFO: ######################################################################
[08:42:34.303] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[08:42:34.303] <TB1> INFO: ######################################################################
[08:42:34.314] <TB1> INFO: scanning low vcal = 10
[08:42:38.171] <TB1> INFO: Test took 3857ms.
[08:42:38.178] <TB1> INFO: scanning low vcal = 20
[08:42:42.051] <TB1> INFO: Test took 3873ms.
[08:42:42.058] <TB1> INFO: scanning low vcal = 30
[08:42:45.955] <TB1> INFO: Test took 3897ms.
[08:42:45.967] <TB1> INFO: scanning low vcal = 40
[08:42:50.304] <TB1> INFO: Test took 4337ms.
[08:42:50.374] <TB1> INFO: scanning low vcal = 50
[08:42:54.751] <TB1> INFO: Test took 4377ms.
[08:42:54.825] <TB1> INFO: scanning low vcal = 60
[08:42:59.200] <TB1> INFO: Test took 4375ms.
[08:42:59.269] <TB1> INFO: scanning low vcal = 70
[08:43:03.640] <TB1> INFO: Test took 4371ms.
[08:43:03.709] <TB1> INFO: scanning low vcal = 80
[08:43:08.071] <TB1> INFO: Test took 4362ms.
[08:43:08.143] <TB1> INFO: scanning low vcal = 90
[08:43:12.512] <TB1> INFO: Test took 4369ms.
[08:43:12.584] <TB1> INFO: scanning low vcal = 100
[08:43:16.936] <TB1> INFO: Test took 4352ms.
[08:43:16.999] <TB1> INFO: scanning low vcal = 110
[08:43:21.373] <TB1> INFO: Test took 4374ms.
[08:43:21.441] <TB1> INFO: scanning low vcal = 120
[08:43:25.823] <TB1> INFO: Test took 4382ms.
[08:43:25.895] <TB1> INFO: scanning low vcal = 130
[08:43:30.237] <TB1> INFO: Test took 4343ms.
[08:43:30.303] <TB1> INFO: scanning low vcal = 140
[08:43:34.664] <TB1> INFO: Test took 4361ms.
[08:43:34.730] <TB1> INFO: scanning low vcal = 150
[08:43:39.116] <TB1> INFO: Test took 4386ms.
[08:43:39.182] <TB1> INFO: scanning low vcal = 160
[08:43:43.554] <TB1> INFO: Test took 4372ms.
[08:43:43.621] <TB1> INFO: scanning low vcal = 170
[08:43:47.991] <TB1> INFO: Test took 4370ms.
[08:43:48.065] <TB1> INFO: scanning low vcal = 180
[08:43:52.474] <TB1> INFO: Test took 4409ms.
[08:43:52.546] <TB1> INFO: scanning low vcal = 190
[08:43:57.039] <TB1> INFO: Test took 4493ms.
[08:43:57.102] <TB1> INFO: scanning low vcal = 200
[08:44:01.454] <TB1> INFO: Test took 4352ms.
[08:44:01.518] <TB1> INFO: scanning low vcal = 210
[08:44:05.893] <TB1> INFO: Test took 4375ms.
[08:44:05.962] <TB1> INFO: scanning low vcal = 220
[08:44:10.369] <TB1> INFO: Test took 4407ms.
[08:44:10.431] <TB1> INFO: scanning low vcal = 230
[08:44:14.801] <TB1> INFO: Test took 4370ms.
[08:44:14.872] <TB1> INFO: scanning low vcal = 240
[08:44:19.229] <TB1> INFO: Test took 4357ms.
[08:44:19.303] <TB1> INFO: scanning low vcal = 250
[08:44:23.693] <TB1> INFO: Test took 4390ms.
[08:44:23.760] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[08:44:28.126] <TB1> INFO: Test took 4366ms.
[08:44:28.195] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[08:44:32.577] <TB1> INFO: Test took 4382ms.
[08:44:32.642] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[08:44:36.921] <TB1> INFO: Test took 4279ms.
[08:44:36.985] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[08:44:41.323] <TB1> INFO: Test took 4339ms.
[08:44:41.398] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[08:44:45.725] <TB1> INFO: Test took 4327ms.
[08:44:46.268] <TB1> INFO: PixTestGainPedestal::measure() done
[08:45:20.709] <TB1> INFO: PixTestGainPedestal::fit() done
[08:45:20.709] <TB1> INFO: non-linearity mean: 0.955 0.946 0.953 0.958 0.953 0.961 0.950 0.954 0.955 0.953 0.947 0.950 0.953 0.954 0.955 0.955
[08:45:20.709] <TB1> INFO: non-linearity RMS: 0.006 0.006 0.006 0.006 0.006 0.006 0.007 0.006 0.005 0.005 0.005 0.006 0.007 0.006 0.006 0.007
[08:45:20.709] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[08:45:20.731] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[08:45:20.752] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[08:45:20.774] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[08:45:20.796] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[08:45:20.817] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[08:45:20.846] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[08:45:20.866] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[08:45:20.887] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[08:45:20.907] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[08:45:20.926] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[08:45:20.944] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[08:45:20.963] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[08:45:20.981] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[08:45:21.000] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[08:45:21.020] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[08:45:21.039] <TB1> INFO: PixTestGainPedestal::doTest() done, duration: 166 seconds
[08:45:21.045] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C15.dat
[08:45:21.066] <TB1> INFO: PixTestReadback::doTest() start.
[08:45:21.067] <TB1> INFO: PixTestReadback::RES sent once
[08:45:38.967] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C0.dat
[08:45:38.968] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C1.dat
[08:45:38.968] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C2.dat
[08:45:38.968] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C3.dat
[08:45:38.968] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C4.dat
[08:45:38.968] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C5.dat
[08:45:38.968] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C6.dat
[08:45:38.968] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C7.dat
[08:45:38.968] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C8.dat
[08:45:38.968] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C9.dat
[08:45:38.969] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C10.dat
[08:45:38.969] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C11.dat
[08:45:38.969] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C12.dat
[08:45:38.969] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C13.dat
[08:45:38.969] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C14.dat
[08:45:38.969] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C15.dat
[08:45:39.015] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[08:45:39.018] <TB1> INFO: PixTestReadback::RES sent once
[08:45:55.563] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C0.dat
[08:45:55.563] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C1.dat
[08:45:55.563] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C2.dat
[08:45:55.563] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C3.dat
[08:45:55.564] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C4.dat
[08:45:55.564] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C5.dat
[08:45:55.564] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C6.dat
[08:45:55.564] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C7.dat
[08:45:55.565] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C8.dat
[08:45:55.565] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C9.dat
[08:45:55.565] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C10.dat
[08:45:55.565] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C11.dat
[08:45:55.565] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C12.dat
[08:45:55.565] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C13.dat
[08:45:55.565] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C14.dat
[08:45:55.565] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C15.dat
[08:45:55.612] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[08:45:55.612] <TB1> INFO: PixTestReadback::RES sent once
[08:46:08.403] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[08:46:08.403] <TB1> INFO: Vbg will be calibrated using Vd calibration
[08:46:08.403] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 145.5calibrated Vbg = 1.17444 :::*/*/*/*/
[08:46:08.403] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 149.1calibrated Vbg = 1.17857 :::*/*/*/*/
[08:46:08.403] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 149.7calibrated Vbg = 1.18021 :::*/*/*/*/
[08:46:08.404] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 255calibrated Vbg = 1.1725 :::*/*/*/*/
[08:46:08.404] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 153.2calibrated Vbg = 1.1822 :::*/*/*/*/
[08:46:08.404] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 158.2calibrated Vbg = 1.18897 :::*/*/*/*/
[08:46:08.404] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 144.8calibrated Vbg = 1.18853 :::*/*/*/*/
[08:46:08.404] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 255calibrated Vbg = 1.1675 :::*/*/*/*/
[08:46:08.404] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 153.4calibrated Vbg = 1.18515 :::*/*/*/*/
[08:46:08.404] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 151.6calibrated Vbg = 1.18914 :::*/*/*/*/
[08:46:08.404] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 162.3calibrated Vbg = 1.18743 :::*/*/*/*/
[08:46:08.404] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 255calibrated Vbg = 1.1675 :::*/*/*/*/
[08:46:08.404] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 157.8calibrated Vbg = 1.17753 :::*/*/*/*/
[08:46:08.404] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 166.3calibrated Vbg = 1.17137 :::*/*/*/*/
[08:46:08.404] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 156.4calibrated Vbg = 1.17533 :::*/*/*/*/
[08:46:08.404] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 255calibrated Vbg = 1.1675 :::*/*/*/*/
[08:46:08.407] <TB1> INFO: PixTestReadback::RES sent once
[08:50:04.042] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C0.dat
[08:50:04.042] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C1.dat
[08:50:04.042] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C2.dat
[08:50:04.042] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C3.dat
[08:50:04.042] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C4.dat
[08:50:04.042] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C5.dat
[08:50:04.042] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C6.dat
[08:50:04.042] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C7.dat
[08:50:04.042] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C8.dat
[08:50:04.043] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C9.dat
[08:50:04.043] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C10.dat
[08:50:04.043] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C11.dat
[08:50:04.043] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C12.dat
[08:50:04.043] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C13.dat
[08:50:04.043] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C14.dat
[08:50:04.043] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C15.dat
[08:50:04.089] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[08:50:04.091] <TB1> INFO: PixTestReadback::doTest() done
[08:50:04.142] <TB1> INFO: enter test to run
[08:50:04.142] <TB1> INFO: test: exit no parameter change
[08:50:04.753] <TB1> QUIET: Connection to board 153 closed.
[08:50:04.833] <TB1> INFO: pXar: this is the end, my friend