[07:26:20.315] <TB2> INFO: *** Welcome to pxar ***
[07:26:20.315] <TB2> INFO: *** Today: 2015/08/24
[07:26:20.315] <TB2> INFO: readRocDacs: /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C15.dat
[07:26:20.316] <TB2> INFO: readTbmDacs: /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//tbmParameters_C0b.dat
[07:26:20.317] <TB2> INFO: readMaskFile: /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//defaultMaskFile.dat
[07:26:20.317] <TB2> INFO: readTrimFile: /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters_C15.dat
[07:26:20.394] <TB2> INFO: clk: 4
[07:26:20.394] <TB2> INFO: ctr: 4
[07:26:20.394] <TB2> INFO: sda: 19
[07:26:20.394] <TB2> INFO: tin: 9
[07:26:20.394] <TB2> INFO: level: 15
[07:26:20.394] <TB2> INFO: triggerdelay: 0
[07:26:20.394] <TB2> QUIET: Instanciating API for pxar prod-01+86~g1838649
[07:26:20.394] <TB2> INFO: Log level: INFO
[07:26:20.402] <TB2> INFO: Found DTB DTB_WXC55Z
[07:26:20.409] <TB2> QUIET: Connection to board DTB_WXC55Z opened.
[07:26:20.413] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 156
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WXC55Z
MAC address: 40D85511809C
Hostname: pixelDTB156
Comment:
------------------------------------------------------
[07:26:20.416] <TB2> INFO: RPC call hashes of host and DTB match: 447413373
[07:26:21.955] <TB2> INFO: DUT info:
[07:26:21.955] <TB2> INFO: The DUT currently contains the following objects:
[07:26:21.955] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[07:26:21.955] <TB2> INFO: TBM Core alpha (0): 7 registers set
[07:26:21.955] <TB2> INFO: TBM Core beta (1): 7 registers set
[07:26:21.955] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:26:21.955] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:21.955] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:21.955] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:21.955] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:21.955] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:21.955] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:21.955] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:21.955] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:21.955] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:21.955] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:21.955] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:21.956] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:21.956] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:21.956] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:21.956] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:21.956] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:26:22.357] <TB2> INFO: enter 'restricted' command line mode
[07:26:22.357] <TB2> INFO: enter test to run
[07:26:22.357] <TB2> INFO: test: pretest no parameter change
[07:26:22.357] <TB2> INFO: running: pretest
[07:26:22.365] <TB2> INFO: ######################################################################
[07:26:22.365] <TB2> INFO: PixTestPretest::doTest()
[07:26:22.365] <TB2> INFO: ######################################################################
[07:26:22.367] <TB2> INFO: ----------------------------------------------------------------------
[07:26:22.367] <TB2> INFO: PixTestPretest::programROC()
[07:26:22.367] <TB2> INFO: ----------------------------------------------------------------------
[07:26:40.385] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[07:26:40.385] <TB2> INFO: IA differences per ROC: 18.5 20.9 19.3 21.7 21.7 18.5 20.1 20.9 19.3 18.5 16.1 17.7 18.5 20.1 18.5 19.3
[07:26:40.452] <TB2> INFO: ----------------------------------------------------------------------
[07:26:40.452] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[07:26:40.452] <TB2> INFO: ----------------------------------------------------------------------
[07:26:44.320] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 381.9 mA = 23.8688 mA/ROC
[07:26:44.324] <TB2> INFO: ----------------------------------------------------------------------
[07:26:44.325] <TB2> INFO: PixTestPretest::findWorkingPixel()
[07:26:44.325] <TB2> INFO: ----------------------------------------------------------------------
[07:26:52.838] <TB2> INFO: Test took 8507ms.
[07:26:53.137] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[07:26:53.176] <TB2> INFO: ----------------------------------------------------------------------
[07:26:53.176] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[07:26:53.176] <TB2> INFO: ----------------------------------------------------------------------
[07:27:01.488] <TB2> INFO: Test took 8305ms.
[07:27:01.798] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[07:27:01.799] <TB2> INFO: CalDel: 143 137 144 148 149 172 156 157 133 142 133 144 134 169 141 141
[07:27:01.799] <TB2> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[07:27:01.803] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C0.dat
[07:27:01.804] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C1.dat
[07:27:01.804] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C2.dat
[07:27:01.804] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C3.dat
[07:27:01.804] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C4.dat
[07:27:01.805] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C5.dat
[07:27:01.805] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C6.dat
[07:27:01.805] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C7.dat
[07:27:01.805] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C8.dat
[07:27:01.805] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C9.dat
[07:27:01.806] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C10.dat
[07:27:01.806] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C11.dat
[07:27:01.806] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C12.dat
[07:27:01.806] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C13.dat
[07:27:01.807] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C14.dat
[07:27:01.807] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters_C15.dat
[07:27:01.807] <TB2> INFO: write tbm parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//tbmParameters_C0a.dat
[07:27:01.807] <TB2> INFO: write tbm parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//tbmParameters_C0b.dat
[07:27:01.807] <TB2> INFO: PixTestPretest::doTest() done, duration: 39 seconds
[07:27:01.902] <TB2> INFO: enter test to run
[07:27:01.902] <TB2> INFO: test: fulltest no parameter change
[07:27:01.902] <TB2> INFO: running: fulltest
[07:27:01.902] <TB2> INFO: ######################################################################
[07:27:01.902] <TB2> INFO: PixTestFullTest::doTest()
[07:27:01.902] <TB2> INFO: ######################################################################
[07:27:01.904] <TB2> INFO: ######################################################################
[07:27:01.904] <TB2> INFO: PixTestAlive::doTest()
[07:27:01.904] <TB2> INFO: ######################################################################
[07:27:01.905] <TB2> INFO: ----------------------------------------------------------------------
[07:27:01.905] <TB2> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:27:01.905] <TB2> INFO: ----------------------------------------------------------------------
[07:27:05.343] <TB2> INFO: Test took 3436ms.
[07:27:05.367] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:27:05.593] <TB2> INFO: PixTestAlive::aliveTest() done
[07:27:05.593] <TB2> INFO: number of dead pixels (per ROC): 0 1 0 0 0 1 0 0 0 0 0 0 0 0 0 0
[07:27:05.597] <TB2> INFO: ----------------------------------------------------------------------
[07:27:05.597] <TB2> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:27:05.597] <TB2> INFO: ----------------------------------------------------------------------
[07:27:08.490] <TB2> INFO: Test took 2892ms.
[07:27:08.493] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:27:08.494] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[07:27:08.726] <TB2> INFO: PixTestAlive::maskTest() done
[07:27:08.726] <TB2> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:27:08.727] <TB2> INFO: ----------------------------------------------------------------------
[07:27:08.727] <TB2> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:27:08.727] <TB2> INFO: ----------------------------------------------------------------------
[07:27:12.250] <TB2> INFO: Test took 3521ms.
[07:27:12.271] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:27:12.495] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[07:27:12.495] <TB2> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:27:12.495] <TB2> INFO: PixTestAlive::doTest() done, duration: 10 seconds
[07:27:12.508] <TB2> INFO: ######################################################################
[07:27:12.508] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[07:27:12.508] <TB2> INFO: ######################################################################
[07:27:12.511] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30/5) hits flags = 2 (plus default)
[07:27:12.523] <TB2> INFO: dacScan step from 0 .. 29
[07:27:12.523] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:27:12.523] <TB2> INFO: run 1 of 1
[07:27:35.176] <TB2> INFO: Test took 22653ms.
[07:27:35.207] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:27:35.207] <TB2> INFO: dacScan step from 30 .. 59
[07:27:35.207] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:27:35.207] <TB2> INFO: run 1 of 1
[07:27:59.427] <TB2> INFO: Test took 24220ms.
[07:27:59.531] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:27:59.544] <TB2> INFO: dacScan step from 60 .. 89
[07:27:59.545] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:27:59.545] <TB2> INFO: run 1 of 1
[07:28:29.652] <TB2> INFO: Test took 30107ms.
[07:28:29.907] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:28:29.949] <TB2> INFO: dacScan step from 90 .. 119
[07:28:29.949] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:28:29.949] <TB2> INFO: run 1 of 1
[07:29:00.097] <TB2> INFO: Test took 30147ms.
[07:29:00.345] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:29:00.388] <TB2> INFO: dacScan step from 120 .. 149
[07:29:00.388] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:29:00.388] <TB2> INFO: run 1 of 1
[07:29:23.777] <TB2> INFO: Test took 23388ms.
[07:29:23.935] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:29:46.588] <TB2> INFO: PixTestBBMap::doTest() done, duration: 154 seconds
[07:29:46.588] <TB2> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:29:46.588] <TB2> INFO: separation cut (per ROC): 91 97 92 99 102 94 80 73 93 84 83 83 79 71 77 81
[07:29:46.658] <TB2> INFO: ######################################################################
[07:29:46.658] <TB2> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = 4, ntrig/step = 50
[07:29:46.658] <TB2> INFO: ######################################################################
[07:29:46.658] <TB2> INFO: ----------------------------------------------------------------------
[07:29:46.658] <TB2> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = 4, ntrig/step = 50
[07:29:46.658] <TB2> INFO: ----------------------------------------------------------------------
[07:29:46.658] <TB2> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (4/50) hits flags = 16 (plus default)
[07:29:46.667] <TB2> INFO: dacScan step from 0 .. 3
[07:29:46.667] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:29:46.667] <TB2> INFO: run 1 of 1
[07:30:08.072] <TB2> INFO: Test took 21405ms.
[07:30:08.102] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:30:08.102] <TB2> INFO: dacScan step from 4 .. 7
[07:30:08.102] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:30:08.102] <TB2> INFO: run 1 of 1
[07:30:29.033] <TB2> INFO: Test took 20930ms.
[07:30:29.059] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:30:29.059] <TB2> INFO: dacScan step from 8 .. 11
[07:30:29.059] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:30:29.059] <TB2> INFO: run 1 of 1
[07:30:50.030] <TB2> INFO: Test took 20971ms.
[07:30:50.061] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:30:50.061] <TB2> INFO: dacScan step from 12 .. 15
[07:30:50.061] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:30:50.061] <TB2> INFO: run 1 of 1
[07:31:10.683] <TB2> INFO: Test took 20621ms.
[07:31:10.708] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:31:10.709] <TB2> INFO: dacScan step from 16 .. 19
[07:31:10.709] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:31:10.709] <TB2> INFO: run 1 of 1
[07:31:31.124] <TB2> INFO: Test took 20415ms.
[07:31:31.155] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:31:31.156] <TB2> INFO: dacScan step from 20 .. 23
[07:31:31.156] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:31:31.156] <TB2> INFO: run 1 of 1
[07:31:51.726] <TB2> INFO: Test took 20570ms.
[07:31:51.751] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:31:51.752] <TB2> INFO: dacScan step from 24 .. 27
[07:31:51.752] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:31:51.752] <TB2> INFO: run 1 of 1
[07:32:12.354] <TB2> INFO: Test took 20602ms.
[07:32:12.381] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:12.381] <TB2> INFO: dacScan step from 28 .. 31
[07:32:12.381] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:32:12.381] <TB2> INFO: run 1 of 1
[07:32:33.608] <TB2> INFO: Test took 21227ms.
[07:32:33.633] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:33.633] <TB2> INFO: dacScan step from 32 .. 35
[07:32:33.633] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:32:33.633] <TB2> INFO: run 1 of 1
[07:32:54.768] <TB2> INFO: Test took 21135ms.
[07:32:54.802] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:54.802] <TB2> INFO: dacScan step from 36 .. 39
[07:32:54.802] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:32:54.802] <TB2> INFO: run 1 of 1
[07:33:16.212] <TB2> INFO: Test took 21410ms.
[07:33:16.242] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:33:16.242] <TB2> INFO: dacScan step from 40 .. 43
[07:33:16.242] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:33:16.242] <TB2> INFO: run 1 of 1
[07:33:37.523] <TB2> INFO: Test took 21281ms.
[07:33:37.550] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:33:37.550] <TB2> INFO: dacScan step from 44 .. 47
[07:33:37.550] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:33:37.550] <TB2> INFO: run 1 of 1
[07:33:59.205] <TB2> INFO: Test took 21655ms.
[07:33:59.238] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:33:59.238] <TB2> INFO: dacScan step from 48 .. 51
[07:33:59.238] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:33:59.238] <TB2> INFO: run 1 of 1
[07:34:20.945] <TB2> INFO: Test took 21707ms.
[07:34:20.978] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:34:20.978] <TB2> INFO: dacScan step from 52 .. 55
[07:34:20.978] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:34:20.978] <TB2> INFO: run 1 of 1
[07:34:42.036] <TB2> INFO: Test took 21058ms.
[07:34:42.074] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:34:42.074] <TB2> INFO: dacScan step from 56 .. 59
[07:34:42.074] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:34:42.074] <TB2> INFO: run 1 of 1
[07:35:03.490] <TB2> INFO: Test took 21416ms.
[07:35:03.519] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:35:03.519] <TB2> INFO: dacScan step from 60 .. 63
[07:35:03.519] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:35:03.519] <TB2> INFO: run 1 of 1
[07:35:24.510] <TB2> INFO: Test took 20990ms.
[07:35:24.544] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:35:24.544] <TB2> INFO: dacScan step from 64 .. 67
[07:35:24.544] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:35:24.544] <TB2> INFO: run 1 of 1
[07:35:46.478] <TB2> INFO: Test took 21934ms.
[07:35:46.528] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:35:46.529] <TB2> INFO: dacScan step from 68 .. 71
[07:35:46.529] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:35:46.529] <TB2> INFO: run 1 of 1
[07:36:09.600] <TB2> INFO: Test took 23070ms.
[07:36:09.674] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:36:09.678] <TB2> INFO: dacScan step from 72 .. 75
[07:36:09.678] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:36:09.678] <TB2> INFO: run 1 of 1
[07:36:34.151] <TB2> INFO: Test took 24473ms.
[07:36:34.252] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:36:34.257] <TB2> INFO: dacScan step from 76 .. 79
[07:36:34.257] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:36:34.257] <TB2> INFO: run 1 of 1
[07:37:00.490] <TB2> INFO: Test took 26233ms.
[07:37:00.649] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:37:00.659] <TB2> INFO: dacScan step from 80 .. 83
[07:37:00.659] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:37:00.659] <TB2> INFO: run 1 of 1
[07:37:29.518] <TB2> INFO: Test took 28859ms.
[07:37:29.706] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:37:29.714] <TB2> INFO: dacScan step from 84 .. 87
[07:37:29.714] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:37:29.714] <TB2> INFO: run 1 of 1
[07:37:59.696] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (8) != Token Chain Length (4)
[07:37:59.696] <TB2> ERROR: <datapipe.cc/CheckEventID:L453> Channel 3 Event ID mismatch: local ID (128) != TBM ID (129)
[07:37:59.696] <TB2> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[07:37:59.696] <TB2> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[07:37:59.697] <TB2> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[07:38:00.105] <TB2> INFO: Test took 30391ms.
[07:38:00.317] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:38:00.328] <TB2> INFO: dacScan step from 88 .. 91
[07:38:00.328] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:38:00.328] <TB2> INFO: run 1 of 1
[07:38:30.872] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (8) != Token Chain Length (4)
[07:38:30.872] <TB2> ERROR: <datapipe.cc/CheckEventID:L453> Channel 3 Event ID mismatch: local ID (192) != TBM ID (193)
[07:38:30.872] <TB2> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[07:38:30.872] <TB2> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[07:38:30.872] <TB2> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[07:38:31.849] <TB2> INFO: Test took 31521ms.
[07:38:32.082] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:38:32.091] <TB2> INFO: dacScan step from 92 .. 95
[07:38:32.091] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:38:32.091] <TB2> INFO: run 1 of 1
[07:39:02.540] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[07:39:02.540] <TB2> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[07:39:04.026] <TB2> INFO: Test took 31934ms.
[07:39:04.255] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:39:04.264] <TB2> INFO: dacScan step from 96 .. 99
[07:39:04.264] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:39:04.264] <TB2> INFO: run 1 of 1
[07:39:35.501] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (8) != Token Chain Length (4)
[07:39:35.501] <TB2> ERROR: <datapipe.cc/CheckEventID:L453> Channel 3 Event ID mismatch: local ID (191) != TBM ID (192)
[07:39:35.501] <TB2> WARNING: Channel 3 ROC 1: Readback start marker after 31 readouts!
[07:39:35.501] <TB2> WARNING: Channel 3 ROC 2: Readback start marker after 31 readouts!
[07:39:35.501] <TB2> WARNING: Channel 3 ROC 3: Readback start marker after 31 readouts!
[07:39:37.233] <TB2> INFO: Test took 32969ms.
[07:39:37.488] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:39:37.497] <TB2> INFO: dacScan step from 100 .. 103
[07:39:37.497] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:39:37.497] <TB2> INFO: run 1 of 1
[07:40:09.937] <TB2> INFO: Test took 32440ms.
[07:40:10.168] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:40:10.177] <TB2> INFO: dacScan step from 104 .. 107
[07:40:10.177] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:40:10.177] <TB2> INFO: run 1 of 1
[07:40:40.996] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[07:40:40.996] <TB2> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[07:40:42.477] <TB2> INFO: Test took 32301ms.
[07:40:42.710] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:40:42.720] <TB2> INFO: dacScan step from 108 .. 111
[07:40:42.720] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:40:42.720] <TB2> INFO: run 1 of 1
[07:41:13.367] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[07:41:13.367] <TB2> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[07:41:14.845] <TB2> INFO: Test took 32125ms.
[07:41:15.078] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:41:15.089] <TB2> INFO: dacScan step from 112 .. 115
[07:41:15.089] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:41:15.089] <TB2> INFO: run 1 of 1
[07:41:47.109] <TB2> INFO: Test took 32020ms.
[07:41:47.342] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:41:47.353] <TB2> INFO: dacScan step from 116 .. 119
[07:41:47.353] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:41:47.353] <TB2> INFO: run 1 of 1
[07:42:19.443] <TB2> INFO: Test took 32089ms.
[07:42:19.665] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:42:19.674] <TB2> INFO: dacScan step from 120 .. 123
[07:42:19.674] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:42:19.674] <TB2> INFO: run 1 of 1
[07:42:51.561] <TB2> INFO: Test took 31887ms.
[07:42:51.794] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:42:51.804] <TB2> INFO: dacScan step from 124 .. 127
[07:42:51.804] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:42:51.804] <TB2> INFO: run 1 of 1
[07:43:23.517] <TB2> INFO: Test took 31713ms.
[07:43:23.737] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:43:23.746] <TB2> INFO: dacScan step from 128 .. 131
[07:43:23.746] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:43:23.746] <TB2> INFO: run 1 of 1
[07:43:55.878] <TB2> INFO: Test took 32131ms.
[07:43:56.111] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:43:56.121] <TB2> INFO: dacScan step from 132 .. 135
[07:43:56.121] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:43:56.121] <TB2> INFO: run 1 of 1
[07:44:28.866] <TB2> INFO: Test took 32745ms.
[07:44:29.102] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:44:29.112] <TB2> INFO: dacScan step from 136 .. 139
[07:44:29.112] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:44:29.112] <TB2> INFO: run 1 of 1
[07:45:00.463] <TB2> INFO: Test took 31351ms.
[07:45:00.765] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:45:00.774] <TB2> INFO: dacScan step from 140 .. 143
[07:45:00.774] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:45:00.774] <TB2> INFO: run 1 of 1
[07:45:32.048] <TB2> INFO: Test took 31274ms.
[07:45:32.283] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:45:32.291] <TB2> INFO: dacScan step from 144 .. 147
[07:45:32.291] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:45:32.291] <TB2> INFO: run 1 of 1
[07:46:04.985] <TB2> INFO: Test took 32694ms.
[07:46:05.209] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:46:05.218] <TB2> INFO: dacScan step from 148 .. 149
[07:46:05.218] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[07:46:05.218] <TB2> INFO: run 1 of 1
[07:46:20.082] <TB2> INFO: Test took 14864ms.
[07:46:20.195] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:46:20.202] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:46:21.630] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:46:23.125] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:46:24.581] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:46:26.010] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:46:27.417] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:46:28.807] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:46:30.233] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:46:31.659] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:46:33.007] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:46:34.400] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:46:35.782] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:46:37.185] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:46:38.562] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:46:39.964] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:46:41.366] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[07:46:42.826] <TB2> INFO: PixTestScurves::scurves() done
[07:46:42.826] <TB2> INFO: Vcal mean: 89.38 83.27 78.46 96.09 84.41 83.77 77.72 73.78 92.94 76.79 79.94 74.04 82.16 70.59 76.29 76.40
[07:46:42.827] <TB2> INFO: Vcal RMS: 5.61 5.87 4.57 5.99 5.74 5.70 4.77 4.39 5.60 4.72 4.36 5.32 5.61 4.52 4.43 4.55
[07:46:42.827] <TB2> INFO: PixTestScurves::fullTest() done, duration: 1016 seconds
[07:46:42.906] <TB2> INFO: ######################################################################
[07:46:42.906] <TB2> INFO: PixTestTrim::doTest()
[07:46:42.906] <TB2> INFO: ######################################################################
[07:46:42.909] <TB2> INFO: ----------------------------------------------------------------------
[07:46:42.909] <TB2> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[07:46:42.909] <TB2> INFO: ----------------------------------------------------------------------
[07:46:42.997] <TB2> INFO: ---> VthrComp thr map (minimal VthrComp)
[07:46:42.997] <TB2> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[07:46:43.007] <TB2> INFO: dacScan step from 0 .. 19
[07:46:43.007] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:46:43.007] <TB2> INFO: run 1 of 1
[07:46:59.293] <TB2> INFO: Test took 16286ms.
[07:46:59.320] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:46:59.320] <TB2> INFO: dacScan step from 20 .. 39
[07:46:59.320] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:46:59.320] <TB2> INFO: run 1 of 1
[07:47:15.412] <TB2> INFO: Test took 16092ms.
[07:47:15.434] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:47:15.434] <TB2> INFO: dacScan step from 40 .. 59
[07:47:15.434] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:47:15.434] <TB2> INFO: run 1 of 1
[07:47:31.164] <TB2> INFO: Test took 15730ms.
[07:47:31.184] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:47:31.184] <TB2> INFO: dacScan step from 60 .. 79
[07:47:31.184] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:47:31.184] <TB2> INFO: run 1 of 1
[07:47:47.128] <TB2> INFO: Test took 15944ms.
[07:47:47.150] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:47:47.150] <TB2> INFO: dacScan step from 80 .. 99
[07:47:47.150] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:47:47.150] <TB2> INFO: run 1 of 1
[07:48:05.016] <TB2> INFO: Test took 17866ms.
[07:48:05.104] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:48:05.118] <TB2> INFO: dacScan step from 100 .. 119
[07:48:05.118] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:48:05.118] <TB2> INFO: run 1 of 1
[07:48:25.869] <TB2> INFO: Test took 20751ms.
[07:48:26.044] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:48:26.073] <TB2> INFO: dacScan step from 120 .. 139
[07:48:26.073] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:48:26.073] <TB2> INFO: run 1 of 1
[07:48:45.585] <TB2> INFO: Test took 19512ms.
[07:48:45.704] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:48:45.724] <TB2> INFO: dacScan step from 140 .. 159
[07:48:45.724] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:48:45.724] <TB2> INFO: run 1 of 1
[07:49:00.483] <TB2> INFO: Test took 14759ms.
[07:49:00.527] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:49:21.236] <TB2> INFO: ROC 0 VthrComp = 92
[07:49:21.236] <TB2> INFO: ROC 1 VthrComp = 86
[07:49:21.236] <TB2> INFO: ROC 2 VthrComp = 83
[07:49:21.236] <TB2> INFO: ROC 3 VthrComp = 98
[07:49:21.236] <TB2> INFO: ROC 4 VthrComp = 91
[07:49:21.236] <TB2> INFO: ROC 5 VthrComp = 85
[07:49:21.236] <TB2> INFO: ROC 6 VthrComp = 82
[07:49:21.236] <TB2> INFO: ROC 7 VthrComp = 78
[07:49:21.237] <TB2> INFO: ROC 8 VthrComp = 96
[07:49:21.237] <TB2> INFO: ROC 9 VthrComp = 83
[07:49:21.237] <TB2> INFO: ROC 10 VthrComp = 84
[07:49:21.237] <TB2> INFO: ROC 11 VthrComp = 78
[07:49:21.237] <TB2> INFO: ROC 12 VthrComp = 83
[07:49:21.237] <TB2> INFO: ROC 13 VthrComp = 77
[07:49:21.237] <TB2> INFO: ROC 14 VthrComp = 82
[07:49:21.237] <TB2> INFO: ROC 15 VthrComp = 81
[07:49:21.237] <TB2> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[07:49:21.237] <TB2> INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[07:49:21.246] <TB2> INFO: dacScan step from 0 .. 19
[07:49:21.246] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:49:21.246] <TB2> INFO: run 1 of 1
[07:49:37.041] <TB2> INFO: Test took 15794ms.
[07:49:37.061] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:49:37.061] <TB2> INFO: dacScan step from 20 .. 39
[07:49:37.061] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:49:37.061] <TB2> INFO: run 1 of 1
[07:49:53.654] <TB2> INFO: Test took 16592ms.
[07:49:53.690] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:49:53.693] <TB2> INFO: dacScan step from 40 .. 59
[07:49:53.693] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:49:53.693] <TB2> INFO: run 1 of 1
[07:50:14.439] <TB2> INFO: Test took 20746ms.
[07:50:14.599] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:50:14.629] <TB2> INFO: dacScan step from 60 .. 79
[07:50:14.629] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:50:14.629] <TB2> INFO: run 1 of 1
[07:50:35.471] <TB2> INFO: Test took 20842ms.
[07:50:35.628] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:50:35.661] <TB2> INFO: dacScan step from 80 .. 99
[07:50:35.661] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:50:35.661] <TB2> INFO: run 1 of 1
[07:50:57.338] <TB2> INFO: Test took 21677ms.
[07:50:57.517] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:50:57.546] <TB2> INFO: dacScan step from 100 .. 119
[07:50:57.546] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:50:57.546] <TB2> INFO: run 1 of 1
[07:51:19.610] <TB2> INFO: Test took 22063ms.
[07:51:19.772] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:51:19.802] <TB2> INFO: dacScan step from 120 .. 139
[07:51:19.802] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:51:19.802] <TB2> INFO: run 1 of 1
[07:51:41.890] <TB2> INFO: Test took 22088ms.
[07:51:42.065] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:51:42.102] <TB2> INFO: dacScan step from 140 .. 159
[07:51:42.102] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:51:42.102] <TB2> INFO: run 1 of 1
[07:52:01.167] <TB2> INFO: Test took 19065ms.
[07:52:01.321] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:52:25.982] <TB2> INFO: roc 0 with ID = 0 has maximal Vcal 59.964 for pixel 26/79 mean/min/max = 45.8196/31.6592/59.9799
[07:52:25.983] <TB2> INFO: roc 1 with ID = 1 has maximal Vcal 61.9658 for pixel 14/64 mean/min/max = 46.7429/31.5194/61.9665
[07:52:25.983] <TB2> INFO: roc 2 with ID = 2 has maximal Vcal 57.5393 for pixel 5/54 mean/min/max = 45.324/32.7075/57.9406
[07:52:25.983] <TB2> INFO: roc 3 with ID = 3 has maximal Vcal 60.1276 for pixel 20/77 mean/min/max = 45.6138/30.9654/60.2621
[07:52:25.984] <TB2> INFO: roc 4 with ID = 4 has maximal Vcal 60.0294 for pixel 24/75 mean/min/max = 45.866/31.443/60.2889
[07:52:25.984] <TB2> INFO: roc 5 with ID = 5 has maximal Vcal 61.6943 for pixel 0/69 mean/min/max = 46.7586/31.6347/61.8825
[07:52:25.984] <TB2> INFO: roc 6 with ID = 6 has maximal Vcal 58.2002 for pixel 25/62 mean/min/max = 45.0018/31.7133/58.2904
[07:52:25.985] <TB2> INFO: roc 7 with ID = 7 has maximal Vcal 57.8815 for pixel 20/63 mean/min/max = 46.6349/35.2494/58.0205
[07:52:25.985] <TB2> INFO: roc 8 with ID = 8 has maximal Vcal 58.6705 for pixel 34/7 mean/min/max = 45.2721/31.7792/58.765
[07:52:25.985] <TB2> INFO: roc 9 with ID = 9 has maximal Vcal 58.5532 for pixel 10/50 mean/min/max = 45.2147/31.7639/58.6655
[07:52:25.985] <TB2> INFO: roc 10 with ID = 10 has maximal Vcal 58.3018 for pixel 0/20 mean/min/max = 45.551/32.7449/58.3571
[07:52:25.986] <TB2> INFO: roc 11 with ID = 11 has maximal Vcal 61.5834 for pixel 19/32 mean/min/max = 47.7428/33.8577/61.6279
[07:52:25.986] <TB2> INFO: roc 12 with ID = 12 has maximal Vcal 62.1139 for pixel 15/79 mean/min/max = 46.7673/31.3198/62.2148
[07:52:25.986] <TB2> INFO: roc 13 with ID = 13 has maximal Vcal 57.4096 for pixel 14/9 mean/min/max = 46.1892/34.8224/57.5559
[07:52:25.986] <TB2> INFO: roc 14 with ID = 14 has maximal Vcal 57.5141 for pixel 34/5 mean/min/max = 44.9636/32.2815/57.6457
[07:52:25.987] <TB2> INFO: roc 15 with ID = 15 has maximal Vcal 57.5733 for pixel 9/79 mean/min/max = 44.9834/32.3742/57.5926
[07:52:25.987] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:54:06.916] <TB2> INFO: Test took 100929ms.
[07:54:08.498] <TB2> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[07:54:08.507] <TB2> INFO: dacScan step from 0 .. 19
[07:54:08.507] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[07:54:08.507] <TB2> INFO: run 1 of 2
[07:54:23.739] <TB2> INFO: Test took 15232ms.
[07:54:23.765] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:54:23.766] <TB2> INFO: run 2 of 2
[07:54:39.432] <TB2> INFO: Test took 15666ms.
[07:54:39.471] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:54:39.472] <TB2> INFO: dacScan step from 20 .. 39
[07:54:39.472] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[07:54:39.472] <TB2> INFO: run 1 of 2
[07:54:57.881] <TB2> INFO: Test took 18408ms.
[07:54:58.030] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:54:58.072] <TB2> INFO: run 2 of 2
[07:55:16.087] <TB2> INFO: Test took 18015ms.
[07:55:16.240] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:55:16.284] <TB2> INFO: dacScan step from 40 .. 59
[07:55:16.284] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[07:55:16.284] <TB2> INFO: run 1 of 2
[07:55:37.062] <TB2> INFO: Test took 20778ms.
[07:55:37.338] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:55:37.412] <TB2> INFO: run 2 of 2
[07:55:59.460] <TB2> INFO: Test took 22048ms.
[07:55:59.627] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:55:59.658] <TB2> INFO: dacScan step from 60 .. 79
[07:55:59.658] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[07:55:59.658] <TB2> INFO: run 1 of 2
[07:56:21.776] <TB2> INFO: Test took 22118ms.
[07:56:21.952] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:56:21.985] <TB2> INFO: run 2 of 2
[07:56:44.045] <TB2> INFO: Test took 22060ms.
[07:56:44.208] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:56:44.239] <TB2> INFO: dacScan step from 80 .. 99
[07:56:44.239] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[07:56:44.239] <TB2> INFO: run 1 of 2
[07:57:06.157] <TB2> INFO: Test took 21918ms.
[07:57:06.325] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:57:06.354] <TB2> INFO: run 2 of 2
[07:57:27.924] <TB2> INFO: Test took 21569ms.
[07:57:28.096] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:57:28.128] <TB2> INFO: dacScan step from 100 .. 119
[07:57:28.128] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[07:57:28.128] <TB2> INFO: run 1 of 2
[07:57:49.008] <TB2> INFO: Test took 20880ms.
[07:57:49.179] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:57:49.210] <TB2> INFO: run 2 of 2
[07:58:10.726] <TB2> INFO: Test took 21516ms.
[07:58:10.898] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:58:10.930] <TB2> INFO: dacScan step from 120 .. 139
[07:58:10.930] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[07:58:10.930] <TB2> INFO: run 1 of 2
[07:58:32.620] <TB2> INFO: Test took 21690ms.
[07:58:32.786] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:58:32.817] <TB2> INFO: run 2 of 2
[07:58:54.028] <TB2> INFO: Test took 21211ms.
[07:58:54.290] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:58:54.348] <TB2> INFO: dacScan step from 140 .. 159
[07:58:54.348] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[07:58:54.348] <TB2> INFO: run 1 of 2
[07:59:14.818] <TB2> INFO: Test took 20470ms.
[07:59:14.991] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:59:15.024] <TB2> INFO: run 2 of 2
[07:59:36.440] <TB2> INFO: Test took 21416ms.
[07:59:36.603] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:59:36.636] <TB2> INFO: dacScan step from 160 .. 179
[07:59:36.636] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[07:59:36.636] <TB2> INFO: run 1 of 2
[07:59:58.027] <TB2> INFO: Test took 21391ms.
[07:59:58.202] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:59:58.236] <TB2> INFO: run 2 of 2
[08:00:19.843] <TB2> INFO: Test took 21607ms.
[08:00:20.013] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:00:20.044] <TB2> INFO: dacScan step from 180 .. 199
[08:00:20.044] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:00:20.044] <TB2> INFO: run 1 of 2
[08:00:42.386] <TB2> INFO: Test took 22341ms.
[08:00:42.554] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:00:42.585] <TB2> INFO: run 2 of 2
[08:01:02.375] <TB2> INFO: Test took 19789ms.
[08:01:02.535] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:01:26.801] <TB2> INFO: ---> TrimStepCorr4 extremal thresholds: 0.179556 .. 255.000000
[08:01:26.880] <TB2> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 255 (20/-1) hits flags = 16 (plus default)
[08:01:26.889] <TB2> INFO: dacScan step from 0 .. 19
[08:01:26.889] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[08:01:26.889] <TB2> INFO: run 1 of 1
[08:01:40.824] <TB2> INFO: Test took 13935ms.
[08:01:40.843] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:01:40.843] <TB2> INFO: dacScan step from 20 .. 39
[08:01:40.843] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[08:01:40.843] <TB2> INFO: run 1 of 1
[08:01:56.265] <TB2> INFO: Test took 15422ms.
[08:01:56.343] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:01:56.358] <TB2> INFO: dacScan step from 40 .. 59
[08:01:56.358] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[08:01:56.358] <TB2> INFO: run 1 of 1
[08:02:14.906] <TB2> INFO: Test took 18548ms.
[08:02:15.053] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:02:15.083] <TB2> INFO: dacScan step from 60 .. 79
[08:02:15.083] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[08:02:15.083] <TB2> INFO: run 1 of 1
[08:02:33.641] <TB2> INFO: Test took 18558ms.
[08:02:33.779] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:02:33.808] <TB2> INFO: dacScan step from 80 .. 99
[08:02:33.808] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[08:02:33.808] <TB2> INFO: run 1 of 1
[08:02:51.348] <TB2> INFO: Test took 17539ms.
[08:02:51.486] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:02:51.520] <TB2> INFO: dacScan step from 100 .. 119
[08:02:51.520] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[08:02:51.520] <TB2> INFO: run 1 of 1
[08:03:08.395] <TB2> INFO: Test took 16875ms.
[08:03:08.532] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:03:08.566] <TB2> INFO: dacScan step from 120 .. 139
[08:03:08.566] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[08:03:08.566] <TB2> INFO: run 1 of 1
[08:03:25.755] <TB2> INFO: Test took 17189ms.
[08:03:25.906] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:03:25.941] <TB2> INFO: dacScan step from 140 .. 159
[08:03:25.941] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[08:03:25.941] <TB2> INFO: run 1 of 1
[08:03:44.689] <TB2> INFO: Test took 18747ms.
[08:03:44.829] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:03:44.861] <TB2> INFO: dacScan step from 160 .. 179
[08:03:44.861] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[08:03:44.861] <TB2> INFO: run 1 of 1
[08:04:03.114] <TB2> INFO: Test took 18253ms.
[08:04:03.258] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:04:03.289] <TB2> INFO: dacScan step from 180 .. 199
[08:04:03.289] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[08:04:03.289] <TB2> INFO: run 1 of 1
[08:04:22.348] <TB2> INFO: Test took 19059ms.
[08:04:22.490] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:04:22.524] <TB2> INFO: dacScan step from 200 .. 219
[08:04:22.524] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[08:04:22.524] <TB2> INFO: run 1 of 1
[08:04:40.290] <TB2> INFO: Test took 17766ms.
[08:04:40.432] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:04:40.465] <TB2> INFO: dacScan step from 220 .. 239
[08:04:40.465] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[08:04:40.465] <TB2> INFO: run 1 of 1
[08:04:59.315] <TB2> INFO: Test took 18850ms.
[08:04:59.462] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:04:59.493] <TB2> INFO: dacScan step from 240 .. 255
[08:04:59.493] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[08:04:59.493] <TB2> INFO: run 1 of 1
[08:05:13.342] <TB2> INFO: Test took 13849ms.
[08:05:13.495] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:05:44.393] <TB2> INFO: ---> TrimStepCorr2 extremal thresholds: 14.479005 .. 47.109866
[08:05:44.475] <TB2> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 4 .. 57 (20/-1) hits flags = 16 (plus default)
[08:05:44.483] <TB2> INFO: dacScan step from 4 .. 23
[08:05:44.483] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[08:05:44.483] <TB2> INFO: run 1 of 1
[08:05:58.761] <TB2> INFO: Test took 14278ms.
[08:05:58.787] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:05:58.787] <TB2> INFO: dacScan step from 24 .. 43
[08:05:58.787] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[08:05:58.787] <TB2> INFO: run 1 of 1
[08:06:15.317] <TB2> INFO: Test took 16530ms.
[08:06:15.425] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:06:15.449] <TB2> INFO: dacScan step from 44 .. 57
[08:06:15.449] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[08:06:15.449] <TB2> INFO: run 1 of 1
[08:06:27.795] <TB2> INFO: Test took 12346ms.
[08:06:27.893] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:06:43.942] <TB2> INFO: ---> TrimStepCorr1a extremal thresholds: 17.011470 .. 43.763224
[08:06:44.036] <TB2> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 7 .. 53 (20/-1) hits flags = 16 (plus default)
[08:06:44.045] <TB2> INFO: dacScan step from 7 .. 26
[08:06:44.045] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[08:06:44.045] <TB2> INFO: run 1 of 1
[08:06:57.112] <TB2> INFO: Test took 13067ms.
[08:06:57.131] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:06:57.132] <TB2> INFO: dacScan step from 27 .. 46
[08:06:57.132] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[08:06:57.132] <TB2> INFO: run 1 of 1
[08:07:12.732] <TB2> INFO: Test took 15600ms.
[08:07:12.895] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:07:12.926] <TB2> INFO: dacScan step from 47 .. 53
[08:07:12.926] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[08:07:12.926] <TB2> INFO: run 1 of 1
[08:07:21.150] <TB2> INFO: Test took 8224ms.
[08:07:21.202] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:07:36.768] <TB2> INFO: ---> TrimStepCorr1b extremal thresholds: 20.100132 .. 43.763224
[08:07:36.847] <TB2> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 10 .. 53 (20/-1) hits flags = 16 (plus default)
[08:07:36.856] <TB2> INFO: dacScan step from 10 .. 29
[08:07:36.856] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[08:07:36.856] <TB2> INFO: run 1 of 1
[08:07:50.468] <TB2> INFO: Test took 13612ms.
[08:07:50.487] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:07:50.488] <TB2> INFO: dacScan step from 30 .. 49
[08:07:50.488] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[08:07:50.488] <TB2> INFO: run 1 of 1
[08:08:07.245] <TB2> INFO: Test took 16757ms.
[08:08:07.384] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:08:07.411] <TB2> INFO: dacScan step from 50 .. 53
[08:08:07.411] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[08:08:07.411] <TB2> INFO: run 1 of 1
[08:08:13.725] <TB2> INFO: Test took 6314ms.
[08:08:13.759] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:08:29.116] <TB2> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[08:08:29.116] <TB2> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20/5) hits flags = 16 (plus default)
[08:08:29.124] <TB2> INFO: dacScan step from 15 .. 34
[08:08:29.124] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:08:29.124] <TB2> INFO: run 1 of 2
[08:08:43.702] <TB2> INFO: Test took 14577ms.
[08:08:43.740] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:08:43.748] <TB2> INFO: run 2 of 2
[08:08:59.409] <TB2> INFO: Test took 15661ms.
[08:08:59.448] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:08:59.455] <TB2> INFO: dacScan step from 35 .. 54
[08:08:59.455] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:08:59.455] <TB2> INFO: run 1 of 2
[08:09:20.623] <TB2> INFO: Test took 21168ms.
[08:09:20.796] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:09:20.827] <TB2> INFO: run 2 of 2
[08:09:42.575] <TB2> INFO: Test took 21748ms.
[08:09:42.780] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:09:42.819] <TB2> INFO: dacScan step from 55 .. 55
[08:09:42.819] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:09:42.819] <TB2> INFO: run 1 of 2
[08:09:46.578] <TB2> INFO: Test took 3759ms.
[08:09:46.591] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:09:46.594] <TB2> INFO: run 2 of 2
[08:09:50.337] <TB2> INFO: Test took 3743ms.
[08:09:50.351] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:10:04.470] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:10:04.470] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:10:04.470] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:10:04.471] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:10:04.471] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:10:04.471] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:10:04.471] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:10:04.471] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:10:04.471] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:10:04.471] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:10:04.471] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:10:04.472] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:10:04.472] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:10:04.472] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:10:04.472] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:10:04.472] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:10:04.473] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C0.dat
[08:10:04.480] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C1.dat
[08:10:04.485] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C2.dat
[08:10:04.491] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C3.dat
[08:10:04.497] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C4.dat
[08:10:04.503] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C5.dat
[08:10:04.509] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C6.dat
[08:10:04.515] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C7.dat
[08:10:04.521] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C8.dat
[08:10:04.526] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C9.dat
[08:10:04.532] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C10.dat
[08:10:04.538] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C11.dat
[08:10:04.545] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C12.dat
[08:10:04.551] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C13.dat
[08:10:04.557] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C14.dat
[08:10:04.563] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//trimParameters35_C15.dat
[08:10:04.569] <TB2> INFO: PixTestTrim::trimTest() done
[08:10:04.569] <TB2> INFO: vtrim: 102 140 109 111 120 120 103 107 120 112 99 112 115 115 110 108
[08:10:04.569] <TB2> INFO: vthrcomp: 92 86 83 98 91 85 82 78 96 83 84 78 83 77 82 81
[08:10:04.569] <TB2> INFO: vcal mean: 35.03 35.02 35.04 35.02 34.97 34.93 35.00 35.05 35.02 35.00 35.01 35.04 35.07 35.03 35.02 35.11
[08:10:04.569] <TB2> INFO: vcal RMS: 1.05 1.20 0.97 1.17 0.99 1.16 1.12 0.94 1.04 1.00 0.99 1.00 1.14 0.96 1.04 1.09
[08:10:04.569] <TB2> INFO: bits mean: 9.89 9.69 9.71 9.77 10.01 9.34 10.01 9.24 10.09 9.99 9.64 9.24 9.45 9.37 10.38 10.22
[08:10:04.569] <TB2> INFO: bits RMS: 2.54 2.59 2.54 2.68 2.49 2.77 2.51 2.29 2.45 2.50 2.54 2.41 2.72 2.34 2.35 2.39
[08:10:04.577] <TB2> INFO: ----------------------------------------------------------------------
[08:10:04.577] <TB2> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[08:10:04.577] <TB2> INFO: ----------------------------------------------------------------------
[08:10:04.580] <TB2> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[08:10:04.589] <TB2> INFO: dacScan step from 0 .. 19
[08:10:04.589] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:10:04.589] <TB2> INFO: run 1 of 2
[08:10:18.961] <TB2> INFO: Test took 14372ms.
[08:10:18.982] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:10:18.982] <TB2> INFO: run 2 of 2
[08:10:34.269] <TB2> INFO: Test took 15287ms.
[08:10:34.293] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:10:34.293] <TB2> INFO: dacScan step from 20 .. 39
[08:10:34.293] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:10:34.293] <TB2> INFO: run 1 of 2
[08:10:49.517] <TB2> INFO: Test took 15224ms.
[08:10:49.542] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:10:49.542] <TB2> INFO: run 2 of 2
[08:11:04.810] <TB2> INFO: Test took 15268ms.
[08:11:04.834] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:11:04.834] <TB2> INFO: dacScan step from 40 .. 59
[08:11:04.834] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:11:04.834] <TB2> INFO: run 1 of 2
[08:11:20.352] <TB2> INFO: Test took 15518ms.
[08:11:20.375] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:11:20.375] <TB2> INFO: run 2 of 2
[08:11:35.796] <TB2> INFO: Test took 15421ms.
[08:11:35.817] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:11:35.817] <TB2> INFO: dacScan step from 60 .. 79
[08:11:35.817] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:11:35.817] <TB2> INFO: run 1 of 2
[08:11:51.015] <TB2> INFO: Test took 15198ms.
[08:11:51.042] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:11:51.042] <TB2> INFO: run 2 of 2
[08:12:06.387] <TB2> INFO: Test took 15345ms.
[08:12:06.412] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:12:06.413] <TB2> INFO: dacScan step from 80 .. 99
[08:12:06.413] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:12:06.413] <TB2> INFO: run 1 of 2
[08:12:22.626] <TB2> INFO: Test took 16213ms.
[08:12:22.687] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:12:22.693] <TB2> INFO: run 2 of 2
[08:12:39.158] <TB2> INFO: Test took 16465ms.
[08:12:39.215] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:12:39.222] <TB2> INFO: dacScan step from 100 .. 119
[08:12:39.222] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:12:39.222] <TB2> INFO: run 1 of 2
[08:12:59.415] <TB2> INFO: Test took 20193ms.
[08:12:59.572] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:12:59.599] <TB2> INFO: run 2 of 2
[08:13:20.544] <TB2> INFO: Test took 20945ms.
[08:13:20.703] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:13:20.729] <TB2> INFO: dacScan step from 120 .. 139
[08:13:20.729] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:13:20.729] <TB2> INFO: run 1 of 2
[08:13:41.562] <TB2> INFO: Test took 20833ms.
[08:13:41.746] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:13:41.789] <TB2> INFO: run 2 of 2
[08:14:04.179] <TB2> INFO: Test took 22390ms.
[08:14:04.364] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:14:04.396] <TB2> INFO: dacScan step from 140 .. 159
[08:14:04.396] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:14:04.396] <TB2> INFO: run 1 of 2
[08:14:26.252] <TB2> INFO: Test took 21856ms.
[08:14:26.421] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:14:26.476] <TB2> INFO: run 2 of 2
[08:14:46.567] <TB2> INFO: Test took 20091ms.
[08:14:46.733] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:14:46.766] <TB2> INFO: dacScan step from 160 .. 179
[08:14:46.766] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:14:46.766] <TB2> INFO: run 1 of 2
[08:15:08.467] <TB2> INFO: Test took 21701ms.
[08:15:08.634] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:15:08.667] <TB2> INFO: run 2 of 2
[08:15:30.635] <TB2> INFO: Test took 21968ms.
[08:15:30.805] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:15:30.835] <TB2> INFO: dacScan step from 180 .. 199
[08:15:30.835] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:15:30.835] <TB2> INFO: run 1 of 2
[08:15:53.179] <TB2> INFO: Test took 22344ms.
[08:15:53.342] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:15:53.371] <TB2> INFO: run 2 of 2
[08:16:15.932] <TB2> INFO: Test took 22561ms.
[08:16:16.121] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:16:43.353] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 161 (20/5) hits flags = 16 (plus default)
[08:16:43.362] <TB2> INFO: dacScan step from 0 .. 19
[08:16:43.362] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:16:43.362] <TB2> INFO: run 1 of 2
[08:16:57.876] <TB2> INFO: Test took 14514ms.
[08:16:57.903] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:16:57.903] <TB2> INFO: run 2 of 2
[08:17:13.711] <TB2> INFO: Test took 15808ms.
[08:17:13.734] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:17:13.734] <TB2> INFO: dacScan step from 20 .. 39
[08:17:13.734] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:17:13.734] <TB2> INFO: run 1 of 2
[08:17:29.486] <TB2> INFO: Test took 15752ms.
[08:17:29.514] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:17:29.514] <TB2> INFO: run 2 of 2
[08:17:45.307] <TB2> INFO: Test took 15793ms.
[08:17:45.329] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:17:45.329] <TB2> INFO: dacScan step from 40 .. 59
[08:17:45.329] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:17:45.329] <TB2> INFO: run 1 of 2
[08:18:01.091] <TB2> INFO: Test took 15762ms.
[08:18:01.114] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:01.114] <TB2> INFO: run 2 of 2
[08:18:16.693] <TB2> INFO: Test took 15579ms.
[08:18:16.715] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:16.715] <TB2> INFO: dacScan step from 60 .. 79
[08:18:16.715] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:18:16.715] <TB2> INFO: run 1 of 2
[08:18:32.778] <TB2> INFO: Test took 16063ms.
[08:18:32.799] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:32.800] <TB2> INFO: run 2 of 2
[08:18:48.916] <TB2> INFO: Test took 16116ms.
[08:18:48.945] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:48.945] <TB2> INFO: dacScan step from 80 .. 99
[08:18:48.945] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:18:48.945] <TB2> INFO: run 1 of 2
[08:19:05.936] <TB2> INFO: Test took 16991ms.
[08:19:06.055] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:06.078] <TB2> INFO: run 2 of 2
[08:19:25.343] <TB2> INFO: Test took 19265ms.
[08:19:25.453] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:25.469] <TB2> INFO: dacScan step from 100 .. 119
[08:19:25.469] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:19:25.469] <TB2> INFO: run 1 of 2
[08:19:47.078] <TB2> INFO: Test took 21609ms.
[08:19:47.257] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:47.288] <TB2> INFO: run 2 of 2
[08:20:07.968] <TB2> INFO: Test took 20680ms.
[08:20:08.155] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:20:08.184] <TB2> INFO: dacScan step from 120 .. 139
[08:20:08.184] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:20:08.184] <TB2> INFO: run 1 of 2
[08:20:28.512] <TB2> INFO: Test took 20328ms.
[08:20:28.697] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:20:28.732] <TB2> INFO: run 2 of 2
[08:20:50.472] <TB2> INFO: Test took 21740ms.
[08:20:50.633] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:20:50.662] <TB2> INFO: dacScan step from 140 .. 159
[08:20:50.662] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:20:50.662] <TB2> INFO: run 1 of 2
[08:21:12.687] <TB2> INFO: Test took 22025ms.
[08:21:12.850] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:21:12.879] <TB2> INFO: run 2 of 2
[08:21:34.836] <TB2> INFO: Test took 21956ms.
[08:21:34.998] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:21:35.034] <TB2> INFO: dacScan step from 160 .. 161
[08:21:35.034] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:21:35.034] <TB2> INFO: run 1 of 2
[08:21:39.591] <TB2> INFO: Test took 4557ms.
[08:21:39.609] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:21:39.615] <TB2> INFO: run 2 of 2
[08:21:44.474] <TB2> INFO: Test took 4859ms.
[08:21:44.498] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:07.187] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 152 (20/5) hits flags = 16 (plus default)
[08:22:07.196] <TB2> INFO: dacScan step from 0 .. 19
[08:22:07.196] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:22:07.196] <TB2> INFO: run 1 of 2
[08:22:22.666] <TB2> INFO: Test took 15470ms.
[08:22:22.693] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:22.693] <TB2> INFO: run 2 of 2
[08:22:38.264] <TB2> INFO: Test took 15571ms.
[08:22:38.286] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:38.286] <TB2> INFO: dacScan step from 20 .. 39
[08:22:38.286] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:22:38.286] <TB2> INFO: run 1 of 2
[08:22:52.745] <TB2> INFO: Test took 14459ms.
[08:22:52.768] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:52.768] <TB2> INFO: run 2 of 2
[08:23:07.276] <TB2> INFO: Test took 14508ms.
[08:23:07.296] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:23:07.296] <TB2> INFO: dacScan step from 40 .. 59
[08:23:07.296] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:23:07.296] <TB2> INFO: run 1 of 2
[08:23:22.832] <TB2> INFO: Test took 15536ms.
[08:23:22.859] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:23:22.859] <TB2> INFO: run 2 of 2
[08:23:38.285] <TB2> INFO: Test took 15425ms.
[08:23:38.312] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:23:38.312] <TB2> INFO: dacScan step from 60 .. 79
[08:23:38.312] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:23:38.312] <TB2> INFO: run 1 of 2
[08:23:52.867] <TB2> INFO: Test took 14555ms.
[08:23:52.889] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:23:52.889] <TB2> INFO: run 2 of 2
[08:24:07.741] <TB2> INFO: Test took 14851ms.
[08:24:07.765] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:24:07.765] <TB2> INFO: dacScan step from 80 .. 99
[08:24:07.766] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:24:07.766] <TB2> INFO: run 1 of 2
[08:24:24.529] <TB2> INFO: Test took 16763ms.
[08:24:24.635] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:24:24.655] <TB2> INFO: run 2 of 2
[08:24:41.907] <TB2> INFO: Test took 17251ms.
[08:24:42.018] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:24:42.042] <TB2> INFO: dacScan step from 100 .. 119
[08:24:42.042] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:24:42.042] <TB2> INFO: run 1 of 2
[08:25:02.119] <TB2> INFO: Test took 20077ms.
[08:25:02.405] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:02.451] <TB2> INFO: run 2 of 2
[08:25:21.488] <TB2> INFO: Test took 19037ms.
[08:25:21.666] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:21.715] <TB2> INFO: dacScan step from 120 .. 139
[08:25:21.715] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:25:21.715] <TB2> INFO: run 1 of 2
[08:25:41.812] <TB2> INFO: Test took 20097ms.
[08:25:41.994] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:42.026] <TB2> INFO: run 2 of 2
[08:26:04.008] <TB2> INFO: Test took 21982ms.
[08:26:04.190] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:04.222] <TB2> INFO: dacScan step from 140 .. 152
[08:26:04.222] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:26:04.222] <TB2> INFO: run 1 of 2
[08:26:18.785] <TB2> INFO: Test took 14563ms.
[08:26:18.900] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:18.923] <TB2> INFO: run 2 of 2
[08:26:33.492] <TB2> INFO: Test took 14569ms.
[08:26:33.604] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:57.218] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 152 (20/5) hits flags = 16 (plus default)
[08:26:57.227] <TB2> INFO: dacScan step from 0 .. 19
[08:26:57.227] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:26:57.227] <TB2> INFO: run 1 of 2
[08:27:12.620] <TB2> INFO: Test took 15393ms.
[08:27:12.644] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:27:12.644] <TB2> INFO: run 2 of 2
[08:27:28.852] <TB2> INFO: Test took 16208ms.
[08:27:28.881] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:27:28.881] <TB2> INFO: dacScan step from 20 .. 39
[08:27:28.881] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:27:28.881] <TB2> INFO: run 1 of 2
[08:27:44.977] <TB2> INFO: Test took 16096ms.
[08:27:45.002] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:27:45.002] <TB2> INFO: run 2 of 2
[08:28:00.421] <TB2> INFO: Test took 15419ms.
[08:28:00.446] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:28:00.446] <TB2> INFO: dacScan step from 40 .. 59
[08:28:00.446] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:28:00.446] <TB2> INFO: run 1 of 2
[08:28:16.437] <TB2> INFO: Test took 15991ms.
[08:28:16.457] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:28:16.457] <TB2> INFO: run 2 of 2
[08:28:30.776] <TB2> INFO: Test took 14319ms.
[08:28:30.798] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:28:30.798] <TB2> INFO: dacScan step from 60 .. 79
[08:28:30.799] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:28:30.799] <TB2> INFO: run 1 of 2
[08:28:45.252] <TB2> INFO: Test took 14453ms.
[08:28:45.276] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:28:45.276] <TB2> INFO: run 2 of 2
[08:29:00.365] <TB2> INFO: Test took 15089ms.
[08:29:00.392] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:29:00.393] <TB2> INFO: dacScan step from 80 .. 99
[08:29:00.393] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:29:00.393] <TB2> INFO: run 1 of 2
[08:29:17.840] <TB2> INFO: Test took 17447ms.
[08:29:17.953] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:29:17.969] <TB2> INFO: run 2 of 2
[08:29:35.214] <TB2> INFO: Test took 17245ms.
[08:29:35.321] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:29:35.337] <TB2> INFO: dacScan step from 100 .. 119
[08:29:35.337] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:29:35.337] <TB2> INFO: run 1 of 2
[08:29:56.138] <TB2> INFO: Test took 20801ms.
[08:29:56.306] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:29:56.336] <TB2> INFO: run 2 of 2
[08:30:17.985] <TB2> INFO: Test took 21649ms.
[08:30:18.165] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:30:18.193] <TB2> INFO: dacScan step from 120 .. 139
[08:30:18.193] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:30:18.193] <TB2> INFO: run 1 of 2
[08:30:37.508] <TB2> INFO: Test took 19315ms.
[08:30:37.668] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:30:37.707] <TB2> INFO: run 2 of 2
[08:30:59.131] <TB2> INFO: Test took 21424ms.
[08:30:59.314] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:30:59.347] <TB2> INFO: dacScan step from 140 .. 152
[08:30:59.347] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:30:59.347] <TB2> INFO: run 1 of 2
[08:31:13.787] <TB2> INFO: Test took 14440ms.
[08:31:13.892] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:31:13.913] <TB2> INFO: run 2 of 2
[08:31:28.061] <TB2> INFO: Test took 14148ms.
[08:31:28.181] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:31:51.580] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 152 (20/5) hits flags = 16 (plus default)
[08:31:51.588] <TB2> INFO: dacScan step from 0 .. 19
[08:31:51.588] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:31:51.588] <TB2> INFO: run 1 of 2
[08:32:06.680] <TB2> INFO: Test took 15092ms.
[08:32:06.709] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:32:06.709] <TB2> INFO: run 2 of 2
[08:32:22.392] <TB2> INFO: Test took 15683ms.
[08:32:22.420] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:32:22.420] <TB2> INFO: dacScan step from 20 .. 39
[08:32:22.420] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:32:22.420] <TB2> INFO: run 1 of 2
[08:32:38.997] <TB2> INFO: Test took 16577ms.
[08:32:39.023] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:32:39.023] <TB2> INFO: run 2 of 2
[08:32:54.528] <TB2> INFO: Test took 15505ms.
[08:32:54.553] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:32:54.553] <TB2> INFO: dacScan step from 40 .. 59
[08:32:54.553] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:32:54.553] <TB2> INFO: run 1 of 2
[08:33:09.637] <TB2> INFO: Test took 15084ms.
[08:33:09.663] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:09.663] <TB2> INFO: run 2 of 2
[08:33:24.510] <TB2> INFO: Test took 14847ms.
[08:33:24.530] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:24.530] <TB2> INFO: dacScan step from 60 .. 79
[08:33:24.530] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:33:24.530] <TB2> INFO: run 1 of 2
[08:33:39.760] <TB2> INFO: Test took 15230ms.
[08:33:39.788] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:39.788] <TB2> INFO: run 2 of 2
[08:33:54.446] <TB2> INFO: Test took 14658ms.
[08:33:54.469] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:54.469] <TB2> INFO: dacScan step from 80 .. 99
[08:33:54.469] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:33:54.469] <TB2> INFO: run 1 of 2
[08:34:11.061] <TB2> INFO: Test took 16592ms.
[08:34:11.178] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:11.195] <TB2> INFO: run 2 of 2
[08:34:29.169] <TB2> INFO: Test took 17974ms.
[08:34:29.271] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:29.286] <TB2> INFO: dacScan step from 100 .. 119
[08:34:29.286] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:34:29.286] <TB2> INFO: run 1 of 2
[08:34:49.489] <TB2> INFO: Test took 20203ms.
[08:34:49.679] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:49.712] <TB2> INFO: run 2 of 2
[08:35:11.053] <TB2> INFO: Test took 21341ms.
[08:35:11.220] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:35:11.250] <TB2> INFO: dacScan step from 120 .. 139
[08:35:11.250] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:35:11.250] <TB2> INFO: run 1 of 2
[08:35:31.982] <TB2> INFO: Test took 20732ms.
[08:35:32.142] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:35:32.173] <TB2> INFO: run 2 of 2
[08:35:51.044] <TB2> INFO: Test took 18871ms.
[08:35:51.203] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:35:51.232] <TB2> INFO: dacScan step from 140 .. 152
[08:35:51.232] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[08:35:51.232] <TB2> INFO: run 1 of 2
[08:36:05.351] <TB2> INFO: Test took 14119ms.
[08:36:05.474] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:05.500] <TB2> INFO: run 2 of 2
[08:36:19.769] <TB2> INFO: Test took 14269ms.
[08:36:19.879] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:43.835] <TB2> INFO: PixTestTrim::trimBitTest() done
[08:36:43.837] <TB2> INFO: PixTestTrim::doTest() done, duration: 3000 seconds
[08:36:44.555] <TB2> INFO: ######################################################################
[08:36:44.555] <TB2> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[08:36:44.555] <TB2> INFO: ######################################################################
[08:36:47.990] <TB2> INFO: Test took 3433ms.
[08:36:48.011] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:51.701] <TB2> INFO: Test took 3494ms.
[08:36:51.768] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:55.301] <TB2> INFO: Test took 3523ms.
[08:36:55.368] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:55.375] <TB2> INFO: The DUT currently contains the following objects:
[08:36:55.375] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:36:55.375] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:36:55.375] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:36:55.375] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:36:55.375] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:55.375] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:55.375] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:55.375] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:55.375] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:55.375] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:55.375] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:55.375] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:55.375] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:55.375] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:55.375] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:55.375] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:55.375] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:55.375] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:55.375] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:55.375] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:56.478] <TB2> INFO: Test took 1103ms.
[08:36:56.481] <TB2> INFO: The DUT currently contains the following objects:
[08:36:56.481] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:36:56.481] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:36:56.481] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:36:56.481] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:36:56.481] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:56.481] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:56.481] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:56.481] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:56.481] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:56.481] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:56.481] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:56.481] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:56.481] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:56.481] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:56.481] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:56.481] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:56.481] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:56.481] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:56.481] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:56.481] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:57.617] <TB2> INFO: Test took 1136ms.
[08:36:57.619] <TB2> INFO: The DUT currently contains the following objects:
[08:36:57.619] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:36:57.619] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:36:57.619] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:36:57.619] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:36:57.619] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:57.619] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:57.619] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:57.619] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:57.619] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:57.619] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:57.619] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:57.620] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:57.620] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:57.620] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:57.620] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:57.620] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:57.620] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:57.620] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:57.620] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:57.620] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:58.744] <TB2> INFO: Test took 1124ms.
[08:36:58.745] <TB2> INFO: The DUT currently contains the following objects:
[08:36:58.745] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:36:58.745] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:36:58.745] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:36:58.745] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:36:58.745] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:58.745] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:58.745] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:58.745] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:58.745] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:58.745] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:58.745] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:58.745] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:58.746] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:58.746] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:58.746] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:58.746] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:58.746] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:58.746] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:58.746] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:58.746] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:59.867] <TB2> INFO: Test took 1121ms.
[08:36:59.869] <TB2> INFO: The DUT currently contains the following objects:
[08:36:59.869] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:36:59.869] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:36:59.869] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:36:59.869] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:36:59.870] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:59.870] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:59.870] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:59.870] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:59.870] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:59.870] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:59.870] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:59.870] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:59.870] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:59.870] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:59.870] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:59.870] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:59.870] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:59.870] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:59.870] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:36:59.870] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:00.974] <TB2> INFO: Test took 1104ms.
[08:37:00.975] <TB2> INFO: The DUT currently contains the following objects:
[08:37:00.975] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:37:00.975] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:37:00.975] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:37:00.975] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:37:00.976] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:00.976] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:00.976] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:00.976] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:00.976] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:00.976] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:00.976] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:00.976] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:00.976] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:00.976] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:00.976] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:00.976] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:00.976] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:00.976] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:00.976] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:00.976] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:02.084] <TB2> INFO: Test took 1108ms.
[08:37:02.085] <TB2> INFO: The DUT currently contains the following objects:
[08:37:02.085] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:37:02.085] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:37:02.085] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:37:02.085] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:37:02.085] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:02.085] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:02.085] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:02.085] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:02.085] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:02.085] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:02.085] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:02.085] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:02.085] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:02.085] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:02.085] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:02.085] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:02.086] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:02.086] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:02.086] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:02.086] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:03.220] <TB2> INFO: Test took 1134ms.
[08:37:03.222] <TB2> INFO: The DUT currently contains the following objects:
[08:37:03.222] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:37:03.222] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:37:03.222] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:37:03.222] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:37:03.222] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:03.222] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:03.222] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:03.222] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:03.222] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:03.222] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:03.222] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:03.222] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:03.222] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:03.222] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:03.222] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:03.222] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:03.222] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:03.222] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:03.222] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:03.222] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:04.357] <TB2> INFO: Test took 1135ms.
[08:37:04.359] <TB2> INFO: The DUT currently contains the following objects:
[08:37:04.359] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:37:04.359] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:37:04.359] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:37:04.359] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:37:04.359] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:04.359] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:04.359] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:04.359] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:04.359] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:04.359] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:04.359] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:04.359] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:04.359] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:04.359] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:04.359] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:04.359] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:04.359] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:04.359] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:04.359] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:04.359] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:05.465] <TB2> INFO: Test took 1106ms.
[08:37:05.466] <TB2> INFO: The DUT currently contains the following objects:
[08:37:05.466] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:37:05.466] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:37:05.466] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:37:05.466] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:37:05.466] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:05.466] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:05.466] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:05.466] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:05.466] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:05.466] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:05.466] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:05.466] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:05.466] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:05.466] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:05.467] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:05.467] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:05.467] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:05.467] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:05.467] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:05.467] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:06.575] <TB2> INFO: Test took 1108ms.
[08:37:06.576] <TB2> INFO: The DUT currently contains the following objects:
[08:37:06.576] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:37:06.576] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:37:06.576] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:37:06.576] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:37:06.576] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:06.576] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:06.576] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:06.576] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:06.576] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:06.576] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:06.576] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:06.576] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:06.576] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:06.576] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:06.577] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:06.577] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:06.577] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:06.577] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:06.577] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:06.577] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:07.684] <TB2> INFO: Test took 1107ms.
[08:37:07.685] <TB2> INFO: The DUT currently contains the following objects:
[08:37:07.685] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:37:07.685] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:37:07.685] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:37:07.685] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:37:07.685] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:07.685] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:07.685] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:07.685] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:07.685] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:07.685] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:07.685] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:07.685] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:07.686] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:07.686] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:07.686] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:07.686] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:07.686] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:07.686] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:07.686] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:07.686] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:08.791] <TB2> INFO: Test took 1105ms.
[08:37:08.792] <TB2> INFO: The DUT currently contains the following objects:
[08:37:08.792] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:37:08.792] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:37:08.792] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:37:08.792] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:37:08.792] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:08.792] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:08.792] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:08.792] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:08.792] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:08.793] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:08.793] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:08.793] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:08.793] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:08.793] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:08.793] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:08.793] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:08.793] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:08.793] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:08.793] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:08.793] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:09.916] <TB2> INFO: Test took 1123ms.
[08:37:09.918] <TB2> INFO: The DUT currently contains the following objects:
[08:37:09.918] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:37:09.918] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:37:09.918] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:37:09.918] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:37:09.918] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:09.918] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:09.918] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:09.918] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:09.918] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:09.918] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:09.918] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:09.918] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:09.918] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:09.918] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:09.918] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:09.918] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:09.918] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:09.919] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:09.919] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:09.919] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:11.027] <TB2> INFO: Test took 1108ms.
[08:37:11.028] <TB2> INFO: The DUT currently contains the following objects:
[08:37:11.028] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:37:11.028] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:37:11.028] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:37:11.028] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:37:11.028] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:11.028] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:11.028] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:11.028] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:11.028] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:11.029] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:11.029] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:11.029] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:11.029] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:11.029] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:11.029] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:11.029] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:11.029] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:11.029] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:11.029] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:11.029] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:12.136] <TB2> INFO: Test took 1107ms.
[08:37:12.137] <TB2> INFO: The DUT currently contains the following objects:
[08:37:12.137] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:37:12.137] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:37:12.137] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:37:12.137] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[08:37:12.138] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:12.138] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:12.138] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:12.138] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:12.138] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:12.138] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:12.138] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:12.138] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:12.138] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:12.138] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:12.138] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:12.138] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:12.138] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:12.138] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:12.138] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:12.138] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[08:37:13.246] <TB2> INFO: Test took 1108ms.
[08:37:13.250] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:41:17.462] <TB2> INFO: Test took 244212ms.
[08:41:19.071] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:45:36.064] <TB2> INFO: Test took 256993ms.
[08:45:37.856] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:45:37.863] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:45:37.870] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:45:37.877] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:45:37.884] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:45:37.890] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:45:37.897] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:45:37.904] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:45:37.911] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:45:37.918] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:45:37.924] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:45:37.931] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:45:37.938] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:45:37.945] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:45:37.951] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:45:37.958] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[08:45:38.018] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:45:38.030] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:45:38.030] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:45:38.030] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:45:38.040] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:45:38.040] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:45:38.041] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:45:38.041] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:45:38.051] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:45:38.051] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:45:38.051] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:45:38.051] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:45:38.052] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:45:38.052] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:45:38.052] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:45:38.058] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:45:41.545] <TB2> INFO: Test took 3483ms.
[08:45:45.518] <TB2> INFO: Test took 3684ms.
[08:45:49.521] <TB2> INFO: Test took 3733ms.
[08:45:49.795] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:45:50.712] <TB2> INFO: Test took 917ms.
[08:45:50.715] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:45:51.823] <TB2> INFO: Test took 1108ms.
[08:45:51.826] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:45:52.932] <TB2> INFO: Test took 1106ms.
[08:45:52.936] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:45:54.042] <TB2> INFO: Test took 1106ms.
[08:45:54.045] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:45:55.150] <TB2> INFO: Test took 1105ms.
[08:45:55.154] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:45:56.261] <TB2> INFO: Test took 1108ms.
[08:45:56.264] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:45:57.386] <TB2> INFO: Test took 1122ms.
[08:45:57.390] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:45:58.497] <TB2> INFO: Test took 1107ms.
[08:45:58.500] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:45:59.606] <TB2> INFO: Test took 1106ms.
[08:45:59.609] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:00.730] <TB2> INFO: Test took 1121ms.
[08:46:00.733] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:01.840] <TB2> INFO: Test took 1107ms.
[08:46:01.843] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:02.965] <TB2> INFO: Test took 1122ms.
[08:46:02.969] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:04.076] <TB2> INFO: Test took 1108ms.
[08:46:04.079] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:05.201] <TB2> INFO: Test took 1122ms.
[08:46:05.205] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:06.312] <TB2> INFO: Test took 1108ms.
[08:46:06.315] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:07.419] <TB2> INFO: Test took 1104ms.
[08:46:07.421] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:08.529] <TB2> INFO: Test took 1108ms.
[08:46:08.532] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:09.641] <TB2> INFO: Test took 1109ms.
[08:46:09.644] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:10.751] <TB2> INFO: Test took 1107ms.
[08:46:10.755] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:11.856] <TB2> INFO: Test took 1101ms.
[08:46:11.858] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:12.966] <TB2> INFO: Test took 1108ms.
[08:46:12.970] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:14.079] <TB2> INFO: Test took 1109ms.
[08:46:14.082] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:15.204] <TB2> INFO: Test took 1122ms.
[08:46:15.208] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:16.351] <TB2> INFO: Test took 1143ms.
[08:46:16.354] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:17.463] <TB2> INFO: Test took 1109ms.
[08:46:17.467] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:18.574] <TB2> INFO: Test took 1108ms.
[08:46:18.579] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:19.685] <TB2> INFO: Test took 1106ms.
[08:46:19.689] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:20.790] <TB2> INFO: Test took 1101ms.
[08:46:20.792] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:21.898] <TB2> INFO: Test took 1106ms.
[08:46:21.902] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:23.008] <TB2> INFO: Test took 1107ms.
[08:46:23.012] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:24.118] <TB2> INFO: Test took 1106ms.
[08:46:24.121] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:46:25.237] <TB2> INFO: Test took 1116ms.
[08:46:25.758] <TB2> INFO: PixTestPhOptimization::doTest() done, duration: 581 seconds
[08:46:25.758] <TB2> INFO: PH scale (per ROC): 76 82 97 77 86 80 78 82 77 83 84 83 76 92 81 86
[08:46:25.758] <TB2> INFO: PH offset (per ROC): 160 161 151 164 166 155 155 138 162 154 160 163 157 141 150 147
[08:46:26.005] <TB2> INFO: ######################################################################
[08:46:26.005] <TB2> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[08:46:26.005] <TB2> INFO: ######################################################################
[08:46:26.428] <TB2> INFO: scanning low vcal = 10
[08:46:30.406] <TB2> INFO: Test took 3978ms.
[08:46:30.411] <TB2> INFO: scanning low vcal = 20
[08:46:34.289] <TB2> INFO: Test took 3878ms.
[08:46:34.294] <TB2> INFO: scanning low vcal = 30
[08:46:38.211] <TB2> INFO: Test took 3917ms.
[08:46:38.220] <TB2> INFO: scanning low vcal = 40
[08:46:42.940] <TB2> INFO: Test took 4720ms.
[08:46:42.997] <TB2> INFO: scanning low vcal = 50
[08:46:47.478] <TB2> INFO: Test took 4481ms.
[08:46:47.534] <TB2> INFO: scanning low vcal = 60
[08:46:51.768] <TB2> INFO: Test took 4234ms.
[08:46:51.835] <TB2> INFO: scanning low vcal = 70
[08:46:56.269] <TB2> INFO: Test took 4434ms.
[08:46:56.328] <TB2> INFO: scanning low vcal = 80
[08:47:00.724] <TB2> INFO: Test took 4396ms.
[08:47:00.779] <TB2> INFO: scanning low vcal = 90
[08:47:04.991] <TB2> INFO: Test took 4212ms.
[08:47:05.050] <TB2> INFO: scanning low vcal = 100
[08:47:09.453] <TB2> INFO: Test took 4403ms.
[08:47:09.515] <TB2> INFO: scanning low vcal = 110
[08:47:13.994] <TB2> INFO: Test took 4479ms.
[08:47:14.062] <TB2> INFO: scanning low vcal = 120
[08:47:18.249] <TB2> INFO: Test took 4187ms.
[08:47:18.304] <TB2> INFO: scanning low vcal = 130
[08:47:22.846] <TB2> INFO: Test took 4542ms.
[08:47:22.907] <TB2> INFO: scanning low vcal = 140
[08:47:27.509] <TB2> INFO: Test took 4602ms.
[08:47:27.568] <TB2> INFO: scanning low vcal = 150
[08:47:32.025] <TB2> INFO: Test took 4457ms.
[08:47:32.088] <TB2> INFO: scanning low vcal = 160
[08:47:36.500] <TB2> INFO: Test took 4412ms.
[08:47:36.558] <TB2> INFO: scanning low vcal = 170
[08:47:41.232] <TB2> INFO: Test took 4674ms.
[08:47:41.302] <TB2> INFO: scanning low vcal = 180
[08:47:45.825] <TB2> INFO: Test took 4523ms.
[08:47:45.886] <TB2> INFO: scanning low vcal = 190
[08:47:50.383] <TB2> INFO: Test took 4497ms.
[08:47:50.459] <TB2> INFO: scanning low vcal = 200
[08:47:55.110] <TB2> INFO: Test took 4651ms.
[08:47:55.168] <TB2> INFO: scanning low vcal = 210
[08:47:59.852] <TB2> INFO: Test took 4684ms.
[08:47:59.921] <TB2> INFO: scanning low vcal = 220
[08:48:04.327] <TB2> INFO: Test took 4406ms.
[08:48:04.397] <TB2> INFO: scanning low vcal = 230
[08:48:08.910] <TB2> INFO: Test took 4513ms.
[08:48:08.977] <TB2> INFO: scanning low vcal = 240
[08:48:13.590] <TB2> INFO: Test took 4613ms.
[08:48:13.648] <TB2> INFO: scanning low vcal = 250
[08:48:18.069] <TB2> INFO: Test took 4421ms.
[08:48:18.128] <TB2> INFO: scanning high vcal = 30 (= 210 in low range)
[08:48:22.758] <TB2> INFO: Test took 4630ms.
[08:48:22.823] <TB2> INFO: scanning high vcal = 50 (= 350 in low range)
[08:48:27.465] <TB2> INFO: Test took 4642ms.
[08:48:27.526] <TB2> INFO: scanning high vcal = 70 (= 490 in low range)
[08:48:31.794] <TB2> INFO: Test took 4268ms.
[08:48:31.863] <TB2> INFO: scanning high vcal = 90 (= 630 in low range)
[08:48:36.294] <TB2> INFO: Test took 4431ms.
[08:48:36.357] <TB2> INFO: scanning high vcal = 200 (= 1400 in low range)
[08:48:40.682] <TB2> INFO: Test took 4325ms.
[08:48:41.241] <TB2> INFO: PixTestGainPedestal::measure() done
[08:49:13.400] <TB2> INFO: PixTestGainPedestal::fit() done
[08:49:13.400] <TB2> INFO: non-linearity mean: 0.959 0.955 0.956 0.962 0.956 0.959 0.956 0.952 0.955 0.953 0.958 0.950 0.955 0.957 0.954 0.956
[08:49:13.400] <TB2> INFO: non-linearity RMS: 0.005 0.006 0.005 0.005 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.006
[08:49:13.401] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[08:49:13.421] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[08:49:13.441] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[08:49:13.461] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[08:49:13.481] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[08:49:13.501] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[08:49:13.533] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[08:49:13.553] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[08:49:13.573] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[08:49:13.592] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[08:49:13.612] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[08:49:13.632] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[08:49:13.652] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[08:49:13.672] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[08:49:13.691] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[08:49:13.711] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[08:49:13.730] <TB2> INFO: PixTestGainPedestal::doTest() done, duration: 167 seconds
[08:49:13.737] <TB2> INFO: readReadbackCal: /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C15.dat
[08:49:13.774] <TB2> INFO: PixTestReadback::doTest() start.
[08:49:13.775] <TB2> INFO: PixTestReadback::RES sent once
[08:49:30.284] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C0.dat
[08:49:30.284] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C1.dat
[08:49:30.285] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C2.dat
[08:49:30.285] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C3.dat
[08:49:30.285] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C4.dat
[08:49:30.285] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C5.dat
[08:49:30.285] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C6.dat
[08:49:30.285] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C7.dat
[08:49:30.285] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C8.dat
[08:49:30.285] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C9.dat
[08:49:30.285] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C10.dat
[08:49:30.285] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C11.dat
[08:49:30.285] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C12.dat
[08:49:30.285] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C13.dat
[08:49:30.285] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C14.dat
[08:49:30.286] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C15.dat
[08:49:30.316] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[08:49:30.317] <TB2> INFO: PixTestReadback::RES sent once
[08:49:46.738] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C0.dat
[08:49:46.738] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C1.dat
[08:49:46.738] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C2.dat
[08:49:46.738] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C3.dat
[08:49:46.738] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C4.dat
[08:49:46.738] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C5.dat
[08:49:46.738] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C6.dat
[08:49:46.738] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C7.dat
[08:49:46.739] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C8.dat
[08:49:46.739] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C9.dat
[08:49:46.739] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C10.dat
[08:49:46.739] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C11.dat
[08:49:46.739] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C12.dat
[08:49:46.739] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C13.dat
[08:49:46.739] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C14.dat
[08:49:46.739] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C15.dat
[08:49:46.772] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[08:49:46.773] <TB2> INFO: PixTestReadback::RES sent once
[08:49:59.498] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[08:49:59.498] <TB2> INFO: Vbg will be calibrated using Vd calibration
[08:49:59.498] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 154.5calibrated Vbg = 1.19709 :::*/*/*/*/
[08:49:59.499] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 152.1calibrated Vbg = 1.18372 :::*/*/*/*/
[08:49:59.499] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 155.7calibrated Vbg = 1.19656 :::*/*/*/*/
[08:49:59.499] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 254.6calibrated Vbg = 1.16384 :::*/*/*/*/
[08:49:59.499] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 144.4calibrated Vbg = 1.20638 :::*/*/*/*/
[08:49:59.499] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 148calibrated Vbg = 1.21082 :::*/*/*/*/
[08:49:59.499] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 150.3calibrated Vbg = 1.20823 :::*/*/*/*/
[08:49:59.499] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 255calibrated Vbg = 1.1675 :::*/*/*/*/
[08:49:59.499] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 167.2calibrated Vbg = 1.20693 :::*/*/*/*/
[08:49:59.499] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 151.4calibrated Vbg = 1.21282 :::*/*/*/*/
[08:49:59.499] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 153.3calibrated Vbg = 1.20943 :::*/*/*/*/
[08:49:59.499] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 255calibrated Vbg = 1.1675 :::*/*/*/*/
[08:49:59.499] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 161.2calibrated Vbg = 1.19276 :::*/*/*/*/
[08:49:59.499] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 145.1calibrated Vbg = 1.18933 :::*/*/*/*/
[08:49:59.499] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 153.6calibrated Vbg = 1.19062 :::*/*/*/*/
[08:49:59.499] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 255calibrated Vbg = 1.1675 :::*/*/*/*/
[08:49:59.502] <TB2> INFO: PixTestReadback::RES sent once
[08:53:54.158] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C0.dat
[08:53:54.158] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C1.dat
[08:53:54.158] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C2.dat
[08:53:54.158] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C3.dat
[08:53:54.158] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C4.dat
[08:53:54.158] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C5.dat
[08:53:54.158] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C6.dat
[08:53:54.158] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C7.dat
[08:53:54.158] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C8.dat
[08:53:54.158] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C9.dat
[08:53:54.158] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C10.dat
[08:53:54.158] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C11.dat
[08:53:54.159] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C12.dat
[08:53:54.159] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C13.dat
[08:53:54.159] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C14.dat
[08:53:54.159] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2082_FullQualification_2015-08-24_09h18m_1440400713//000_FulltestPxar_m20//readbackCal_C15.dat
[08:53:54.188] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[08:53:54.189] <TB2> INFO: PixTestReadback::doTest() done
[08:53:54.202] <TB2> INFO: enter test to run
[08:53:54.202] <TB2> INFO: test: exit no parameter change
[08:53:54.709] <TB2> QUIET: Connection to board 156 closed.
[08:53:54.788] <TB2> INFO: pXar: this is the end, my friend