[10:21:51.048] <TB1> INFO: *** Welcome to pxar ***
[10:21:51.048] <TB1> INFO: *** Today: 2015/08/24
[10:21:51.048] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C15.dat
[10:21:51.049] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//tbmParameters_C0b.dat
[10:21:51.049] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//defaultMaskFile.dat
[10:21:51.049] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters_C15.dat
[10:21:51.121] <TB1> INFO: clk: 4
[10:21:51.121] <TB1> INFO: ctr: 4
[10:21:51.121] <TB1> INFO: sda: 19
[10:21:51.121] <TB1> INFO: tin: 9
[10:21:51.121] <TB1> INFO: level: 15
[10:21:51.121] <TB1> INFO: triggerdelay: 0
[10:21:51.121] <TB1> QUIET: Instanciating API for pxar prod-01+86~g1838649
[10:21:51.121] <TB1> INFO: Log level: INFO
[10:21:51.128] <TB1> INFO: Found DTB DTB_WXBYFL
[10:21:51.142] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[10:21:51.145] <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:
------------------------------------------------------
[10:21:51.148] <TB1> INFO: RPC call hashes of host and DTB match: 447413373
[10:21:52.683] <TB1> INFO: DUT info:
[10:21:52.683] <TB1> INFO: The DUT currently contains the following objects:
[10:21:52.683] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:21:52.683] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:21:52.683] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:21:52.683] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:21:52.683] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:52.683] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:52.683] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:52.683] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:52.683] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:52.683] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:52.683] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:52.683] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:52.683] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:52.683] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:52.683] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:52.683] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:52.683] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:52.683] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:52.683] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:52.683] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:53.084] <TB1> INFO: enter 'restricted' command line mode
[10:21:53.085] <TB1> INFO: enter test to run
[10:21:53.085] <TB1> INFO: test: pretest no parameter change
[10:21:53.085] <TB1> INFO: running: pretest
[10:21:53.091] <TB1> INFO: ######################################################################
[10:21:53.091] <TB1> INFO: PixTestPretest::doTest()
[10:21:53.091] <TB1> INFO: ######################################################################
[10:21:53.093] <TB1> INFO: ----------------------------------------------------------------------
[10:21:53.093] <TB1> INFO: PixTestPretest::programROC()
[10:21:53.093] <TB1> INFO: ----------------------------------------------------------------------
[10:22:11.115] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[10:22:11.115] <TB1> INFO: IA differences per ROC: 18.5 20.1 17.7 18.5 22.5 20.1 20.1 16.9 16.9 17.7 16.1 19.3 18.5 20.1 20.1 21.7
[10:22:11.200] <TB1> INFO: ----------------------------------------------------------------------
[10:22:11.200] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[10:22:11.200] <TB1> INFO: ----------------------------------------------------------------------
[10:22:30.814] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 389.9 mA = 24.3688 mA/ROC
[10:22:30.817] <TB1> INFO: ----------------------------------------------------------------------
[10:22:30.817] <TB1> INFO: PixTestPretest::findWorkingPixel()
[10:22:30.817] <TB1> INFO: ----------------------------------------------------------------------
[10:22:39.200] <TB1> INFO: Test took 8377ms.
[10:22:39.514] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[10:22:39.563] <TB1> INFO: ----------------------------------------------------------------------
[10:22:39.563] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[10:22:39.563] <TB1> INFO: ----------------------------------------------------------------------
[10:22:47.893] <TB1> INFO: Test took 8324ms.
[10:22:48.216] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[10:22:48.216] <TB1> INFO: CalDel: 131 143 149 136 152 135 137 141 126 153 121 133 133 129 162 178
[10:22:48.216] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[10:22:48.221] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C0.dat
[10:22:48.221] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C1.dat
[10:22:48.221] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C2.dat
[10:22:48.222] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C3.dat
[10:22:48.222] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C4.dat
[10:22:48.222] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C5.dat
[10:22:48.223] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C6.dat
[10:22:48.223] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C7.dat
[10:22:48.223] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C8.dat
[10:22:48.223] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C9.dat
[10:22:48.223] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C10.dat
[10:22:48.224] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C11.dat
[10:22:48.224] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C12.dat
[10:22:48.224] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C13.dat
[10:22:48.225] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C14.dat
[10:22:48.225] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters_C15.dat
[10:22:48.225] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//tbmParameters_C0a.dat
[10:22:48.225] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//tbmParameters_C0b.dat
[10:22:48.225] <TB1> INFO: PixTestPretest::doTest() done, duration: 55 seconds
[10:22:48.315] <TB1> INFO: enter test to run
[10:22:48.315] <TB1> INFO: test: fulltest no parameter change
[10:22:48.315] <TB1> INFO: running: fulltest
[10:22:48.315] <TB1> INFO: ######################################################################
[10:22:48.315] <TB1> INFO: PixTestFullTest::doTest()
[10:22:48.315] <TB1> INFO: ######################################################################
[10:22:48.317] <TB1> INFO: ######################################################################
[10:22:48.317] <TB1> INFO: PixTestAlive::doTest()
[10:22:48.317] <TB1> INFO: ######################################################################
[10:22:48.318] <TB1> INFO: ----------------------------------------------------------------------
[10:22:48.318] <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)
[10:22:48.318] <TB1> INFO: ----------------------------------------------------------------------
[10:22:51.781] <TB1> INFO: Test took 3460ms.
[10:22:51.805] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:22:52.046] <TB1> INFO: PixTestAlive::aliveTest() done
[10:22:52.046] <TB1> INFO: number of dead pixels (per ROC): 1 1 0 2 0 0 0 0 0 0 0 2 0 0 0 0
[10:22:52.048] <TB1> INFO: ----------------------------------------------------------------------
[10:22:52.048] <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)
[10:22:52.048] <TB1> INFO: ----------------------------------------------------------------------
[10:22:54.828] <TB1> INFO: Test took 2777ms.
[10:22:54.832] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:22:54.833] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:22:55.073] <TB1> INFO: PixTestAlive::maskTest() done
[10:22:55.073] <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
[10:22:55.075] <TB1> INFO: ----------------------------------------------------------------------
[10:22:55.075] <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)
[10:22:55.075] <TB1> INFO: ----------------------------------------------------------------------
[10:22:58.583] <TB1> INFO: Test took 3506ms.
[10:22:58.611] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:22:58.860] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[10:22:58.860] <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
[10:22:58.860] <TB1> INFO: PixTestAlive::doTest() done, duration: 10 seconds
[10:22:58.876] <TB1> INFO: ######################################################################
[10:22:58.876] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[10:22:58.876] <TB1> INFO: ######################################################################
[10:22:58.881] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30/5) hits flags = 2 (plus default)
[10:22:58.900] <TB1> INFO: dacScan step from 0 .. 29
[10:22:58.900] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:22:58.900] <TB1> INFO: run 1 of 1
[10:23:20.495] <TB1> INFO: Test took 21595ms.
[10:23:20.528] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:20.528] <TB1> INFO: dacScan step from 30 .. 59
[10:23:20.528] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:23:20.528] <TB1> INFO: run 1 of 1
[10:23:43.538] <TB1> INFO: Test took 23010ms.
[10:23:43.643] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:43.656] <TB1> INFO: dacScan step from 60 .. 89
[10:23:43.656] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:23:43.656] <TB1> INFO: run 1 of 1
[10:24:11.747] <TB1> INFO: Test took 28091ms.
[10:24:12.006] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:24:12.093] <TB1> INFO: dacScan step from 90 .. 119
[10:24:12.093] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:24:12.093] <TB1> INFO: run 1 of 1
[10:24:39.296] <TB1> INFO: Test took 27203ms.
[10:24:39.600] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:24:39.677] <TB1> INFO: dacScan step from 120 .. 149
[10:24:39.677] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:24:39.677] <TB1> INFO: run 1 of 1
[10:25:04.128] <TB1> INFO: Test took 24451ms.
[10:25:04.395] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:25:29.936] <TB1> INFO: PixTestBBMap::doTest() done, duration: 151 seconds
[10:25:29.936] <TB1> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 1 0 0 3 0 0 0 1 2
[10:25:29.936] <TB1> INFO: separation cut (per ROC): 86 90 86 88 109 100 76 89 94 75 86 94 111 95 76 89
[10:25:30.009] <TB1> INFO: ######################################################################
[10:25:30.009] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = 4, ntrig/step = 50
[10:25:30.009] <TB1> INFO: ######################################################################
[10:25:30.009] <TB1> INFO: ----------------------------------------------------------------------
[10:25:30.009] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = 4, ntrig/step = 50
[10:25:30.009] <TB1> INFO: ----------------------------------------------------------------------
[10:25:30.010] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (4/50) hits flags = 16 (plus default)
[10:25:30.019] <TB1> INFO: dacScan step from 0 .. 3
[10:25:30.019] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:25:30.019] <TB1> INFO: run 1 of 1
[10:25:48.038] <TB1> INFO: Test took 18019ms.
[10:25:48.068] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:25:48.068] <TB1> INFO: dacScan step from 4 .. 7
[10:25:48.068] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:25:48.068] <TB1> INFO: run 1 of 1
[10:26:07.245] <TB1> INFO: Test took 19177ms.
[10:26:07.275] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:26:07.275] <TB1> INFO: dacScan step from 8 .. 11
[10:26:07.275] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:26:07.275] <TB1> INFO: run 1 of 1
[10:26:26.531] <TB1> INFO: Test took 19256ms.
[10:26:26.562] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:26:26.562] <TB1> INFO: dacScan step from 12 .. 15
[10:26:26.562] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:26:26.562] <TB1> INFO: run 1 of 1
[10:26:45.874] <TB1> INFO: Test took 19312ms.
[10:26:45.909] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:26:45.909] <TB1> INFO: dacScan step from 16 .. 19
[10:26:45.909] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:26:45.909] <TB1> INFO: run 1 of 1
[10:27:05.176] <TB1> INFO: Test took 19267ms.
[10:27:05.210] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:27:05.210] <TB1> INFO: dacScan step from 20 .. 23
[10:27:05.210] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:27:05.210] <TB1> INFO: run 1 of 1
[10:27:24.481] <TB1> INFO: Test took 19270ms.
[10:27:24.509] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:27:24.509] <TB1> INFO: dacScan step from 24 .. 27
[10:27:24.509] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:27:24.509] <TB1> INFO: run 1 of 1
[10:27:43.766] <TB1> INFO: Test took 19257ms.
[10:27:43.798] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:27:43.798] <TB1> INFO: dacScan step from 28 .. 31
[10:27:43.798] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:27:43.798] <TB1> INFO: run 1 of 1
[10:28:03.083] <TB1> INFO: Test took 19285ms.
[10:28:03.112] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:03.112] <TB1> INFO: dacScan step from 32 .. 35
[10:28:03.112] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:28:03.112] <TB1> INFO: run 1 of 1
[10:28:22.285] <TB1> INFO: Test took 19173ms.
[10:28:22.314] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:22.314] <TB1> INFO: dacScan step from 36 .. 39
[10:28:22.314] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:28:22.314] <TB1> INFO: run 1 of 1
[10:28:41.402] <TB1> INFO: Test took 19088ms.
[10:28:41.433] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:41.433] <TB1> INFO: dacScan step from 40 .. 43
[10:28:41.433] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:28:41.433] <TB1> INFO: run 1 of 1
[10:29:00.533] <TB1> INFO: Test took 19100ms.
[10:29:00.562] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:00.562] <TB1> INFO: dacScan step from 44 .. 47
[10:29:00.562] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:29:00.562] <TB1> INFO: run 1 of 1
[10:29:19.668] <TB1> INFO: Test took 19105ms.
[10:29:19.694] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:19.697] <TB1> INFO: dacScan step from 48 .. 51
[10:29:19.697] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:29:19.697] <TB1> INFO: run 1 of 1
[10:29:38.906] <TB1> INFO: Test took 19209ms.
[10:29:38.934] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:38.934] <TB1> INFO: dacScan step from 52 .. 55
[10:29:38.934] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:29:38.934] <TB1> INFO: run 1 of 1
[10:29:58.087] <TB1> INFO: Test took 19152ms.
[10:29:58.118] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:58.118] <TB1> INFO: dacScan step from 56 .. 59
[10:29:58.118] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:29:58.118] <TB1> INFO: run 1 of 1
[10:30:17.431] <TB1> INFO: Test took 19313ms.
[10:30:17.460] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:17.460] <TB1> INFO: dacScan step from 60 .. 63
[10:30:17.460] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:30:17.460] <TB1> INFO: run 1 of 1
[10:30:36.896] <TB1> INFO: Test took 19436ms.
[10:30:36.931] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:36.932] <TB1> INFO: dacScan step from 64 .. 67
[10:30:36.932] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:30:36.932] <TB1> INFO: run 1 of 1
[10:30:56.678] <TB1> INFO: Test took 19746ms.
[10:30:56.732] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:56.734] <TB1> INFO: dacScan step from 68 .. 71
[10:30:56.734] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:30:56.734] <TB1> INFO: run 1 of 1
[10:31:17.467] <TB1> INFO: Test took 20733ms.
[10:31:17.544] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:17.546] <TB1> INFO: dacScan step from 72 .. 75
[10:31:17.546] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:31:17.546] <TB1> INFO: run 1 of 1
[10:31:39.135] <TB1> INFO: Test took 21589ms.
[10:31:39.238] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:39.242] <TB1> INFO: dacScan step from 76 .. 79
[10:31:39.242] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:31:39.242] <TB1> INFO: run 1 of 1
[10:32:02.117] <TB1> INFO: Test took 22875ms.
[10:32:02.239] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:02.245] <TB1> INFO: dacScan step from 80 .. 83
[10:32:02.245] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:32:02.245] <TB1> INFO: run 1 of 1
[10:32:27.865] <TB1> INFO: Test took 25620ms.
[10:32:28.035] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:28.043] <TB1> INFO: dacScan step from 84 .. 87
[10:32:28.043] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:32:28.043] <TB1> INFO: run 1 of 1
[10:32:55.442] <TB1> INFO: Test took 27399ms.
[10:32:55.640] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:55.649] <TB1> INFO: dacScan step from 88 .. 91
[10:32:55.649] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:32:55.649] <TB1> INFO: run 1 of 1
[10:33:23.999] <TB1> INFO: Test took 28350ms.
[10:33:24.219] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:24.228] <TB1> INFO: dacScan step from 92 .. 95
[10:33:24.228] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:33:24.228] <TB1> INFO: run 1 of 1
[10:33:52.949] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[10:33:52.949] <TB1> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[10:33:53.404] <TB1> INFO: Test took 29176ms.
[10:33:53.630] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:53.640] <TB1> INFO: dacScan step from 96 .. 99
[10:33:53.640] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:33:53.640] <TB1> INFO: run 1 of 1
[10:34:23.434] <TB1> INFO: Test took 29794ms.
[10:34:23.665] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:34:23.678] <TB1> INFO: dacScan step from 100 .. 103
[10:34:23.678] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:34:23.678] <TB1> INFO: run 1 of 1
[10:34:52.382] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[10:34:52.382] <TB1> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[10:34:53.668] <TB1> INFO: Test took 29990ms.
[10:34:53.948] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:34:53.960] <TB1> INFO: dacScan step from 104 .. 107
[10:34:53.960] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:34:53.960] <TB1> INFO: run 1 of 1
[10:35:22.632] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[10:35:22.633] <TB1> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[10:35:23.943] <TB1> INFO: Test took 29983ms.
[10:35:24.227] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:35:24.238] <TB1> INFO: dacScan step from 108 .. 111
[10:35:24.238] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:35:24.238] <TB1> INFO: run 1 of 1
[10:35:52.851] <TB1> ERROR: <datapipe.cc/CheckEventID:L453> Channel 3 Event ID mismatch: local ID (9) != TBM ID (0)
[10:35:52.851] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (3) != Token Chain Length (4)
[10:35:52.851] <TB1> ERROR: <datapipe.cc/CheckEventID:L453> Channel 3 Event ID mismatch: local ID (1) != TBM ID (10)
[10:35:54.019] <TB1> INFO: Test took 29780ms.
[10:35:54.255] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:35:54.264] <TB1> INFO: dacScan step from 112 .. 115
[10:35:54.264] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:35:54.264] <TB1> INFO: run 1 of 1
[10:36:22.723] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[10:36:22.723] <TB1> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[10:36:23.920] <TB1> INFO: Test took 29656ms.
[10:36:24.156] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:24.166] <TB1> INFO: dacScan step from 116 .. 119
[10:36:24.166] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:36:24.166] <TB1> INFO: run 1 of 1
[10:36:53.804] <TB1> INFO: Test took 29638ms.
[10:36:54.088] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:54.098] <TB1> INFO: dacScan step from 120 .. 123
[10:36:54.098] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:36:54.098] <TB1> INFO: run 1 of 1
[10:37:23.736] <TB1> INFO: Test took 29638ms.
[10:37:23.997] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:24.007] <TB1> INFO: dacScan step from 124 .. 127
[10:37:24.008] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:37:24.008] <TB1> INFO: run 1 of 1
[10:37:53.629] <TB1> INFO: Test took 29621ms.
[10:37:53.856] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:53.865] <TB1> INFO: dacScan step from 128 .. 131
[10:37:53.865] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:37:53.865] <TB1> INFO: run 1 of 1
[10:38:23.422] <TB1> INFO: Test took 29556ms.
[10:38:23.678] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:23.688] <TB1> INFO: dacScan step from 132 .. 135
[10:38:23.688] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:38:23.688] <TB1> INFO: run 1 of 1
[10:38:53.340] <TB1> INFO: Test took 29652ms.
[10:38:53.626] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:53.638] <TB1> INFO: dacScan step from 136 .. 139
[10:38:53.638] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:38:53.638] <TB1> INFO: run 1 of 1
[10:39:23.216] <TB1> INFO: Test took 29578ms.
[10:39:23.464] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:39:23.475] <TB1> INFO: dacScan step from 140 .. 143
[10:39:23.475] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:39:23.475] <TB1> INFO: run 1 of 1
[10:39:53.160] <TB1> INFO: Test took 29685ms.
[10:39:53.409] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:39:53.418] <TB1> INFO: dacScan step from 144 .. 147
[10:39:53.418] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:39:53.418] <TB1> INFO: run 1 of 1
[10:40:23.126] <TB1> INFO: Test took 29708ms.
[10:40:23.417] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:23.429] <TB1> INFO: dacScan step from 148 .. 149
[10:40:23.429] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:40:23.429] <TB1> INFO: run 1 of 1
[10:40:39.105] <TB1> INFO: Test took 15676ms.
[10:40:39.292] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:39.302] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:40:40.863] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:40:42.436] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:40:43.914] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:40:45.452] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:40:46.970] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:40:48.466] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:40:49.894] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:40:51.270] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:40:52.632] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:40:54.048] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:40:55.499] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:40:56.912] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:40:58.317] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:40:59.720] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:41:01.216] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:41:02.689] <TB1> INFO: PixTestScurves::scurves() done
[10:41:02.689] <TB1> INFO: Vcal mean: 80.23 77.94 72.41 83.34 93.49 91.86 70.04 86.85 93.14 72.86 81.66 86.71 90.70 83.57 73.71 87.09
[10:41:02.689] <TB1> INFO: Vcal RMS: 4.38 4.54 5.32 5.54 5.56 5.47 4.99 5.59 5.47 4.83 5.11 6.09 5.75 4.45 4.57 5.19
[10:41:02.689] <TB1> INFO: PixTestScurves::fullTest() done, duration: 932 seconds
[10:41:02.762] <TB1> INFO: ######################################################################
[10:41:02.762] <TB1> INFO: PixTestTrim::doTest()
[10:41:02.762] <TB1> INFO: ######################################################################
[10:41:02.764] <TB1> INFO: ----------------------------------------------------------------------
[10:41:02.764] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:41:02.764] <TB1> INFO: ----------------------------------------------------------------------
[10:41:02.859] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:41:02.859] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[10:41:02.868] <TB1> INFO: dacScan step from 0 .. 19
[10:41:02.868] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:41:02.868] <TB1> INFO: run 1 of 1
[10:41:17.543] <TB1> INFO: Test took 14675ms.
[10:41:17.565] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:17.565] <TB1> INFO: dacScan step from 20 .. 39
[10:41:17.565] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:41:17.565] <TB1> INFO: run 1 of 1
[10:41:32.013] <TB1> INFO: Test took 14448ms.
[10:41:32.034] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:32.034] <TB1> INFO: dacScan step from 40 .. 59
[10:41:32.034] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:41:32.034] <TB1> INFO: run 1 of 1
[10:41:47.201] <TB1> INFO: Test took 15167ms.
[10:41:47.225] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:47.225] <TB1> INFO: dacScan step from 60 .. 79
[10:41:47.225] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:41:47.225] <TB1> INFO: run 1 of 1
[10:42:01.699] <TB1> INFO: Test took 14474ms.
[10:42:01.724] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:42:01.724] <TB1> INFO: dacScan step from 80 .. 99
[10:42:01.724] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:42:01.724] <TB1> INFO: run 1 of 1
[10:42:17.259] <TB1> INFO: Test took 15535ms.
[10:42:17.352] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:42:17.365] <TB1> INFO: dacScan step from 100 .. 119
[10:42:17.365] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:42:17.365] <TB1> INFO: run 1 of 1
[10:42:36.886] <TB1> INFO: Test took 19521ms.
[10:42:37.067] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:42:37.099] <TB1> INFO: dacScan step from 120 .. 139
[10:42:37.099] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:42:37.099] <TB1> INFO: run 1 of 1
[10:42:55.926] <TB1> INFO: Test took 18827ms.
[10:42:56.072] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:42:56.095] <TB1> INFO: dacScan step from 140 .. 159
[10:42:56.095] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:42:56.095] <TB1> INFO: run 1 of 1
[10:43:11.692] <TB1> INFO: Test took 15597ms.
[10:43:11.772] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:33.981] <TB1> INFO: ROC 0 VthrComp = 88
[10:43:33.981] <TB1> INFO: ROC 1 VthrComp = 84
[10:43:33.981] <TB1> INFO: ROC 2 VthrComp = 76
[10:43:33.981] <TB1> INFO: ROC 3 VthrComp = 87
[10:43:33.981] <TB1> INFO: ROC 4 VthrComp = 102
[10:43:33.982] <TB1> INFO: ROC 5 VthrComp = 97
[10:43:33.982] <TB1> INFO: ROC 6 VthrComp = 77
[10:43:33.982] <TB1> INFO: ROC 7 VthrComp = 89
[10:43:33.982] <TB1> INFO: ROC 8 VthrComp = 98
[10:43:33.982] <TB1> INFO: ROC 9 VthrComp = 79
[10:43:33.982] <TB1> INFO: ROC 10 VthrComp = 83
[10:43:33.982] <TB1> INFO: ROC 11 VthrComp = 90
[10:43:33.982] <TB1> INFO: ROC 12 VthrComp = 94
[10:43:33.983] <TB1> INFO: ROC 13 VthrComp = 92
[10:43:33.983] <TB1> INFO: ROC 14 VthrComp = 77
[10:43:33.983] <TB1> INFO: ROC 15 VthrComp = 92
[10:43:33.983] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:43:33.983] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[10:43:33.994] <TB1> INFO: dacScan step from 0 .. 19
[10:43:33.994] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:43:33.994] <TB1> INFO: run 1 of 1
[10:43:49.177] <TB1> INFO: Test took 15183ms.
[10:43:49.203] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:49.203] <TB1> INFO: dacScan step from 20 .. 39
[10:43:49.203] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:43:49.203] <TB1> INFO: run 1 of 1
[10:44:03.767] <TB1> INFO: Test took 14564ms.
[10:44:03.797] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:03.800] <TB1> INFO: dacScan step from 40 .. 59
[10:44:03.800] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:44:03.800] <TB1> INFO: run 1 of 1
[10:44:22.707] <TB1> INFO: Test took 18907ms.
[10:44:22.857] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:22.885] <TB1> INFO: dacScan step from 60 .. 79
[10:44:22.885] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:44:22.885] <TB1> INFO: run 1 of 1
[10:44:41.678] <TB1> INFO: Test took 18793ms.
[10:44:41.872] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:41.914] <TB1> INFO: dacScan step from 80 .. 99
[10:44:41.914] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:44:41.914] <TB1> INFO: run 1 of 1
[10:45:01.969] <TB1> INFO: Test took 20055ms.
[10:45:02.136] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:45:02.173] <TB1> INFO: dacScan step from 100 .. 119
[10:45:02.173] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:45:02.173] <TB1> INFO: run 1 of 1
[10:45:22.585] <TB1> INFO: Test took 20412ms.
[10:45:22.742] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:45:22.773] <TB1> INFO: dacScan step from 120 .. 139
[10:45:22.773] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:45:22.773] <TB1> INFO: run 1 of 1
[10:45:43.142] <TB1> INFO: Test took 20369ms.
[10:45:43.318] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:45:43.349] <TB1> INFO: dacScan step from 140 .. 159
[10:45:43.349] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:45:43.349] <TB1> INFO: run 1 of 1
[10:46:02.984] <TB1> INFO: Test took 19634ms.
[10:46:03.183] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:46:28.264] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 57.1267 for pixel 0/1 mean/min/max = 44.948/32.689/57.207
[10:46:28.264] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 57.1447 for pixel 0/29 mean/min/max = 44.7282/32.3039/57.1524
[10:46:28.265] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 61.0281 for pixel 8/79 mean/min/max = 47.5563/33.9703/61.1423
[10:46:28.265] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 59.5833 for pixel 10/0 mean/min/max = 45.8706/32.0696/59.6716
[10:46:28.265] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 58.6872 for pixel 0/8 mean/min/max = 45.2885/31.886/58.691
[10:46:28.266] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 59.966 for pixel 20/14 mean/min/max = 45.6937/31.4108/59.9766
[10:46:28.266] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 58.4012 for pixel 11/71 mean/min/max = 46.1509/33.8024/58.4994
[10:46:28.266] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 59.794 for pixel 0/66 mean/min/max = 46.2574/32.7087/59.806
[10:46:28.266] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 57.7756 for pixel 5/73 mean/min/max = 44.4534/31.0891/57.8176
[10:46:28.267] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 58.2475 for pixel 8/12 mean/min/max = 45.9655/33.6825/58.2485
[10:46:28.267] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 60.6577 for pixel 0/6 mean/min/max = 46.41/32.138/60.682
[10:46:28.267] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 61.0801 for pixel 16/77 mean/min/max = 46.7086/32.2498/61.1674
[10:46:28.268] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 59.5832 for pixel 32/51 mean/min/max = 46.0005/32.2417/59.7593
[10:46:28.268] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 56.1499 for pixel 14/79 mean/min/max = 44.2261/32.2364/56.2157
[10:46:28.268] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 57.5832 for pixel 23/11 mean/min/max = 46.2704/34.9428/57.598
[10:46:28.268] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 58.2654 for pixel 6/3 mean/min/max = 45.2788/32.1837/58.3739
[10:46:28.269] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:48:00.619] <TB1> INFO: Test took 92350ms.
[10:48:02.292] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[10:48:02.302] <TB1> INFO: dacScan step from 0 .. 19
[10:48:02.302] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[10:48:02.302] <TB1> INFO: run 1 of 2
[10:48:17.619] <TB1> INFO: Test took 15317ms.
[10:48:17.651] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:17.652] <TB1> INFO: run 2 of 2
[10:48:32.956] <TB1> INFO: Test took 15304ms.
[10:48:32.987] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:32.988] <TB1> INFO: dacScan step from 20 .. 39
[10:48:32.988] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[10:48:32.988] <TB1> INFO: run 1 of 2
[10:48:50.979] <TB1> INFO: Test took 17990ms.
[10:48:51.131] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:51.160] <TB1> INFO: run 2 of 2
[10:49:09.286] <TB1> INFO: Test took 18126ms.
[10:49:09.443] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:09.474] <TB1> INFO: dacScan step from 40 .. 59
[10:49:09.474] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[10:49:09.474] <TB1> INFO: run 1 of 2
[10:49:29.927] <TB1> INFO: Test took 20453ms.
[10:49:30.094] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:30.126] <TB1> INFO: run 2 of 2
[10:49:50.322] <TB1> INFO: Test took 20196ms.
[10:49:50.520] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:50.560] <TB1> INFO: dacScan step from 60 .. 79
[10:49:50.560] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[10:49:50.561] <TB1> INFO: run 1 of 2
[10:50:10.959] <TB1> INFO: Test took 20398ms.
[10:50:11.165] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:11.208] <TB1> INFO: run 2 of 2
[10:50:31.486] <TB1> INFO: Test took 20277ms.
[10:50:31.687] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:31.746] <TB1> INFO: dacScan step from 80 .. 99
[10:50:31.746] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[10:50:31.746] <TB1> INFO: run 1 of 2
[10:50:52.023] <TB1> INFO: Test took 20277ms.
[10:50:52.222] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:52.264] <TB1> INFO: run 2 of 2
[10:51:12.681] <TB1> INFO: Test took 20417ms.
[10:51:12.837] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:12.867] <TB1> INFO: dacScan step from 100 .. 119
[10:51:12.867] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[10:51:12.867] <TB1> INFO: run 1 of 2
[10:51:33.226] <TB1> INFO: Test took 20359ms.
[10:51:33.433] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:33.472] <TB1> INFO: run 2 of 2
[10:51:53.852] <TB1> INFO: Test took 20380ms.
[10:51:54.053] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:54.094] <TB1> INFO: dacScan step from 120 .. 139
[10:51:54.094] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[10:51:54.094] <TB1> INFO: run 1 of 2
[10:52:14.486] <TB1> INFO: Test took 20392ms.
[10:52:14.645] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:14.676] <TB1> INFO: run 2 of 2
[10:52:35.023] <TB1> INFO: Test took 20347ms.
[10:52:35.198] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:35.234] <TB1> INFO: dacScan step from 140 .. 159
[10:52:35.234] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[10:52:35.234] <TB1> INFO: run 1 of 2
[10:52:55.547] <TB1> INFO: Test took 20313ms.
[10:52:55.730] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:55.762] <TB1> INFO: run 2 of 2
[10:53:16.095] <TB1> INFO: Test took 20333ms.
[10:53:16.255] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:16.286] <TB1> INFO: dacScan step from 160 .. 179
[10:53:16.286] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[10:53:16.286] <TB1> INFO: run 1 of 2
[10:53:36.676] <TB1> INFO: Test took 20390ms.
[10:53:36.877] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:36.919] <TB1> INFO: run 2 of 2
[10:53:57.198] <TB1> INFO: Test took 20279ms.
[10:53:57.390] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:57.427] <TB1> INFO: dacScan step from 180 .. 199
[10:53:57.428] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[10:53:57.428] <TB1> INFO: run 1 of 2
[10:54:17.369] <TB1> INFO: Test took 19941ms.
[10:54:17.538] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:17.591] <TB1> INFO: run 2 of 2
[10:54:37.559] <TB1> INFO: Test took 19967ms.
[10:54:37.823] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:04.143] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.092481 .. 50.277883
[10:55:04.238] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 60 (20/-1) hits flags = 16 (plus default)
[10:55:04.247] <TB1> INFO: dacScan step from 0 .. 19
[10:55:04.247] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[10:55:04.247] <TB1> INFO: run 1 of 1
[10:55:17.332] <TB1> INFO: Test took 13085ms.
[10:55:17.353] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:17.353] <TB1> INFO: dacScan step from 20 .. 39
[10:55:17.353] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[10:55:17.353] <TB1> INFO: run 1 of 1
[10:55:31.703] <TB1> INFO: Test took 14350ms.
[10:55:31.784] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:31.800] <TB1> INFO: dacScan step from 40 .. 59
[10:55:31.800] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[10:55:31.800] <TB1> INFO: run 1 of 1
[10:55:49.607] <TB1> INFO: Test took 17807ms.
[10:55:49.779] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:49.822] <TB1> INFO: dacScan step from 60 .. 60
[10:55:49.822] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[10:55:49.822] <TB1> INFO: run 1 of 1
[10:55:53.455] <TB1> INFO: Test took 3633ms.
[10:55:53.470] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:09.685] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 15.195109 .. 45.500009
[10:56:09.765] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 5 .. 55 (20/-1) hits flags = 16 (plus default)
[10:56:09.773] <TB1> INFO: dacScan step from 5 .. 24
[10:56:09.773] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[10:56:09.773] <TB1> INFO: run 1 of 1
[10:56:22.745] <TB1> INFO: Test took 12972ms.
[10:56:22.765] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:22.765] <TB1> INFO: dacScan step from 25 .. 44
[10:56:22.765] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[10:56:22.765] <TB1> INFO: run 1 of 1
[10:56:38.615] <TB1> INFO: Test took 15850ms.
[10:56:38.732] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:38.754] <TB1> INFO: dacScan step from 45 .. 55
[10:56:38.754] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[10:56:38.754] <TB1> INFO: run 1 of 1
[10:56:49.319] <TB1> INFO: Test took 10565ms.
[10:56:49.397] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:05.135] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 17.895804 .. 40.500000
[10:57:05.222] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 7 .. 50 (20/-1) hits flags = 16 (plus default)
[10:57:05.231] <TB1> INFO: dacScan step from 7 .. 26
[10:57:05.231] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[10:57:05.231] <TB1> INFO: run 1 of 1
[10:57:18.896] <TB1> INFO: Test took 13665ms.
[10:57:18.923] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:18.923] <TB1> INFO: dacScan step from 27 .. 46
[10:57:18.923] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[10:57:18.923] <TB1> INFO: run 1 of 1
[10:57:35.186] <TB1> INFO: Test took 16263ms.
[10:57:35.327] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:35.361] <TB1> INFO: dacScan step from 47 .. 50
[10:57:35.361] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[10:57:35.361] <TB1> INFO: run 1 of 1
[10:57:41.267] <TB1> INFO: Test took 5906ms.
[10:57:41.303] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:56.094] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 17.895804 .. 40.500000
[10:57:56.173] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 7 .. 50 (20/-1) hits flags = 16 (plus default)
[10:57:56.181] <TB1> INFO: dacScan step from 7 .. 26
[10:57:56.181] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[10:57:56.181] <TB1> INFO: run 1 of 1
[10:58:09.814] <TB1> INFO: Test took 13633ms.
[10:58:09.842] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:58:09.842] <TB1> INFO: dacScan step from 27 .. 46
[10:58:09.842] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[10:58:09.842] <TB1> INFO: run 1 of 1
[10:58:26.128] <TB1> INFO: Test took 16286ms.
[10:58:26.254] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:58:26.281] <TB1> INFO: dacScan step from 47 .. 50
[10:58:26.281] <TB1> INFO: dacScan split into 1 runs with ntrig = 4
[10:58:26.282] <TB1> INFO: run 1 of 1
[10:58:32.042] <TB1> INFO: Test took 5760ms.
[10:58:32.072] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:58:47.371] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[10:58:47.371] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20/5) hits flags = 16 (plus default)
[10:58:47.379] <TB1> INFO: dacScan step from 15 .. 34
[10:58:47.379] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[10:58:47.379] <TB1> INFO: run 1 of 2
[10:59:01.962] <TB1> INFO: Test took 14583ms.
[10:59:02.000] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:59:02.006] <TB1> INFO: run 2 of 2
[10:59:17.451] <TB1> INFO: Test took 15445ms.
[10:59:17.495] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:59:17.503] <TB1> INFO: dacScan step from 35 .. 54
[10:59:17.503] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[10:59:17.503] <TB1> INFO: run 1 of 2
[10:59:37.711] <TB1> INFO: Test took 20207ms.
[10:59:37.927] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:59:37.969] <TB1> INFO: run 2 of 2
[10:59:56.608] <TB1> INFO: Test took 18639ms.
[10:59:56.852] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:59:56.889] <TB1> INFO: dacScan step from 55 .. 55
[10:59:56.889] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[10:59:56.889] <TB1> INFO: run 1 of 2
[11:00:00.573] <TB1> INFO: Test took 3684ms.
[11:00:00.585] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:00:00.591] <TB1> INFO: run 2 of 2
[11:00:04.387] <TB1> INFO: Test took 3796ms.
[11:00:04.402] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:00:19.744] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C0.dat
[11:00:19.745] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C1.dat
[11:00:19.745] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C2.dat
[11:00:19.745] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C3.dat
[11:00:19.745] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C4.dat
[11:00:19.745] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C5.dat
[11:00:19.745] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C6.dat
[11:00:19.745] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C7.dat
[11:00:19.745] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C8.dat
[11:00:19.745] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C9.dat
[11:00:19.746] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C10.dat
[11:00:19.746] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C11.dat
[11:00:19.746] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C12.dat
[11:00:19.746] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C13.dat
[11:00:19.746] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C14.dat
[11:00:19.746] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C15.dat
[11:00:19.746] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C0.dat
[11:00:19.756] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C1.dat
[11:00:19.764] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C2.dat
[11:00:19.773] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C3.dat
[11:00:19.782] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C4.dat
[11:00:19.790] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C5.dat
[11:00:19.799] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C6.dat
[11:00:19.806] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C7.dat
[11:00:19.812] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C8.dat
[11:00:19.819] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C9.dat
[11:00:19.825] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C10.dat
[11:00:19.832] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C11.dat
[11:00:19.838] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C12.dat
[11:00:19.845] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C13.dat
[11:00:19.851] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C14.dat
[11:00:19.858] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//trimParameters35_C15.dat
[11:00:19.864] <TB1> INFO: PixTestTrim::trimTest() done
[11:00:19.864] <TB1> INFO: vtrim: 110 107 117 114 106 119 106 100 104 112 110 121 112 96 90 112
[11:00:19.864] <TB1> INFO: vthrcomp: 88 84 76 87 102 97 77 89 98 79 83 90 94 92 77 92
[11:00:19.864] <TB1> INFO: vcal mean: 35.01 35.02 35.03 35.00 34.98 35.06 35.05 35.02 35.03 35.04 35.05 35.06 35.04 35.01 35.10 35.07
[11:00:19.864] <TB1> INFO: vcal RMS: 1.06 1.08 0.93 1.27 1.00 1.06 0.89 0.99 1.03 0.92 1.02 1.07 1.02 0.94 0.92 1.02
[11:00:19.864] <TB1> INFO: bits mean: 9.48 9.67 9.28 10.20 9.60 10.13 9.00 9.11 10.30 9.56 9.49 9.76 9.76 9.72 9.16 10.04
[11:00:19.864] <TB1> INFO: bits RMS: 2.58 2.66 2.37 2.36 2.70 2.47 2.62 2.77 2.50 2.41 2.66 2.46 2.52 2.64 2.41 2.33
[11:00:19.874] <TB1> INFO: ----------------------------------------------------------------------
[11:00:19.874] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[11:00:19.874] <TB1> INFO: ----------------------------------------------------------------------
[11:00:19.878] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[11:00:19.887] <TB1> INFO: dacScan step from 0 .. 19
[11:00:19.887] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:00:19.887] <TB1> INFO: run 1 of 2
[11:00:34.337] <TB1> INFO: Test took 14450ms.
[11:00:34.358] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:00:34.358] <TB1> INFO: run 2 of 2
[11:00:48.711] <TB1> INFO: Test took 14352ms.
[11:00:48.732] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:00:48.732] <TB1> INFO: dacScan step from 20 .. 39
[11:00:48.732] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:00:48.732] <TB1> INFO: run 1 of 2
[11:01:03.349] <TB1> INFO: Test took 14616ms.
[11:01:03.373] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:03.373] <TB1> INFO: run 2 of 2
[11:01:17.861] <TB1> INFO: Test took 14488ms.
[11:01:17.883] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:17.883] <TB1> INFO: dacScan step from 40 .. 59
[11:01:17.883] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:01:17.883] <TB1> INFO: run 1 of 2
[11:01:32.396] <TB1> INFO: Test took 14513ms.
[11:01:32.417] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:32.417] <TB1> INFO: run 2 of 2
[11:01:47.567] <TB1> INFO: Test took 15149ms.
[11:01:47.596] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:47.596] <TB1> INFO: dacScan step from 60 .. 79
[11:01:47.596] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:01:47.596] <TB1> INFO: run 1 of 2
[11:02:02.800] <TB1> INFO: Test took 15204ms.
[11:02:02.825] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:02.825] <TB1> INFO: run 2 of 2
[11:02:17.268] <TB1> INFO: Test took 14443ms.
[11:02:17.297] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:17.297] <TB1> INFO: dacScan step from 80 .. 99
[11:02:17.297] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:02:17.297] <TB1> INFO: run 1 of 2
[11:02:33.157] <TB1> INFO: Test took 15860ms.
[11:02:33.209] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:33.214] <TB1> INFO: run 2 of 2
[11:02:49.142] <TB1> INFO: Test took 15928ms.
[11:02:49.208] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:49.214] <TB1> INFO: dacScan step from 100 .. 119
[11:02:49.214] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:02:49.214] <TB1> INFO: run 1 of 2
[11:03:07.096] <TB1> INFO: Test took 17882ms.
[11:03:07.258] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:03:07.284] <TB1> INFO: run 2 of 2
[11:03:25.956] <TB1> INFO: Test took 18671ms.
[11:03:26.109] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:03:26.135] <TB1> INFO: dacScan step from 120 .. 139
[11:03:26.135] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:03:26.135] <TB1> INFO: run 1 of 2
[11:03:46.487] <TB1> INFO: Test took 20352ms.
[11:03:46.648] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:03:46.684] <TB1> INFO: run 2 of 2
[11:04:05.687] <TB1> INFO: Test took 19003ms.
[11:04:05.890] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:04:05.936] <TB1> INFO: dacScan step from 140 .. 159
[11:04:05.936] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:04:05.936] <TB1> INFO: run 1 of 2
[11:04:25.913] <TB1> INFO: Test took 19977ms.
[11:04:26.109] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:04:26.151] <TB1> INFO: run 2 of 2
[11:04:46.556] <TB1> INFO: Test took 20405ms.
[11:04:46.729] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:04:46.769] <TB1> INFO: dacScan step from 160 .. 179
[11:04:46.769] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:04:46.769] <TB1> INFO: run 1 of 2
[11:05:07.361] <TB1> INFO: Test took 20591ms.
[11:05:07.553] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:07.593] <TB1> INFO: run 2 of 2
[11:05:28.232] <TB1> INFO: Test took 20639ms.
[11:05:28.431] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:28.474] <TB1> INFO: dacScan step from 180 .. 199
[11:05:28.474] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:05:28.474] <TB1> INFO: run 1 of 2
[11:05:48.597] <TB1> INFO: Test took 20123ms.
[11:05:48.762] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:48.795] <TB1> INFO: run 2 of 2
[11:06:09.923] <TB1> INFO: Test took 21128ms.
[11:06:10.104] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:06:42.207] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 177 (20/5) hits flags = 16 (plus default)
[11:06:42.217] <TB1> INFO: dacScan step from 0 .. 19
[11:06:42.217] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:06:42.217] <TB1> INFO: run 1 of 2
[11:06:56.748] <TB1> INFO: Test took 14531ms.
[11:06:56.773] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:06:56.773] <TB1> INFO: run 2 of 2
[11:07:11.325] <TB1> INFO: Test took 14552ms.
[11:07:11.350] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:07:11.350] <TB1> INFO: dacScan step from 20 .. 39
[11:07:11.350] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:07:11.350] <TB1> INFO: run 1 of 2
[11:07:26.547] <TB1> INFO: Test took 15197ms.
[11:07:26.576] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:07:26.576] <TB1> INFO: run 2 of 2
[11:07:41.747] <TB1> INFO: Test took 15171ms.
[11:07:41.776] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:07:41.776] <TB1> INFO: dacScan step from 40 .. 59
[11:07:41.776] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:07:41.776] <TB1> INFO: run 1 of 2
[11:07:57.042] <TB1> INFO: Test took 15266ms.
[11:07:57.064] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:07:57.064] <TB1> INFO: run 2 of 2
[11:08:11.456] <TB1> INFO: Test took 14392ms.
[11:08:11.477] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:08:11.477] <TB1> INFO: dacScan step from 60 .. 79
[11:08:11.477] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:08:11.477] <TB1> INFO: run 1 of 2
[11:08:26.715] <TB1> INFO: Test took 15238ms.
[11:08:26.747] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:08:26.747] <TB1> INFO: run 2 of 2
[11:08:41.963] <TB1> INFO: Test took 15216ms.
[11:08:41.996] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:08:41.996] <TB1> INFO: dacScan step from 80 .. 99
[11:08:41.996] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:08:41.996] <TB1> INFO: run 1 of 2
[11:08:58.965] <TB1> INFO: Test took 16968ms.
[11:08:59.069] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:08:59.085] <TB1> INFO: run 2 of 2
[11:09:14.994] <TB1> INFO: Test took 15909ms.
[11:09:15.101] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:15.117] <TB1> INFO: dacScan step from 100 .. 119
[11:09:15.117] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:09:15.117] <TB1> INFO: run 1 of 2
[11:09:34.884] <TB1> INFO: Test took 19767ms.
[11:09:35.050] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:35.087] <TB1> INFO: run 2 of 2
[11:09:53.868] <TB1> INFO: Test took 18781ms.
[11:09:54.070] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:54.107] <TB1> INFO: dacScan step from 120 .. 139
[11:09:54.107] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:09:54.108] <TB1> INFO: run 1 of 2
[11:10:14.392] <TB1> INFO: Test took 20284ms.
[11:10:14.596] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:14.638] <TB1> INFO: run 2 of 2
[11:10:34.948] <TB1> INFO: Test took 20310ms.
[11:10:35.155] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:35.196] <TB1> INFO: dacScan step from 140 .. 159
[11:10:35.196] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:10:35.196] <TB1> INFO: run 1 of 2
[11:10:55.600] <TB1> INFO: Test took 20404ms.
[11:10:55.797] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:55.839] <TB1> INFO: run 2 of 2
[11:11:16.278] <TB1> INFO: Test took 20439ms.
[11:11:16.438] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:11:16.469] <TB1> INFO: dacScan step from 160 .. 177
[11:11:16.469] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:11:16.469] <TB1> INFO: run 1 of 2
[11:11:35.225] <TB1> INFO: Test took 18755ms.
[11:11:35.408] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:11:35.447] <TB1> INFO: run 2 of 2
[11:11:54.219] <TB1> INFO: Test took 18772ms.
[11:11:54.372] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:12:19.355] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 157 (20/5) hits flags = 16 (plus default)
[11:12:19.363] <TB1> INFO: dacScan step from 0 .. 19
[11:12:19.363] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:12:19.364] <TB1> INFO: run 1 of 2
[11:12:34.506] <TB1> INFO: Test took 15142ms.
[11:12:34.532] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:12:34.532] <TB1> INFO: run 2 of 2
[11:12:49.756] <TB1> INFO: Test took 15224ms.
[11:12:49.784] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:12:49.784] <TB1> INFO: dacScan step from 20 .. 39
[11:12:49.784] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:12:49.784] <TB1> INFO: run 1 of 2
[11:13:05.017] <TB1> INFO: Test took 15233ms.
[11:13:05.043] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:05.043] <TB1> INFO: run 2 of 2
[11:13:20.199] <TB1> INFO: Test took 15156ms.
[11:13:20.225] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:20.225] <TB1> INFO: dacScan step from 40 .. 59
[11:13:20.225] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:13:20.225] <TB1> INFO: run 1 of 2
[11:13:35.373] <TB1> INFO: Test took 15148ms.
[11:13:35.396] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:35.396] <TB1> INFO: run 2 of 2
[11:13:50.529] <TB1> INFO: Test took 15133ms.
[11:13:50.562] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:50.562] <TB1> INFO: dacScan step from 60 .. 79
[11:13:50.562] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:13:50.562] <TB1> INFO: run 1 of 2
[11:14:05.892] <TB1> INFO: Test took 15330ms.
[11:14:05.926] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:05.927] <TB1> INFO: run 2 of 2
[11:14:21.206] <TB1> INFO: Test took 15279ms.
[11:14:21.238] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:21.238] <TB1> INFO: dacScan step from 80 .. 99
[11:14:21.239] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:14:21.239] <TB1> INFO: run 1 of 2
[11:14:37.148] <TB1> INFO: Test took 15909ms.
[11:14:37.248] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:37.262] <TB1> INFO: run 2 of 2
[11:14:53.781] <TB1> INFO: Test took 16519ms.
[11:14:53.890] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:53.906] <TB1> INFO: dacScan step from 100 .. 119
[11:14:53.906] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:14:53.907] <TB1> INFO: run 1 of 2
[11:15:13.827] <TB1> INFO: Test took 19920ms.
[11:15:13.994] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:14.030] <TB1> INFO: run 2 of 2
[11:15:34.127] <TB1> INFO: Test took 20097ms.
[11:15:34.296] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:34.328] <TB1> INFO: dacScan step from 120 .. 139
[11:15:34.328] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:15:34.328] <TB1> INFO: run 1 of 2
[11:15:54.768] <TB1> INFO: Test took 20440ms.
[11:15:54.942] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:54.979] <TB1> INFO: run 2 of 2
[11:16:13.742] <TB1> INFO: Test took 18763ms.
[11:16:13.919] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:13.956] <TB1> INFO: dacScan step from 140 .. 157
[11:16:13.956] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:16:13.956] <TB1> INFO: run 1 of 2
[11:16:32.334] <TB1> INFO: Test took 18378ms.
[11:16:32.480] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:32.509] <TB1> INFO: run 2 of 2
[11:16:51.151] <TB1> INFO: Test took 18642ms.
[11:16:51.305] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:14.671] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 151 (20/5) hits flags = 16 (plus default)
[11:17:14.679] <TB1> INFO: dacScan step from 0 .. 19
[11:17:14.679] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:17:14.679] <TB1> INFO: run 1 of 2
[11:17:29.085] <TB1> INFO: Test took 14405ms.
[11:17:29.107] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:29.107] <TB1> INFO: run 2 of 2
[11:17:44.235] <TB1> INFO: Test took 15128ms.
[11:17:44.263] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:44.263] <TB1> INFO: dacScan step from 20 .. 39
[11:17:44.263] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:17:44.263] <TB1> INFO: run 1 of 2
[11:17:59.365] <TB1> INFO: Test took 15102ms.
[11:17:59.394] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:59.394] <TB1> INFO: run 2 of 2
[11:18:14.596] <TB1> INFO: Test took 15202ms.
[11:18:14.622] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:14.623] <TB1> INFO: dacScan step from 40 .. 59
[11:18:14.623] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:18:14.623] <TB1> INFO: run 1 of 2
[11:18:29.742] <TB1> INFO: Test took 15119ms.
[11:18:29.772] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:29.772] <TB1> INFO: run 2 of 2
[11:18:44.931] <TB1> INFO: Test took 15159ms.
[11:18:44.955] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:44.955] <TB1> INFO: dacScan step from 60 .. 79
[11:18:44.955] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:18:44.955] <TB1> INFO: run 1 of 2
[11:19:00.111] <TB1> INFO: Test took 15156ms.
[11:19:00.140] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:00.140] <TB1> INFO: run 2 of 2
[11:19:15.335] <TB1> INFO: Test took 15195ms.
[11:19:15.364] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:15.365] <TB1> INFO: dacScan step from 80 .. 99
[11:19:15.365] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:19:15.365] <TB1> INFO: run 1 of 2
[11:19:31.281] <TB1> INFO: Test took 15916ms.
[11:19:31.410] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:31.429] <TB1> INFO: run 2 of 2
[11:19:48.175] <TB1> INFO: Test took 16746ms.
[11:19:48.275] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:48.292] <TB1> INFO: dacScan step from 100 .. 119
[11:19:48.292] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:19:48.292] <TB1> INFO: run 1 of 2
[11:20:08.206] <TB1> INFO: Test took 19914ms.
[11:20:08.373] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:08.402] <TB1> INFO: run 2 of 2
[11:20:28.364] <TB1> INFO: Test took 19962ms.
[11:20:28.526] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:28.555] <TB1> INFO: dacScan step from 120 .. 139
[11:20:28.555] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:20:28.555] <TB1> INFO: run 1 of 2
[11:20:48.765] <TB1> INFO: Test took 20210ms.
[11:20:48.931] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:48.961] <TB1> INFO: run 2 of 2
[11:21:09.222] <TB1> INFO: Test took 20261ms.
[11:21:09.396] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:09.426] <TB1> INFO: dacScan step from 140 .. 151
[11:21:09.426] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:21:09.426] <TB1> INFO: run 1 of 2
[11:21:22.716] <TB1> INFO: Test took 13290ms.
[11:21:22.832] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:22.853] <TB1> INFO: run 2 of 2
[11:21:36.217] <TB1> INFO: Test took 13364ms.
[11:21:36.317] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:58.420] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 150 (20/5) hits flags = 16 (plus default)
[11:21:58.428] <TB1> INFO: dacScan step from 0 .. 19
[11:21:58.428] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:21:58.428] <TB1> INFO: run 1 of 2
[11:22:12.785] <TB1> INFO: Test took 14356ms.
[11:22:12.808] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:12.808] <TB1> INFO: run 2 of 2
[11:22:27.178] <TB1> INFO: Test took 14370ms.
[11:22:27.198] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:27.198] <TB1> INFO: dacScan step from 20 .. 39
[11:22:27.198] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:22:27.198] <TB1> INFO: run 1 of 2
[11:22:41.559] <TB1> INFO: Test took 14360ms.
[11:22:41.581] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:41.581] <TB1> INFO: run 2 of 2
[11:22:56.702] <TB1> INFO: Test took 15121ms.
[11:22:56.725] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:56.726] <TB1> INFO: dacScan step from 40 .. 59
[11:22:56.726] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:22:56.726] <TB1> INFO: run 1 of 2
[11:23:11.899] <TB1> INFO: Test took 15173ms.
[11:23:11.922] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:11.922] <TB1> INFO: run 2 of 2
[11:23:27.038] <TB1> INFO: Test took 15116ms.
[11:23:27.064] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:27.064] <TB1> INFO: dacScan step from 60 .. 79
[11:23:27.064] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:23:27.064] <TB1> INFO: run 1 of 2
[11:23:42.261] <TB1> INFO: Test took 15197ms.
[11:23:42.293] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:42.293] <TB1> INFO: run 2 of 2
[11:23:57.457] <TB1> INFO: Test took 15164ms.
[11:23:57.487] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:57.487] <TB1> INFO: dacScan step from 80 .. 99
[11:23:57.487] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:23:57.487] <TB1> INFO: run 1 of 2
[11:24:13.519] <TB1> INFO: Test took 16032ms.
[11:24:13.654] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:24:13.683] <TB1> INFO: run 2 of 2
[11:24:29.794] <TB1> INFO: Test took 16111ms.
[11:24:29.931] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:24:29.950] <TB1> INFO: dacScan step from 100 .. 119
[11:24:29.950] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:24:29.950] <TB1> INFO: run 1 of 2
[11:24:48.787] <TB1> INFO: Test took 18837ms.
[11:24:48.952] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:24:49.001] <TB1> INFO: run 2 of 2
[11:25:07.684] <TB1> INFO: Test took 18683ms.
[11:25:07.844] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:07.879] <TB1> INFO: dacScan step from 120 .. 139
[11:25:07.879] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:25:07.879] <TB1> INFO: run 1 of 2
[11:25:26.779] <TB1> INFO: Test took 18900ms.
[11:25:26.965] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:27.017] <TB1> INFO: run 2 of 2
[11:25:47.237] <TB1> INFO: Test took 20220ms.
[11:25:47.410] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:47.442] <TB1> INFO: dacScan step from 140 .. 150
[11:25:47.442] <TB1> INFO: dacScan split into 2 runs with ntrig = 5
[11:25:47.442] <TB1> INFO: run 1 of 2
[11:25:59.764] <TB1> INFO: Test took 12322ms.
[11:25:59.859] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:59.883] <TB1> INFO: run 2 of 2
[11:26:12.402] <TB1> INFO: Test took 12519ms.
[11:26:12.496] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:34.370] <TB1> INFO: PixTestTrim::trimBitTest() done
[11:26:34.372] <TB1> INFO: PixTestTrim::doTest() done, duration: 2731 seconds
[11:26:35.062] <TB1> INFO: ######################################################################
[11:26:35.062] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[11:26:35.062] <TB1> INFO: ######################################################################
[11:26:38.352] <TB1> INFO: Test took 3289ms.
[11:26:38.369] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:41.879] <TB1> INFO: Test took 3311ms.
[11:26:41.942] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:45.261] <TB1> INFO: Test took 3305ms.
[11:26:45.324] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:45.332] <TB1> INFO: The DUT currently contains the following objects:
[11:26:45.332] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[11:26:45.332] <TB1> INFO: TBM Core alpha (0): 7 registers set
[11:26:45.332] <TB1> INFO: TBM Core beta (1): 7 registers set
[11:26:45.332] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:26:45.333] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:45.333] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:45.333] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:45.333] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:45.333] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:45.333] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:45.333] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:45.333] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:45.333] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:45.333] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:45.333] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:45.333] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:45.333] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:45.333] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:45.333] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:45.333] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:46.437] <TB1> INFO: Test took 1104ms.
[11:26:46.437] <TB1> INFO: The DUT currently contains the following objects:
[11:26:46.437] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[11:26:46.437] <TB1> INFO: TBM Core alpha (0): 7 registers set
[11:26:46.437] <TB1> INFO: TBM Core beta (1): 7 registers set
[11:26:46.437] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:26:46.437] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:46.437] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:46.437] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:46.437] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:46.437] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:46.437] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:46.437] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:46.437] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:46.437] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:46.437] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:46.437] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:46.437] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:46.437] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:46.437] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:46.437] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:46.437] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:47.541] <TB1> INFO: Test took 1103ms.
[11:26:47.542] <TB1> INFO: The DUT currently contains the following objects:
[11:26:47.542] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[11:26:47.542] <TB1> INFO: TBM Core alpha (0): 7 registers set
[11:26:47.542] <TB1> INFO: TBM Core beta (1): 7 registers set
[11:26:47.542] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:26:47.542] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:47.542] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:47.542] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:47.542] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:47.542] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:47.542] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:47.542] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:47.542] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:47.542] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:47.542] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:47.542] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:47.542] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:47.542] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:47.542] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:47.542] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:47.542] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:48.660] <TB1> INFO: Test took 1118ms.
[11:26:48.662] <TB1> INFO: The DUT currently contains the following objects:
[11:26:48.662] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[11:26:48.662] <TB1> INFO: TBM Core alpha (0): 7 registers set
[11:26:48.662] <TB1> INFO: TBM Core beta (1): 7 registers set
[11:26:48.662] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:26:48.662] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:48.662] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:48.662] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:48.662] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:48.662] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:48.662] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:48.662] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:48.662] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:48.662] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:48.662] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:48.662] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:48.662] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:48.662] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:48.662] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:48.662] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:48.662] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:49.765] <TB1> INFO: Test took 1103ms.
[11:26:49.766] <TB1> INFO: The DUT currently contains the following objects:
[11:26:49.766] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[11:26:49.766] <TB1> INFO: TBM Core alpha (0): 7 registers set
[11:26:49.766] <TB1> INFO: TBM Core beta (1): 7 registers set
[11:26:49.766] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:26:49.766] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:49.766] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:49.766] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:49.766] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:49.766] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:49.766] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:49.766] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:49.766] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:49.766] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:49.766] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:49.766] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:49.766] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:49.766] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:49.766] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:49.766] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:49.766] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:50.872] <TB1> INFO: Test took 1106ms.
[11:26:50.872] <TB1> INFO: The DUT currently contains the following objects:
[11:26:50.872] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[11:26:50.872] <TB1> INFO: TBM Core alpha (0): 7 registers set
[11:26:50.872] <TB1> INFO: TBM Core beta (1): 7 registers set
[11:26:50.872] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:26:50.872] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:50.872] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:50.873] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:50.873] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:50.873] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:50.873] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:50.873] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:50.873] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:50.873] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:50.873] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:50.873] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:50.873] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:50.873] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:50.873] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:50.873] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:50.873] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:51.977] <TB1> INFO: Test took 1104ms.
[11:26:51.978] <TB1> INFO: The DUT currently contains the following objects:
[11:26:51.978] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[11:26:51.978] <TB1> INFO: TBM Core alpha (0): 7 registers set
[11:26:51.978] <TB1> INFO: TBM Core beta (1): 7 registers set
[11:26:51.978] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:26:51.978] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:51.978] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:51.978] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:51.978] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:51.978] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:51.978] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:51.978] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:51.978] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:51.978] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:51.978] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:51.978] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:51.978] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:51.978] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:51.978] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:51.978] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:51.978] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:53.084] <TB1> INFO: Test took 1106ms.
[11:26:53.085] <TB1> INFO: The DUT currently contains the following objects:
[11:26:53.085] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[11:26:53.085] <TB1> INFO: TBM Core alpha (0): 7 registers set
[11:26:53.085] <TB1> INFO: TBM Core beta (1): 7 registers set
[11:26:53.085] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:26:53.085] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:53.085] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:53.085] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:53.085] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:53.085] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:53.085] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:53.085] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:53.085] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:53.085] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:53.085] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:53.085] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:53.085] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:53.085] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:53.085] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:53.085] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:53.085] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:54.190] <TB1> INFO: Test took 1105ms.
[11:26:54.191] <TB1> INFO: The DUT currently contains the following objects:
[11:26:54.191] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[11:26:54.191] <TB1> INFO: TBM Core alpha (0): 7 registers set
[11:26:54.191] <TB1> INFO: TBM Core beta (1): 7 registers set
[11:26:54.191] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:26:54.191] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:54.191] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:54.191] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:54.191] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:54.191] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:54.191] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:54.191] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:54.191] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:54.191] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:54.191] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:54.191] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:54.191] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:54.191] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:54.191] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:54.191] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:54.191] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:55.303] <TB1> INFO: Test took 1111ms.
[11:26:55.304] <TB1> INFO: The DUT currently contains the following objects:
[11:26:55.304] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[11:26:55.304] <TB1> INFO: TBM Core alpha (0): 7 registers set
[11:26:55.304] <TB1> INFO: TBM Core beta (1): 7 registers set
[11:26:55.304] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:26:55.304] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:55.304] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:55.304] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:55.304] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:55.304] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:55.305] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:55.305] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:55.305] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:55.305] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:55.305] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:55.305] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:55.305] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:55.305] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:55.305] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:55.305] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:55.305] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:56.416] <TB1> INFO: Test took 1111ms.
[11:26:56.417] <TB1> INFO: The DUT currently contains the following objects:
[11:26:56.417] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[11:26:56.418] <TB1> INFO: TBM Core alpha (0): 7 registers set
[11:26:56.418] <TB1> INFO: TBM Core beta (1): 7 registers set
[11:26:56.418] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:26:56.418] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:56.418] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:56.418] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:56.418] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:56.418] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:56.418] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:56.418] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:56.418] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:56.418] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:56.418] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:56.418] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:56.418] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:56.418] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:56.418] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:56.419] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:56.419] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:57.530] <TB1> INFO: Test took 1111ms.
[11:26:57.532] <TB1> INFO: The DUT currently contains the following objects:
[11:26:57.542] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[11:26:57.542] <TB1> INFO: TBM Core alpha (0): 7 registers set
[11:26:57.542] <TB1> INFO: TBM Core beta (1): 7 registers set
[11:26:57.542] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:26:57.542] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:57.542] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:57.542] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:57.542] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:57.542] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:57.542] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:57.542] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:57.543] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:57.543] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:57.543] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:57.543] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:57.543] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:57.543] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:57.543] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:57.543] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:57.543] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:58.644] <TB1> INFO: Test took 1102ms.
[11:26:58.646] <TB1> INFO: The DUT currently contains the following objects:
[11:26:58.646] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[11:26:58.646] <TB1> INFO: TBM Core alpha (0): 7 registers set
[11:26:58.646] <TB1> INFO: TBM Core beta (1): 7 registers set
[11:26:58.646] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:26:58.646] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:58.646] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:58.646] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:58.646] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:58.646] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:58.646] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:58.646] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:58.646] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:58.647] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:58.647] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:58.647] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:58.647] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:58.647] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:58.647] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:58.647] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:58.647] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:59.751] <TB1> INFO: Test took 1104ms.
[11:26:59.752] <TB1> INFO: The DUT currently contains the following objects:
[11:26:59.752] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[11:26:59.752] <TB1> INFO: TBM Core alpha (0): 7 registers set
[11:26:59.752] <TB1> INFO: TBM Core beta (1): 7 registers set
[11:26:59.752] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:26:59.752] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:59.752] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:59.752] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:59.752] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:59.752] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:59.752] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:59.752] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:59.752] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:59.752] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:59.752] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:59.752] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:59.752] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:59.752] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:59.752] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:59.752] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:26:59.752] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:00.863] <TB1> INFO: Test took 1111ms.
[11:27:00.866] <TB1> INFO: The DUT currently contains the following objects:
[11:27:00.866] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[11:27:00.866] <TB1> INFO: TBM Core alpha (0): 7 registers set
[11:27:00.866] <TB1> INFO: TBM Core beta (1): 7 registers set
[11:27:00.866] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:27:00.866] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:00.866] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:00.866] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:00.866] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:00.866] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:00.866] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:00.866] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:00.866] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:00.866] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:00.866] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:00.866] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:00.866] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:00.866] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:00.866] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:00.866] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:00.866] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:01.977] <TB1> INFO: Test took 1111ms.
[11:27:01.978] <TB1> INFO: The DUT currently contains the following objects:
[11:27:01.978] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[11:27:01.978] <TB1> INFO: TBM Core alpha (0): 7 registers set
[11:27:01.978] <TB1> INFO: TBM Core beta (1): 7 registers set
[11:27:01.978] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:27:01.978] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:01.978] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:01.978] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:01.978] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:01.978] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:01.978] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:01.978] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:01.978] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:01.978] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:01.978] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:01.979] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:01.979] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:01.979] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:01.979] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:01.979] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:01.979] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:27:03.089] <TB1> INFO: Test took 1110ms.
[11:27:03.094] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:30:46.281] <TB1> INFO: Test took 223187ms.
[11:30:47.852] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:34:31.157] <TB1> INFO: Test took 223305ms.
[11:34:32.901] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:34:32.908] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[11:34:32.915] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:34:32.922] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:34:32.929] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:34:32.936] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:34:32.943] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:34:32.950] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:34:32.957] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:34:32.964] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:34:32.971] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:34:32.978] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[11:34:32.985] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[11:34:32.992] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[11:34:32.999] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:34:33.006] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:34:33.013] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:34:33.020] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:34:33.027] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:34:33.034] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:34:33.089] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C0.dat
[11:34:33.089] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C1.dat
[11:34:33.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C2.dat
[11:34:33.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C3.dat
[11:34:33.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C4.dat
[11:34:33.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C5.dat
[11:34:33.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C6.dat
[11:34:33.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C7.dat
[11:34:33.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C8.dat
[11:34:33.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C9.dat
[11:34:33.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C10.dat
[11:34:33.091] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C11.dat
[11:34:33.091] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C12.dat
[11:34:33.091] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C13.dat
[11:34:33.091] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C14.dat
[11:34:33.091] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//dacParameters35_C15.dat
[11:34:36.395] <TB1> INFO: Test took 3300ms.
[11:34:40.115] <TB1> INFO: Test took 3445ms.
[11:34:43.887] <TB1> INFO: Test took 3488ms.
[11:34:44.175] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:34:45.097] <TB1> INFO: Test took 922ms.
[11:34:45.101] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:34:46.211] <TB1> INFO: Test took 1110ms.
[11:34:46.216] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:34:47.324] <TB1> INFO: Test took 1108ms.
[11:34:47.326] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:34:48.438] <TB1> INFO: Test took 1112ms.
[11:34:48.442] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:34:49.553] <TB1> INFO: Test took 1111ms.
[11:34:49.557] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:34:50.668] <TB1> INFO: Test took 1111ms.
[11:34:50.672] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:34:51.782] <TB1> INFO: Test took 1110ms.
[11:34:51.786] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:34:52.897] <TB1> INFO: Test took 1111ms.
[11:34:52.901] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:34:54.013] <TB1> INFO: Test took 1112ms.
[11:34:54.017] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:34:55.127] <TB1> INFO: Test took 1110ms.
[11:34:55.131] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:34:56.244] <TB1> INFO: Test took 1113ms.
[11:34:56.248] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:34:57.359] <TB1> INFO: Test took 1111ms.
[11:34:57.363] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:34:58.475] <TB1> INFO: Test took 1112ms.
[11:34:58.479] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:34:59.586] <TB1> INFO: Test took 1107ms.
[11:34:59.589] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:00.701] <TB1> INFO: Test took 1112ms.
[11:35:00.705] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:01.815] <TB1> INFO: Test took 1110ms.
[11:35:01.820] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:02.930] <TB1> INFO: Test took 1111ms.
[11:35:02.934] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:04.045] <TB1> INFO: Test took 1111ms.
[11:35:04.049] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:05.161] <TB1> INFO: Test took 1112ms.
[11:35:05.166] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:06.276] <TB1> INFO: Test took 1110ms.
[11:35:06.281] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:07.392] <TB1> INFO: Test took 1111ms.
[11:35:07.396] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:08.502] <TB1> INFO: Test took 1106ms.
[11:35:08.506] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:09.617] <TB1> INFO: Test took 1112ms.
[11:35:09.622] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:10.732] <TB1> INFO: Test took 1110ms.
[11:35:10.735] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:11.847] <TB1> INFO: Test took 1112ms.
[11:35:11.852] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:12.963] <TB1> INFO: Test took 1112ms.
[11:35:12.966] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:14.077] <TB1> INFO: Test took 1111ms.
[11:35:14.081] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:15.191] <TB1> INFO: Test took 1111ms.
[11:35:15.195] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:16.308] <TB1> INFO: Test took 1113ms.
[11:35:16.313] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:17.425] <TB1> INFO: Test took 1112ms.
[11:35:17.429] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:18.535] <TB1> INFO: Test took 1106ms.
[11:35:18.538] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:19.649] <TB1> INFO: Test took 1111ms.
[11:35:20.244] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 525 seconds
[11:35:20.244] <TB1> INFO: PH scale (per ROC): 80 84 86 83 79 80 96 82 80 80 79 81 80 97 90 79
[11:35:20.244] <TB1> INFO: PH offset (per ROC): 145 147 143 142 146 153 139 153 155 140 158 150 165 160 145 159
[11:35:20.425] <TB1> INFO: ######################################################################
[11:35:20.425] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:35:20.425] <TB1> INFO: ######################################################################
[11:35:20.435] <TB1> INFO: scanning low vcal = 10
[11:35:24.245] <TB1> INFO: Test took 3810ms.
[11:35:24.250] <TB1> INFO: scanning low vcal = 20
[11:35:28.119] <TB1> INFO: Test took 3869ms.
[11:35:28.126] <TB1> INFO: scanning low vcal = 30
[11:35:32.039] <TB1> INFO: Test took 3913ms.
[11:35:32.049] <TB1> INFO: scanning low vcal = 40
[11:35:36.401] <TB1> INFO: Test took 4352ms.
[11:35:36.478] <TB1> INFO: scanning low vcal = 50
[11:35:40.908] <TB1> INFO: Test took 4430ms.
[11:35:40.975] <TB1> INFO: scanning low vcal = 60
[11:35:45.337] <TB1> INFO: Test took 4362ms.
[11:35:45.402] <TB1> INFO: scanning low vcal = 70
[11:35:49.784] <TB1> INFO: Test took 4382ms.
[11:35:49.847] <TB1> INFO: scanning low vcal = 80
[11:35:54.241] <TB1> INFO: Test took 4394ms.
[11:35:54.310] <TB1> INFO: scanning low vcal = 90
[11:35:58.698] <TB1> INFO: Test took 4388ms.
[11:35:58.767] <TB1> INFO: scanning low vcal = 100
[11:36:03.129] <TB1> INFO: Test took 4362ms.
[11:36:03.190] <TB1> INFO: scanning low vcal = 110
[11:36:07.565] <TB1> INFO: Test took 4375ms.
[11:36:07.636] <TB1> INFO: scanning low vcal = 120
[11:36:12.006] <TB1> INFO: Test took 4370ms.
[11:36:12.075] <TB1> INFO: scanning low vcal = 130
[11:36:16.446] <TB1> INFO: Test took 4371ms.
[11:36:16.525] <TB1> INFO: scanning low vcal = 140
[11:36:20.932] <TB1> INFO: Test took 4407ms.
[11:36:20.998] <TB1> INFO: scanning low vcal = 150
[11:36:25.336] <TB1> INFO: Test took 4338ms.
[11:36:25.399] <TB1> INFO: scanning low vcal = 160
[11:36:29.782] <TB1> INFO: Test took 4383ms.
[11:36:29.852] <TB1> INFO: scanning low vcal = 170
[11:36:34.205] <TB1> INFO: Test took 4352ms.
[11:36:34.274] <TB1> INFO: scanning low vcal = 180
[11:36:38.656] <TB1> INFO: Test took 4382ms.
[11:36:38.724] <TB1> INFO: scanning low vcal = 190
[11:36:43.215] <TB1> INFO: Test took 4491ms.
[11:36:43.282] <TB1> INFO: scanning low vcal = 200
[11:36:47.645] <TB1> INFO: Test took 4363ms.
[11:36:47.711] <TB1> INFO: scanning low vcal = 210
[11:36:52.101] <TB1> INFO: Test took 4390ms.
[11:36:52.157] <TB1> INFO: scanning low vcal = 220
[11:36:56.557] <TB1> INFO: Test took 4399ms.
[11:36:56.624] <TB1> INFO: scanning low vcal = 230
[11:37:00.988] <TB1> INFO: Test took 4364ms.
[11:37:01.048] <TB1> INFO: scanning low vcal = 240
[11:37:05.416] <TB1> INFO: Test took 4368ms.
[11:37:05.489] <TB1> INFO: scanning low vcal = 250
[11:37:09.870] <TB1> INFO: Test took 4380ms.
[11:37:09.940] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[11:37:14.304] <TB1> INFO: Test took 4364ms.
[11:37:14.374] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[11:37:18.742] <TB1> INFO: Test took 4368ms.
[11:37:18.805] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[11:37:23.212] <TB1> INFO: Test took 4407ms.
[11:37:23.285] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[11:37:27.587] <TB1> INFO: Test took 4302ms.
[11:37:27.655] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:37:32.024] <TB1> INFO: Test took 4369ms.
[11:37:32.560] <TB1> INFO: PixTestGainPedestal::measure() done
[11:38:08.225] <TB1> INFO: PixTestGainPedestal::fit() done
[11:38:08.225] <TB1> INFO: non-linearity mean: 0.954 0.948 0.953 0.958 0.952 0.961 0.954 0.947 0.955 0.953 0.949 0.950 0.953 0.954 0.957 0.956
[11:38:08.225] <TB1> INFO: non-linearity RMS: 0.006 0.006 0.006 0.006 0.006 0.005 0.006 0.007 0.005 0.005 0.005 0.006 0.007 0.006 0.005 0.007
[11:38:08.225] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[11:38:08.250] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[11:38:08.273] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[11:38:08.294] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[11:38:08.316] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[11:38:08.342] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[11:38:08.368] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[11:38:08.388] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[11:38:08.410] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[11:38:08.431] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[11:38:08.451] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[11:38:08.471] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[11:38:08.491] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[11:38:08.511] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[11:38:08.532] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[11:38:08.551] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[11:38:08.571] <TB1> INFO: PixTestGainPedestal::doTest() done, duration: 168 seconds
[11:38:08.578] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C15.dat
[11:38:08.579] <TB1> INFO: PixTestReadback::doTest() start.
[11:38:08.580] <TB1> INFO: PixTestReadback::RES sent once
[11:38:25.224] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C0.dat
[11:38:25.224] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C1.dat
[11:38:25.224] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C2.dat
[11:38:25.224] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C3.dat
[11:38:25.224] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C4.dat
[11:38:25.224] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C5.dat
[11:38:25.224] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C6.dat
[11:38:25.224] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C7.dat
[11:38:25.224] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C8.dat
[11:38:25.224] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C9.dat
[11:38:25.224] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C10.dat
[11:38:25.224] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C11.dat
[11:38:25.225] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C12.dat
[11:38:25.225] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C13.dat
[11:38:25.225] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C14.dat
[11:38:25.225] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C15.dat
[11:38:25.271] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:38:25.271] <TB1> INFO: PixTestReadback::RES sent once
[11:38:41.817] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C0.dat
[11:38:41.817] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C1.dat
[11:38:41.817] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C2.dat
[11:38:41.817] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C3.dat
[11:38:41.817] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C4.dat
[11:38:41.817] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C5.dat
[11:38:41.817] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C6.dat
[11:38:41.817] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C7.dat
[11:38:41.817] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C8.dat
[11:38:41.817] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C9.dat
[11:38:41.818] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C10.dat
[11:38:41.818] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C11.dat
[11:38:41.818] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C12.dat
[11:38:41.818] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C13.dat
[11:38:41.818] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C14.dat
[11:38:41.818] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C15.dat
[11:38:41.865] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:38:41.866] <TB1> INFO: PixTestReadback::RES sent once
[11:38:54.658] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:38:54.658] <TB1> INFO: Vbg will be calibrated using Vd calibration
[11:38:54.658] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 145.6calibrated Vbg = 1.17163 :::*/*/*/*/
[11:38:54.658] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 149.3calibrated Vbg = 1.17568 :::*/*/*/*/
[11:38:54.658] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 149.7calibrated Vbg = 1.17877 :::*/*/*/*/
[11:38:54.658] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 255calibrated Vbg = 1.1725 :::*/*/*/*/
[11:38:54.658] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 153.5calibrated Vbg = 1.18521 :::*/*/*/*/
[11:38:54.658] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 158.1calibrated Vbg = 1.1917 :::*/*/*/*/
[11:38:54.658] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 144.9calibrated Vbg = 1.19114 :::*/*/*/*/
[11:38:54.658] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 255calibrated Vbg = 1.1725 :::*/*/*/*/
[11:38:54.658] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 153.7calibrated Vbg = 1.19039 :::*/*/*/*/
[11:38:54.658] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 151.8calibrated Vbg = 1.18943 :::*/*/*/*/
[11:38:54.658] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 162.1calibrated Vbg = 1.18599 :::*/*/*/*/
[11:38:54.658] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 248.6calibrated Vbg = 1.11365 :::*/*/*/*/
[11:38:54.659] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 158.1calibrated Vbg = 1.18013 :::*/*/*/*/
[11:38:54.659] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 166.3calibrated Vbg = 1.17526 :::*/*/*/*/
[11:38:54.659] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 156.4calibrated Vbg = 1.17928 :::*/*/*/*/
[11:38:54.659] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 255calibrated Vbg = 1.1675 :::*/*/*/*/
[11:38:54.663] <TB1> INFO: PixTestReadback::RES sent once
[11:42:50.270] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C0.dat
[11:42:50.270] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C1.dat
[11:42:50.271] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C2.dat
[11:42:50.271] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C3.dat
[11:42:50.271] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C4.dat
[11:42:50.271] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C5.dat
[11:42:50.271] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C6.dat
[11:42:50.271] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C7.dat
[11:42:50.271] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C8.dat
[11:42:50.271] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C9.dat
[11:42:50.271] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C10.dat
[11:42:50.271] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C11.dat
[11:42:50.271] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C12.dat
[11:42:50.271] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C13.dat
[11:42:50.272] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C14.dat
[11:42:50.272] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2081_FullQualification_2015-08-24_09h18m_1440400713//002_FulltestPxar_m20//readbackCal_C15.dat
[11:42:50.317] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:42:50.319] <TB1> INFO: PixTestReadback::doTest() done
[11:42:50.335] <TB1> INFO: enter test to run
[11:42:50.335] <TB1> INFO: test: exit no parameter change
[11:42:50.896] <TB1> QUIET: Connection to board 153 closed.
[11:42:50.976] <TB1> INFO: pXar: this is the end, my friend