[11:33:32.849] <TB2> INFO: *** Welcome to pxar ***
[11:33:32.849] <TB2> INFO: *** Today: 2015/08/25
[11:33:32.849] <TB2> INFO: readRocDacs: /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters_C15.dat
[11:33:32.850] <TB2> INFO: readTbmDacs: /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//tbmParameters_C0b.dat
[11:33:32.850] <TB2> INFO: readMaskFile: /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//defaultMaskFile.dat
[11:33:32.850] <TB2> INFO: readTrimFile: /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//trimParameters_C15.dat
[11:33:32.919] <TB2> INFO: clk: 4
[11:33:32.919] <TB2> INFO: ctr: 4
[11:33:32.919] <TB2> INFO: sda: 19
[11:33:32.919] <TB2> INFO: tin: 9
[11:33:32.919] <TB2> INFO: level: 15
[11:33:32.919] <TB2> INFO: triggerdelay: 0
[11:33:32.919] <TB2> QUIET: Instanciating API for pxar prod-01+86~g1838649
[11:33:32.919] <TB2> INFO: Log level: INFO
[11:33:32.927] <TB2> INFO: Found DTB DTB_WXC55Z
[11:33:32.936] <TB2> QUIET: Connection to board DTB_WXC55Z opened.
[11:33:32.939] <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:
------------------------------------------------------
[11:33:32.942] <TB2> INFO: RPC call hashes of host and DTB match: 447413373
[11:33:34.493] <TB2> INFO: DUT info:
[11:33:34.493] <TB2> INFO: The DUT currently contains the following objects:
[11:33:34.493] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[11:33:34.493] <TB2> INFO: TBM Core alpha (0): 7 registers set
[11:33:34.493] <TB2> INFO: TBM Core beta (1): 7 registers set
[11:33:34.493] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:33:34.494] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:33:34.494] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:33:34.494] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:33:34.494] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:33:34.494] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:33:34.494] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:33:34.494] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:33:34.494] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:33:34.494] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:33:34.494] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:33:34.494] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:33:34.494] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:33:34.494] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:33:34.494] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:33:34.494] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:33:34.494] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:33:34.896] <TB2> INFO: enter 'restricted' command line mode
[11:33:34.896] <TB2> INFO: enter test to run
[11:33:34.896] <TB2> INFO: test: pretest no parameter change
[11:33:34.896] <TB2> INFO: running: pretest
[11:33:34.903] <TB2> INFO: ######################################################################
[11:33:34.903] <TB2> INFO: PixTestPretest::doTest()
[11:33:34.903] <TB2> INFO: ######################################################################
[11:33:34.905] <TB2> INFO: ----------------------------------------------------------------------
[11:33:34.905] <TB2> INFO: PixTestPretest::programROC()
[11:33:34.905] <TB2> INFO: ----------------------------------------------------------------------
[11:33:52.922] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[11:33:52.922] <TB2> INFO: IA differences per ROC: 19.3 19.3 18.5 18.5 18.5 19.3 18.5 20.9 20.9 20.1 17.7 19.3 22.5 18.5 19.3 18.5
[11:33:52.995] <TB2> INFO: ----------------------------------------------------------------------
[11:33:52.995] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[11:33:52.995] <TB2> INFO: ----------------------------------------------------------------------
[11:34:12.550] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 373.8 mA = 23.3625 mA/ROC
[11:34:12.554] <TB2> INFO: ----------------------------------------------------------------------
[11:34:12.554] <TB2> INFO: PixTestPretest::findWorkingPixel()
[11:34:12.554] <TB2> INFO: ----------------------------------------------------------------------
[11:34:20.927] <TB2> INFO: Test took 8366ms.
[11:34:21.224] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[11:34:21.263] <TB2> INFO: ----------------------------------------------------------------------
[11:34:21.263] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[11:34:21.263] <TB2> INFO: ----------------------------------------------------------------------
[11:34:30.253] <TB2> INFO: Test took 8983ms.
[11:34:30.569] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[11:34:30.569] <TB2> INFO: CalDel: 137 141 178 154 128 136 125 159 118 144 136 144 128 121 150 134
[11:34:30.569] <TB2> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[11:34:30.574] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters_C0.dat
[11:34:30.575] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters_C1.dat
[11:34:30.575] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters_C2.dat
[11:34:30.575] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters_C3.dat
[11:34:30.575] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters_C4.dat
[11:34:30.576] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters_C5.dat
[11:34:30.576] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters_C6.dat
[11:34:30.576] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters_C7.dat
[11:34:30.576] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters_C8.dat
[11:34:30.577] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters_C9.dat
[11:34:30.577] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters_C10.dat
[11:34:30.577] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters_C11.dat
[11:34:30.577] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters_C12.dat
[11:34:30.578] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters_C13.dat
[11:34:30.578] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters_C14.dat
[11:34:30.578] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters_C15.dat
[11:34:30.578] <TB2> INFO: write tbm parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//tbmParameters_C0a.dat
[11:34:30.578] <TB2> INFO: write tbm parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//tbmParameters_C0b.dat
[11:34:30.579] <TB2> INFO: PixTestPretest::doTest() done, duration: 55 seconds
[11:34:30.672] <TB2> INFO: enter test to run
[11:34:30.672] <TB2> INFO: test: fulltest no parameter change
[11:34:30.672] <TB2> INFO: running: fulltest
[11:34:30.672] <TB2> INFO: ######################################################################
[11:34:30.672] <TB2> INFO: PixTestFullTest::doTest()
[11:34:30.672] <TB2> INFO: ######################################################################
[11:34:30.673] <TB2> INFO: ######################################################################
[11:34:30.673] <TB2> INFO: PixTestAlive::doTest()
[11:34:30.673] <TB2> INFO: ######################################################################
[11:34:30.675] <TB2> INFO: ----------------------------------------------------------------------
[11:34:30.675] <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)
[11:34:30.675] <TB2> INFO: ----------------------------------------------------------------------
[11:34:34.103] <TB2> INFO: Test took 3427ms.
[11:34:34.128] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:34:34.356] <TB2> INFO: PixTestAlive::aliveTest() done
[11:34:34.356] <TB2> INFO: number of dead pixels (per ROC): 4 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:34:34.359] <TB2> INFO: ----------------------------------------------------------------------
[11:34:34.359] <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)
[11:34:34.359] <TB2> INFO: ----------------------------------------------------------------------
[11:34:37.114] <TB2> INFO: Test took 2752ms.
[11:34:37.117] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:34:37.118] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[11:34:37.349] <TB2> INFO: PixTestAlive::maskTest() done
[11:34:37.349] <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
[11:34:37.350] <TB2> INFO: ----------------------------------------------------------------------
[11:34:37.350] <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)
[11:34:37.351] <TB2> INFO: ----------------------------------------------------------------------
[11:34:40.801] <TB2> INFO: Test took 3449ms.
[11:34:40.826] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:34:41.053] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[11:34:41.053] <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
[11:34:41.053] <TB2> INFO: PixTestAlive::doTest() done, duration: 10 seconds
[11:34:41.067] <TB2> INFO: ######################################################################
[11:34:41.067] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[11:34:41.067] <TB2> INFO: ######################################################################
[11:34:41.070] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30/5) hits flags = 2 (plus default)
[11:34:41.084] <TB2> INFO: dacScan step from 0 .. 29
[11:34:41.084] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:34:41.084] <TB2> INFO: run 1 of 1
[11:35:02.654] <TB2> INFO: Test took 21570ms.
[11:35:02.687] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:35:02.687] <TB2> INFO: dacScan step from 30 .. 59
[11:35:02.687] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:35:02.687] <TB2> INFO: run 1 of 1
[11:35:28.505] <TB2> INFO: Test took 25818ms.
[11:35:28.638] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:35:28.656] <TB2> INFO: dacScan step from 60 .. 89
[11:35:28.656] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:35:28.656] <TB2> INFO: run 1 of 1
[11:36:00.708] <TB2> INFO: Test took 32052ms.
[11:36:00.968] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:36:01.013] <TB2> INFO: dacScan step from 90 .. 119
[11:36:01.013] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:36:01.013] <TB2> INFO: run 1 of 1
[11:36:30.944] <TB2> INFO: Test took 29931ms.
[11:36:31.188] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:36:31.233] <TB2> INFO: dacScan step from 120 .. 149
[11:36:31.233] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:36:31.233] <TB2> INFO: run 1 of 1
[11:36:54.778] <TB2> INFO: Test took 23545ms.
[11:36:54.987] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:37:18.735] <TB2> INFO: PixTestBBMap::doTest() done, duration: 157 seconds
[11:37:18.735] <TB2> INFO: number of dead bumps (per ROC): 6 0 1 0 0 0 0 0 1 0 0 0 0 1 0 26
[11:37:18.735] <TB2> INFO: separation cut (per ROC): 94 79 94 87 93 80 89 77 91 70 78 67 87 69 83 70
[11:37:18.811] <TB2> INFO: ######################################################################
[11:37:18.811] <TB2> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = 4, ntrig/step = 50
[11:37:18.811] <TB2> INFO: ######################################################################
[11:37:18.811] <TB2> INFO: ----------------------------------------------------------------------
[11:37:18.811] <TB2> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = 4, ntrig/step = 50
[11:37:18.811] <TB2> INFO: ----------------------------------------------------------------------
[11:37:18.811] <TB2> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (4/50) hits flags = 16 (plus default)
[11:37:18.820] <TB2> INFO: dacScan step from 0 .. 3
[11:37:18.820] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:37:18.820] <TB2> INFO: run 1 of 1
[11:37:38.794] <TB2> INFO: Test took 19974ms.
[11:37:38.827] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:37:38.827] <TB2> INFO: dacScan step from 4 .. 7
[11:37:38.827] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:37:38.827] <TB2> INFO: run 1 of 1
[11:37:58.985] <TB2> INFO: Test took 20158ms.
[11:37:59.017] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:37:59.017] <TB2> INFO: dacScan step from 8 .. 11
[11:37:59.017] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:37:59.017] <TB2> INFO: run 1 of 1
[11:38:18.858] <TB2> INFO: Test took 19841ms.
[11:38:18.888] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:18.888] <TB2> INFO: dacScan step from 12 .. 15
[11:38:18.888] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:38:18.888] <TB2> INFO: run 1 of 1
[11:38:39.054] <TB2> INFO: Test took 20165ms.
[11:38:39.080] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:39.080] <TB2> INFO: dacScan step from 16 .. 19
[11:38:39.080] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:38:39.080] <TB2> INFO: run 1 of 1
[11:38:59.328] <TB2> INFO: Test took 20248ms.
[11:38:59.359] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:59.359] <TB2> INFO: dacScan step from 20 .. 23
[11:38:59.359] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:38:59.359] <TB2> INFO: run 1 of 1
[11:39:19.606] <TB2> INFO: Test took 20247ms.
[11:39:19.639] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:19.639] <TB2> INFO: dacScan step from 24 .. 27
[11:39:19.639] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:39:19.639] <TB2> INFO: run 1 of 1
[11:39:39.946] <TB2> INFO: Test took 20307ms.
[11:39:39.973] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:39.973] <TB2> INFO: dacScan step from 28 .. 31
[11:39:39.973] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:39:39.973] <TB2> INFO: run 1 of 1
[11:40:00.142] <TB2> INFO: Test took 20169ms.
[11:40:00.169] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:40:00.169] <TB2> INFO: dacScan step from 32 .. 35
[11:40:00.169] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:40:00.169] <TB2> INFO: run 1 of 1
[11:40:20.303] <TB2> INFO: Test took 20134ms.
[11:40:20.329] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:40:20.329] <TB2> INFO: dacScan step from 36 .. 39
[11:40:20.329] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:40:20.329] <TB2> INFO: run 1 of 1
[11:40:40.749] <TB2> INFO: Test took 20419ms.
[11:40:40.781] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:40:40.781] <TB2> INFO: dacScan step from 40 .. 43
[11:40:40.781] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:40:40.781] <TB2> INFO: run 1 of 1
[11:41:00.945] <TB2> INFO: Test took 20164ms.
[11:41:00.978] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:41:00.978] <TB2> INFO: dacScan step from 44 .. 47
[11:41:00.978] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:41:00.978] <TB2> INFO: run 1 of 1
[11:41:21.026] <TB2> INFO: Test took 20047ms.
[11:41:21.059] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:41:21.059] <TB2> INFO: dacScan step from 48 .. 51
[11:41:21.059] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:41:21.059] <TB2> INFO: run 1 of 1
[11:41:41.293] <TB2> INFO: Test took 20234ms.
[11:41:41.325] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:41:41.326] <TB2> INFO: dacScan step from 52 .. 55
[11:41:41.326] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:41:41.326] <TB2> INFO: run 1 of 1
[11:42:01.757] <TB2> INFO: Test took 20431ms.
[11:42:01.791] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:01.792] <TB2> INFO: dacScan step from 56 .. 59
[11:42:01.792] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:42:01.792] <TB2> INFO: run 1 of 1
[11:42:22.053] <TB2> INFO: Test took 20261ms.
[11:42:22.088] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:22.088] <TB2> INFO: dacScan step from 60 .. 63
[11:42:22.088] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:42:22.088] <TB2> INFO: run 1 of 1
[11:42:43.234] <TB2> INFO: Test took 21146ms.
[11:42:43.284] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:43.285] <TB2> INFO: dacScan step from 64 .. 67
[11:42:43.285] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:42:43.285] <TB2> INFO: run 1 of 1
[11:43:05.182] <TB2> INFO: Test took 21897ms.
[11:43:05.248] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:43:05.250] <TB2> INFO: dacScan step from 68 .. 71
[11:43:05.250] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:43:05.250] <TB2> INFO: run 1 of 1
[11:43:28.135] <TB2> INFO: Test took 22885ms.
[11:43:28.212] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:43:28.215] <TB2> INFO: dacScan step from 72 .. 75
[11:43:28.215] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:43:28.215] <TB2> INFO: run 1 of 1
[11:43:52.212] <TB2> INFO: Test took 23997ms.
[11:43:52.308] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:43:52.311] <TB2> INFO: dacScan step from 76 .. 79
[11:43:52.311] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:43:52.311] <TB2> INFO: run 1 of 1
[11:44:16.914] <TB2> INFO: Test took 24602ms.
[11:44:17.032] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:44:17.037] <TB2> INFO: dacScan step from 80 .. 83
[11:44:17.037] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:44:17.037] <TB2> INFO: run 1 of 1
[11:44:44.003] <TB2> INFO: Test took 26966ms.
[11:44:44.164] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:44:44.172] <TB2> INFO: dacScan step from 84 .. 87
[11:44:44.172] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:44:44.172] <TB2> INFO: run 1 of 1
[11:45:12.759] <TB2> INFO: Test took 28587ms.
[11:45:12.951] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:45:12.960] <TB2> INFO: dacScan step from 88 .. 91
[11:45:12.960] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:45:12.960] <TB2> INFO: run 1 of 1
[11:45:43.706] <TB2> INFO: Test took 30746ms.
[11:45:43.949] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:45:43.960] <TB2> INFO: dacScan step from 92 .. 95
[11:45:43.960] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:45:43.960] <TB2> INFO: run 1 of 1
[11:46:14.509] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[11:46:14.509] <TB2> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[11:46:15.605] <TB2> INFO: Test took 31645ms.
[11:46:15.833] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:15.843] <TB2> INFO: dacScan step from 96 .. 99
[11:46:15.843] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:46:15.843] <TB2> INFO: run 1 of 1
[11:46:46.657] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[11:46:46.657] <TB2> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[11:46:48.087] <TB2> INFO: Test took 32244ms.
[11:46:48.333] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:48.343] <TB2> INFO: dacScan step from 100 .. 103
[11:46:48.343] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:46:48.343] <TB2> INFO: run 1 of 1
[11:47:18.943] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[11:47:18.943] <TB2> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[11:47:20.422] <TB2> INFO: Test took 32079ms.
[11:47:20.663] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:20.672] <TB2> INFO: dacScan step from 104 .. 107
[11:47:20.672] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:47:20.672] <TB2> INFO: run 1 of 1
[11:47:51.743] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (2) != Token Chain Length (4)
[11:47:51.743] <TB2> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[11:47:53.243] <TB2> INFO: Test took 32571ms.
[11:47:53.483] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:53.493] <TB2> INFO: dacScan step from 108 .. 111
[11:47:53.493] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:47:53.493] <TB2> INFO: run 1 of 1
[11:48:23.961] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (8) != Token Chain Length (4)
[11:48:23.962] <TB2> ERROR: <datapipe.cc/CheckEventID:L453> Channel 3 Event ID mismatch: local ID (198) != TBM ID (199)
[11:48:23.962] <TB2> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!
[11:48:23.962] <TB2> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!
[11:48:23.962] <TB2> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!
[11:48:25.406] <TB2> INFO: Test took 31913ms.
[11:48:25.652] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:48:25.661] <TB2> INFO: dacScan step from 112 .. 115
[11:48:25.661] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:48:25.661] <TB2> INFO: run 1 of 1
[11:48:57.815] <TB2> INFO: Test took 32154ms.
[11:48:58.047] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:48:58.057] <TB2> INFO: dacScan step from 116 .. 119
[11:48:58.057] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:48:58.057] <TB2> INFO: run 1 of 1
[11:49:30.290] <TB2> INFO: Test took 32233ms.
[11:49:30.518] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:49:30.528] <TB2> INFO: dacScan step from 120 .. 123
[11:49:30.528] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:49:30.528] <TB2> INFO: run 1 of 1
[11:50:03.193] <TB2> INFO: Test took 32665ms.
[11:50:03.440] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:50:03.450] <TB2> INFO: dacScan step from 124 .. 127
[11:50:03.450] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:50:03.450] <TB2> INFO: run 1 of 1
[11:50:36.022] <TB2> INFO: Test took 32572ms.
[11:50:36.268] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:50:36.277] <TB2> INFO: dacScan step from 128 .. 131
[11:50:36.277] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:50:36.277] <TB2> INFO: run 1 of 1
[11:51:08.497] <TB2> INFO: Test took 32220ms.
[11:51:08.723] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:51:08.732] <TB2> INFO: dacScan step from 132 .. 135
[11:51:08.732] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:51:08.732] <TB2> INFO: run 1 of 1
[11:51:41.053] <TB2> INFO: Test took 32321ms.
[11:51:41.301] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:51:41.312] <TB2> INFO: dacScan step from 136 .. 139
[11:51:41.312] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:51:41.312] <TB2> INFO: run 1 of 1
[11:52:14.010] <TB2> INFO: Test took 32698ms.
[11:52:14.253] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:52:14.262] <TB2> INFO: dacScan step from 140 .. 143
[11:52:14.262] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:52:14.262] <TB2> INFO: run 1 of 1
[11:52:44.122] <TB2> INFO: Test took 29860ms.
[11:52:44.365] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:52:44.375] <TB2> INFO: dacScan step from 144 .. 147
[11:52:44.375] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:52:44.375] <TB2> INFO: run 1 of 1
[11:53:11.222] <TB2> INFO: Test took 26847ms.
[11:53:11.456] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:53:11.471] <TB2> INFO: dacScan step from 148 .. 149
[11:53:11.471] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:53:11.471] <TB2> INFO: run 1 of 1
[11:53:28.549] <TB2> INFO: Test took 17078ms.
[11:53:28.665] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:53:28.671] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[11:53:30.137] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[11:53:31.527] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[11:53:32.905] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[11:53:34.286] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[11:53:35.728] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[11:53:37.182] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[11:53:38.645] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[11:53:40.145] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[11:53:41.575] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[11:53:43.256] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[11:53:44.779] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[11:53:46.300] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[11:53:47.764] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[11:53:49.245] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[11:53:50.637] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[11:53:52.052] <TB2> INFO: PixTestScurves::scurves() done
[11:53:52.052] <TB2> INFO: Vcal mean: 97.53 85.84 97.59 86.55 86.67 86.51 87.83 74.87 88.40 73.66 78.17 63.08 81.95 68.36 80.41 85.51
[11:53:52.052] <TB2> INFO: Vcal RMS: 6.33 5.81 6.19 5.13 5.66 5.12 5.82 4.85 5.71 5.20 4.04 4.59 5.38 5.09 4.90 5.53
[11:53:52.052] <TB2> INFO: PixTestScurves::fullTest() done, duration: 993 seconds
[11:53:52.123] <TB2> INFO: ######################################################################
[11:53:52.123] <TB2> INFO: PixTestTrim::doTest()
[11:53:52.123] <TB2> INFO: ######################################################################
[11:53:52.125] <TB2> INFO: ----------------------------------------------------------------------
[11:53:52.125] <TB2> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[11:53:52.125] <TB2> INFO: ----------------------------------------------------------------------
[11:53:52.208] <TB2> INFO: ---> VthrComp thr map (minimal VthrComp)
[11:53:52.208] <TB2> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[11:53:52.217] <TB2> INFO: dacScan step from 0 .. 19
[11:53:52.217] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:53:52.217] <TB2> INFO: run 1 of 1
[11:54:06.925] <TB2> INFO: Test took 14708ms.
[11:54:06.952] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:54:06.952] <TB2> INFO: dacScan step from 20 .. 39
[11:54:06.952] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:54:06.952] <TB2> INFO: run 1 of 1
[11:54:21.386] <TB2> INFO: Test took 14434ms.
[11:54:21.407] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:54:21.407] <TB2> INFO: dacScan step from 40 .. 59
[11:54:21.407] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:54:21.407] <TB2> INFO: run 1 of 1
[11:54:35.836] <TB2> INFO: Test took 14429ms.
[11:54:35.860] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:54:35.860] <TB2> INFO: dacScan step from 60 .. 79
[11:54:35.860] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:54:35.860] <TB2> INFO: run 1 of 1
[11:54:50.735] <TB2> INFO: Test took 14875ms.
[11:54:50.766] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:54:50.767] <TB2> INFO: dacScan step from 80 .. 99
[11:54:50.767] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:54:50.767] <TB2> INFO: run 1 of 1
[11:55:07.930] <TB2> INFO: Test took 17163ms.
[11:55:08.026] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:55:08.043] <TB2> INFO: dacScan step from 100 .. 119
[11:55:08.043] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:55:08.043] <TB2> INFO: run 1 of 1
[11:55:27.068] <TB2> INFO: Test took 19025ms.
[11:55:27.305] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:55:27.388] <TB2> INFO: dacScan step from 120 .. 139
[11:55:27.388] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:55:27.388] <TB2> INFO: run 1 of 1
[11:55:44.581] <TB2> INFO: Test took 17193ms.
[11:55:44.696] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:55:44.714] <TB2> INFO: dacScan step from 140 .. 159
[11:55:44.714] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:55:44.714] <TB2> INFO: run 1 of 1
[11:56:01.487] <TB2> INFO: Test took 16773ms.
[11:56:01.540] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:56:23.816] <TB2> INFO: ROC 0 VthrComp = 101
[11:56:23.816] <TB2> INFO: ROC 1 VthrComp = 87
[11:56:23.816] <TB2> INFO: ROC 2 VthrComp = 92
[11:56:23.816] <TB2> INFO: ROC 3 VthrComp = 88
[11:56:23.816] <TB2> INFO: ROC 4 VthrComp = 93
[11:56:23.816] <TB2> INFO: ROC 5 VthrComp = 88
[11:56:23.816] <TB2> INFO: ROC 6 VthrComp = 89
[11:56:23.816] <TB2> INFO: ROC 7 VthrComp = 78
[11:56:23.817] <TB2> INFO: ROC 8 VthrComp = 93
[11:56:23.817] <TB2> INFO: ROC 9 VthrComp = 77
[11:56:23.817] <TB2> INFO: ROC 10 VthrComp = 85
[11:56:23.817] <TB2> INFO: ROC 11 VthrComp = 69
[11:56:23.817] <TB2> INFO: ROC 12 VthrComp = 86
[11:56:23.817] <TB2> INFO: ROC 13 VthrComp = 73
[11:56:23.817] <TB2> INFO: ROC 14 VthrComp = 86
[11:56:23.817] <TB2> INFO: ROC 15 VthrComp = 86
[11:56:23.817] <TB2> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[11:56:23.817] <TB2> INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[11:56:23.826] <TB2> INFO: dacScan step from 0 .. 19
[11:56:23.826] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:56:23.826] <TB2> INFO: run 1 of 1
[11:56:38.974] <TB2> INFO: Test took 15148ms.
[11:56:39.001] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:56:39.001] <TB2> INFO: dacScan step from 20 .. 39
[11:56:39.001] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:56:39.001] <TB2> INFO: run 1 of 1
[11:56:55.707] <TB2> INFO: Test took 16706ms.
[11:56:55.738] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:56:55.741] <TB2> INFO: dacScan step from 40 .. 59
[11:56:55.741] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:56:55.741] <TB2> INFO: run 1 of 1
[11:57:17.133] <TB2> INFO: Test took 21392ms.
[11:57:17.278] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:57:17.304] <TB2> INFO: dacScan step from 60 .. 79
[11:57:17.304] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:57:17.304] <TB2> INFO: run 1 of 1
[11:57:40.146] <TB2> INFO: Test took 22842ms.
[11:57:40.306] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:57:40.335] <TB2> INFO: dacScan step from 80 .. 99
[11:57:40.335] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:57:40.335] <TB2> INFO: run 1 of 1
[11:58:02.592] <TB2> INFO: Test took 22257ms.
[11:58:02.757] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:58:02.787] <TB2> INFO: dacScan step from 100 .. 119
[11:58:02.787] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:58:02.787] <TB2> INFO: run 1 of 1
[11:58:24.806] <TB2> INFO: Test took 22019ms.
[11:58:24.963] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:58:24.994] <TB2> INFO: dacScan step from 120 .. 139
[11:58:24.994] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:58:24.994] <TB2> INFO: run 1 of 1
[11:58:44.263] <TB2> INFO: Test took 19269ms.
[11:58:44.418] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:58:44.448] <TB2> INFO: dacScan step from 140 .. 159
[11:58:44.448] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:58:44.448] <TB2> INFO: run 1 of 1
[11:59:04.673] <TB2> INFO: Test took 20225ms.
[11:59:04.828] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:59:30.159] <TB2> INFO: roc 0 with ID = 0 has maximal Vcal 59.3136 for pixel 37/74 mean/min/max = 45.7105/32.0075/59.4136
[11:59:30.159] <TB2> INFO: roc 1 with ID = 1 has maximal Vcal 62.6814 for pixel 1/3 mean/min/max = 47.0549/31.342/62.7679
[11:59:30.159] <TB2> INFO: roc 2 with ID = 2 has maximal Vcal 61.7139 for pixel 28/78 mean/min/max = 47.6355/33.2744/61.9966
[11:59:30.160] <TB2> INFO: roc 3 with ID = 3 has maximal Vcal 59.5128 for pixel 0/78 mean/min/max = 46.7167/33.9003/59.5332
[11:59:30.160] <TB2> INFO: roc 4 with ID = 4 has maximal Vcal 58.7609 for pixel 26/78 mean/min/max = 45.2215/31.5497/58.8933
[11:59:30.160] <TB2> INFO: roc 5 with ID = 5 has maximal Vcal 59.3382 for pixel 45/11 mean/min/max = 45.9588/32.5595/59.3581
[11:59:30.160] <TB2> INFO: roc 6 with ID = 6 has maximal Vcal 60.994 for pixel 19/76 mean/min/max = 46.9097/32.8252/60.9942
[11:59:30.161] <TB2> INFO: roc 7 with ID = 7 has maximal Vcal 60.2165 for pixel 15/3 mean/min/max = 47.3103/34.3607/60.2598
[11:59:30.161] <TB2> INFO: roc 8 with ID = 8 has maximal Vcal 59.5627 for pixel 18/78 mean/min/max = 45.5315/31.4514/59.6116
[11:59:30.161] <TB2> INFO: roc 9 with ID = 9 has maximal Vcal 60.6406 for pixel 16/2 mean/min/max = 47.1178/33.5209/60.7147
[11:59:30.161] <TB2> INFO: roc 10 with ID = 10 has maximal Vcal 56.2974 for pixel 1/79 mean/min/max = 44.1748/31.958/56.3915
[11:59:30.162] <TB2> INFO: roc 11 with ID = 11 has maximal Vcal 56.2044 for pixel 11/68 mean/min/max = 44.0359/31.7552/56.3166
[11:59:30.162] <TB2> INFO: roc 12 with ID = 12 has maximal Vcal 60.1053 for pixel 6/79 mean/min/max = 45.9553/31.7185/60.192
[11:59:30.162] <TB2> INFO: roc 13 with ID = 13 has maximal Vcal 58.3478 for pixel 2/55 mean/min/max = 46.0201/33.5531/58.487
[11:59:30.162] <TB2> INFO: roc 14 with ID = 14 has maximal Vcal 57.9971 for pixel 0/1 mean/min/max = 44.8702/31.4533/58.2871
[11:59:30.163] <TB2> INFO: roc 15 with ID = 15 has maximal Vcal 60.0568 for pixel 22/76 mean/min/max = 45.7475/31.3546/60.1404
[11:59:30.163] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:01:12.845] <TB2> INFO: Test took 102682ms.
[12:01:14.243] <TB2> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[12:01:14.251] <TB2> INFO: dacScan step from 0 .. 19
[12:01:14.251] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:01:14.251] <TB2> INFO: run 1 of 2
[12:01:29.508] <TB2> INFO: Test took 15256ms.
[12:01:29.538] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:01:29.539] <TB2> INFO: run 2 of 2
[12:01:45.250] <TB2> INFO: Test took 15710ms.
[12:01:45.281] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:01:45.282] <TB2> INFO: dacScan step from 20 .. 39
[12:01:45.282] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:01:45.282] <TB2> INFO: run 1 of 2
[12:02:04.716] <TB2> INFO: Test took 19433ms.
[12:02:04.880] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:02:04.910] <TB2> INFO: run 2 of 2
[12:02:25.157] <TB2> INFO: Test took 20247ms.
[12:02:25.311] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:02:25.339] <TB2> INFO: dacScan step from 40 .. 59
[12:02:25.339] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:02:25.339] <TB2> INFO: run 1 of 2
[12:02:47.889] <TB2> INFO: Test took 22550ms.
[12:02:48.056] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:02:48.087] <TB2> INFO: run 2 of 2
[12:03:10.870] <TB2> INFO: Test took 22783ms.
[12:03:11.046] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:03:11.079] <TB2> INFO: dacScan step from 60 .. 79
[12:03:11.079] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:03:11.079] <TB2> INFO: run 1 of 2
[12:03:33.442] <TB2> INFO: Test took 22363ms.
[12:03:33.599] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:03:33.628] <TB2> INFO: run 2 of 2
[12:03:55.904] <TB2> INFO: Test took 22276ms.
[12:03:56.082] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:03:56.114] <TB2> INFO: dacScan step from 80 .. 99
[12:03:56.114] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:03:56.114] <TB2> INFO: run 1 of 2
[12:04:18.319] <TB2> INFO: Test took 22205ms.
[12:04:18.488] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:04:18.517] <TB2> INFO: run 2 of 2
[12:04:40.359] <TB2> INFO: Test took 21842ms.
[12:04:40.538] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:04:40.568] <TB2> INFO: dacScan step from 100 .. 119
[12:04:40.568] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:04:40.568] <TB2> INFO: run 1 of 2
[12:05:02.758] <TB2> INFO: Test took 22190ms.
[12:05:02.934] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:05:02.969] <TB2> INFO: run 2 of 2
[12:05:25.064] <TB2> INFO: Test took 22095ms.
[12:05:25.247] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:05:25.282] <TB2> INFO: dacScan step from 120 .. 139
[12:05:25.282] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:05:25.282] <TB2> INFO: run 1 of 2
[12:05:47.292] <TB2> INFO: Test took 22010ms.
[12:05:47.450] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:05:47.481] <TB2> INFO: run 2 of 2
[12:06:09.330] <TB2> INFO: Test took 21849ms.
[12:06:09.496] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:06:09.527] <TB2> INFO: dacScan step from 140 .. 159
[12:06:09.527] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:06:09.527] <TB2> INFO: run 1 of 2
[12:06:32.012] <TB2> INFO: Test took 22485ms.
[12:06:32.198] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:06:32.234] <TB2> INFO: run 2 of 2
[12:06:54.340] <TB2> INFO: Test took 22106ms.
[12:06:54.509] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:06:54.542] <TB2> INFO: dacScan step from 160 .. 179
[12:06:54.542] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:06:54.542] <TB2> INFO: run 1 of 2
[12:07:16.495] <TB2> INFO: Test took 21953ms.
[12:07:16.655] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:07:16.689] <TB2> INFO: run 2 of 2
[12:07:37.144] <TB2> INFO: Test took 20455ms.
[12:07:37.405] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:07:37.444] <TB2> INFO: dacScan step from 180 .. 199
[12:07:37.444] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:07:37.444] <TB2> INFO: run 1 of 2
[12:07:57.923] <TB2> INFO: Test took 20479ms.
[12:07:58.186] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:07:58.244] <TB2> INFO: run 2 of 2
[12:08:20.335] <TB2> INFO: Test took 22091ms.
[12:08:20.501] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:08:44.222] <TB2> INFO: ---> TrimStepCorr4 extremal thresholds: 0.019313 .. 255.000000
[12:08:44.301] <TB2> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 255 (20/-1) hits flags = 16 (plus default)
[12:08:44.309] <TB2> INFO: dacScan step from 0 .. 19
[12:08:44.309] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:08:44.309] <TB2> INFO: run 1 of 1
[12:08:57.894] <TB2> INFO: Test took 13585ms.
[12:08:57.919] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:08:57.920] <TB2> INFO: dacScan step from 20 .. 39
[12:08:57.920] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:08:57.920] <TB2> INFO: run 1 of 1
[12:09:13.598] <TB2> INFO: Test took 15678ms.
[12:09:13.679] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:13.695] <TB2> INFO: dacScan step from 40 .. 59
[12:09:13.695] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:09:13.695] <TB2> INFO: run 1 of 1
[12:09:32.907] <TB2> INFO: Test took 19212ms.
[12:09:33.067] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:33.101] <TB2> INFO: dacScan step from 60 .. 79
[12:09:33.101] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:09:33.101] <TB2> INFO: run 1 of 1
[12:09:52.199] <TB2> INFO: Test took 19098ms.
[12:09:52.355] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:52.390] <TB2> INFO: dacScan step from 80 .. 99
[12:09:52.390] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:09:52.390] <TB2> INFO: run 1 of 1
[12:10:11.846] <TB2> INFO: Test took 19456ms.
[12:10:12.005] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:12.041] <TB2> INFO: dacScan step from 100 .. 119
[12:10:12.042] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:10:12.042] <TB2> INFO: run 1 of 1
[12:10:31.016] <TB2> INFO: Test took 18974ms.
[12:10:31.169] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:31.201] <TB2> INFO: dacScan step from 120 .. 139
[12:10:31.202] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:10:31.202] <TB2> INFO: run 1 of 1
[12:10:50.187] <TB2> INFO: Test took 18985ms.
[12:10:50.338] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:50.407] <TB2> INFO: dacScan step from 140 .. 159
[12:10:50.407] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:10:50.407] <TB2> INFO: run 1 of 1
[12:11:09.693] <TB2> INFO: Test took 19286ms.
[12:11:09.862] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:09.905] <TB2> INFO: dacScan step from 160 .. 179
[12:11:09.906] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:11:09.906] <TB2> INFO: run 1 of 1
[12:11:29.170] <TB2> INFO: Test took 19264ms.
[12:11:29.349] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:29.386] <TB2> INFO: dacScan step from 180 .. 199
[12:11:29.386] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:11:29.386] <TB2> INFO: run 1 of 1
[12:11:48.687] <TB2> INFO: Test took 19301ms.
[12:11:48.918] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:48.973] <TB2> INFO: dacScan step from 200 .. 219
[12:11:48.973] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:11:48.973] <TB2> INFO: run 1 of 1
[12:12:06.192] <TB2> INFO: Test took 17218ms.
[12:12:06.372] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:12:06.404] <TB2> INFO: dacScan step from 220 .. 239
[12:12:06.404] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:12:06.404] <TB2> INFO: run 1 of 1
[12:12:23.165] <TB2> INFO: Test took 16760ms.
[12:12:23.319] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:12:23.353] <TB2> INFO: dacScan step from 240 .. 255
[12:12:23.353] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:12:23.353] <TB2> INFO: run 1 of 1
[12:12:38.090] <TB2> INFO: Test took 14737ms.
[12:12:38.206] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:13:11.652] <TB2> INFO: ---> TrimStepCorr2 extremal thresholds: 11.662136 .. 55.698561
[12:13:11.740] <TB2> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 1 .. 65 (20/-1) hits flags = 16 (plus default)
[12:13:11.749] <TB2> INFO: dacScan step from 1 .. 20
[12:13:11.749] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:13:11.749] <TB2> INFO: run 1 of 1
[12:13:24.933] <TB2> INFO: Test took 13184ms.
[12:13:24.957] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:13:24.957] <TB2> INFO: dacScan step from 21 .. 40
[12:13:24.957] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:13:24.957] <TB2> INFO: run 1 of 1
[12:13:39.369] <TB2> INFO: Test took 14412ms.
[12:13:39.449] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:13:39.471] <TB2> INFO: dacScan step from 41 .. 60
[12:13:39.471] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:13:39.471] <TB2> INFO: run 1 of 1
[12:13:57.027] <TB2> INFO: Test took 17556ms.
[12:13:57.256] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:13:57.313] <TB2> INFO: dacScan step from 61 .. 65
[12:13:57.313] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:13:57.313] <TB2> INFO: run 1 of 1
[12:14:03.682] <TB2> INFO: Test took 6369ms.
[12:14:03.723] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:14:21.244] <TB2> INFO: ---> TrimStepCorr1a extremal thresholds: 16.055127 .. 55.698561
[12:14:21.324] <TB2> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 6 .. 65 (20/-1) hits flags = 16 (plus default)
[12:14:21.332] <TB2> INFO: dacScan step from 6 .. 25
[12:14:21.332] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:14:21.332] <TB2> INFO: run 1 of 1
[12:14:34.329] <TB2> INFO: Test took 12997ms.
[12:14:34.353] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:14:34.353] <TB2> INFO: dacScan step from 26 .. 45
[12:14:34.353] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:14:34.353] <TB2> INFO: run 1 of 1
[12:14:49.308] <TB2> INFO: Test took 14955ms.
[12:14:49.420] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:14:49.448] <TB2> INFO: dacScan step from 46 .. 65
[12:14:49.448] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:14:49.448] <TB2> INFO: run 1 of 1
[12:15:06.958] <TB2> INFO: Test took 17510ms.
[12:15:07.107] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:15:25.598] <TB2> INFO: ---> TrimStepCorr1b extremal thresholds: 1.988996 .. 55.698561
[12:15:25.679] <TB2> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 1 .. 65 (20/-1) hits flags = 16 (plus default)
[12:15:25.688] <TB2> INFO: dacScan step from 1 .. 20
[12:15:25.688] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:15:25.688] <TB2> INFO: run 1 of 1
[12:15:39.600] <TB2> INFO: Test took 13912ms.
[12:15:39.621] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:15:39.622] <TB2> INFO: dacScan step from 21 .. 40
[12:15:39.622] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:15:39.622] <TB2> INFO: run 1 of 1
[12:15:54.137] <TB2> INFO: Test took 14515ms.
[12:15:54.220] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:15:54.238] <TB2> INFO: dacScan step from 41 .. 60
[12:15:54.238] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:15:54.251] <TB2> INFO: run 1 of 1
[12:16:11.766] <TB2> INFO: Test took 17515ms.
[12:16:11.912] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:16:11.942] <TB2> INFO: dacScan step from 61 .. 65
[12:16:11.942] <TB2> INFO: dacScan split into 1 runs with ntrig = 4
[12:16:11.942] <TB2> INFO: run 1 of 1
[12:16:19.034] <TB2> INFO: Test took 7092ms.
[12:16:19.075] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:16:37.740] <TB2> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[12:16:37.740] <TB2> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20/5) hits flags = 16 (plus default)
[12:16:37.748] <TB2> INFO: dacScan step from 15 .. 34
[12:16:37.749] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:16:37.749] <TB2> INFO: run 1 of 2
[12:16:52.728] <TB2> INFO: Test took 14979ms.
[12:16:52.780] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:16:52.789] <TB2> INFO: run 2 of 2
[12:17:07.629] <TB2> INFO: Test took 14839ms.
[12:17:07.668] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:17:07.675] <TB2> INFO: dacScan step from 35 .. 54
[12:17:07.675] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:17:07.675] <TB2> INFO: run 1 of 2
[12:17:28.246] <TB2> INFO: Test took 20571ms.
[12:17:28.418] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:17:28.450] <TB2> INFO: run 2 of 2
[12:17:49.126] <TB2> INFO: Test took 20675ms.
[12:17:49.335] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:17:49.366] <TB2> INFO: dacScan step from 55 .. 55
[12:17:49.366] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:17:49.366] <TB2> INFO: run 1 of 2
[12:17:53.094] <TB2> INFO: Test took 3728ms.
[12:17:53.109] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:17:53.113] <TB2> INFO: run 2 of 2
[12:17:56.841] <TB2> INFO: Test took 3728ms.
[12:17:56.850] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:18:10.892] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C0.dat
[12:18:10.892] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C1.dat
[12:18:10.892] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C2.dat
[12:18:10.892] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C3.dat
[12:18:10.892] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C4.dat
[12:18:10.893] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C5.dat
[12:18:10.893] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C6.dat
[12:18:10.893] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C7.dat
[12:18:10.893] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C8.dat
[12:18:10.894] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C9.dat
[12:18:10.894] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C10.dat
[12:18:10.894] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C11.dat
[12:18:10.894] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C12.dat
[12:18:10.894] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C13.dat
[12:18:10.895] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C14.dat
[12:18:10.895] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C15.dat
[12:18:10.895] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//trimParameters35_C0.dat
[12:18:10.907] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//trimParameters35_C1.dat
[12:18:10.913] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//trimParameters35_C2.dat
[12:18:10.919] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//trimParameters35_C3.dat
[12:18:10.925] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//trimParameters35_C4.dat
[12:18:10.931] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//trimParameters35_C5.dat
[12:18:10.939] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//trimParameters35_C6.dat
[12:18:10.945] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//trimParameters35_C7.dat
[12:18:10.952] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//trimParameters35_C8.dat
[12:18:10.958] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//trimParameters35_C9.dat
[12:18:10.964] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//trimParameters35_C10.dat
[12:18:10.970] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//trimParameters35_C11.dat
[12:18:10.976] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//trimParameters35_C12.dat
[12:18:10.982] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//trimParameters35_C13.dat
[12:18:10.988] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//trimParameters35_C14.dat
[12:18:10.995] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//trimParameters35_C15.dat
[12:18:11.001] <TB2> INFO: PixTestTrim::trimTest() done
[12:18:11.001] <TB2> INFO: vtrim: 115 118 104 107 104 114 117 117 108 108 95 87 112 90 94 104
[12:18:11.001] <TB2> INFO: vthrcomp: 101 87 92 88 93 88 89 78 93 77 85 69 86 73 86 86
[12:18:11.001] <TB2> INFO: vcal mean: 34.99 35.03 35.02 35.05 35.05 35.05 35.06 35.05 35.06 35.05 35.04 35.00 35.02 35.08 35.01 35.10
[12:18:11.001] <TB2> INFO: vcal RMS: 1.42 1.05 1.07 1.03 1.00 1.05 1.14 0.98 1.18 1.00 0.99 0.98 1.03 0.94 1.01 1.29
[12:18:11.001] <TB2> INFO: bits mean: 9.89 9.83 9.39 9.21 9.71 10.01 9.90 9.24 9.71 9.39 10.28 10.20 9.88 9.50 9.49 10.43
[12:18:11.001] <TB2> INFO: bits RMS: 2.50 2.52 2.51 2.54 2.71 2.36 2.31 2.40 2.66 2.43 2.43 2.46 2.58 2.47 2.84 2.31
[12:18:11.007] <TB2> INFO: ----------------------------------------------------------------------
[12:18:11.007] <TB2> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[12:18:11.007] <TB2> INFO: ----------------------------------------------------------------------
[12:18:11.010] <TB2> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[12:18:11.019] <TB2> INFO: dacScan step from 0 .. 19
[12:18:11.019] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:18:11.019] <TB2> INFO: run 1 of 2
[12:18:26.142] <TB2> INFO: Test took 15123ms.
[12:18:26.163] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:18:26.163] <TB2> INFO: run 2 of 2
[12:18:40.498] <TB2> INFO: Test took 14334ms.
[12:18:40.518] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:18:40.518] <TB2> INFO: dacScan step from 20 .. 39
[12:18:40.518] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:18:40.518] <TB2> INFO: run 1 of 2
[12:18:55.808] <TB2> INFO: Test took 15290ms.
[12:18:55.832] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:18:55.832] <TB2> INFO: run 2 of 2
[12:19:11.106] <TB2> INFO: Test took 15274ms.
[12:19:11.133] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:19:11.133] <TB2> INFO: dacScan step from 40 .. 59
[12:19:11.133] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:19:11.133] <TB2> INFO: run 1 of 2
[12:19:26.310] <TB2> INFO: Test took 15176ms.
[12:19:26.330] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:19:26.330] <TB2> INFO: run 2 of 2
[12:19:41.609] <TB2> INFO: Test took 15279ms.
[12:19:41.630] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:19:41.630] <TB2> INFO: dacScan step from 60 .. 79
[12:19:41.630] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:19:41.630] <TB2> INFO: run 1 of 2
[12:19:56.961] <TB2> INFO: Test took 15331ms.
[12:19:56.993] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:19:56.993] <TB2> INFO: run 2 of 2
[12:20:12.619] <TB2> INFO: Test took 15626ms.
[12:20:12.640] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:20:12.641] <TB2> INFO: dacScan step from 80 .. 99
[12:20:12.641] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:20:12.641] <TB2> INFO: run 1 of 2
[12:20:30.000] <TB2> INFO: Test took 17360ms.
[12:20:30.060] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:20:30.065] <TB2> INFO: run 2 of 2
[12:20:47.336] <TB2> INFO: Test took 17271ms.
[12:20:47.401] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:20:47.406] <TB2> INFO: dacScan step from 100 .. 119
[12:20:47.406] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:20:47.406] <TB2> INFO: run 1 of 2
[12:21:06.725] <TB2> INFO: Test took 19319ms.
[12:21:06.865] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:06.886] <TB2> INFO: run 2 of 2
[12:21:25.932] <TB2> INFO: Test took 19046ms.
[12:21:26.075] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:26.097] <TB2> INFO: dacScan step from 120 .. 139
[12:21:26.097] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:21:26.097] <TB2> INFO: run 1 of 2
[12:21:46.751] <TB2> INFO: Test took 20654ms.
[12:21:46.962] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:46.998] <TB2> INFO: run 2 of 2
[12:22:07.450] <TB2> INFO: Test took 20452ms.
[12:22:07.635] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:07.668] <TB2> INFO: dacScan step from 140 .. 159
[12:22:07.668] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:22:07.668] <TB2> INFO: run 1 of 2
[12:22:28.107] <TB2> INFO: Test took 20439ms.
[12:22:28.274] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:28.305] <TB2> INFO: run 2 of 2
[12:22:48.616] <TB2> INFO: Test took 20311ms.
[12:22:48.797] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:48.829] <TB2> INFO: dacScan step from 160 .. 179
[12:22:48.829] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:22:48.829] <TB2> INFO: run 1 of 2
[12:23:07.951] <TB2> INFO: Test took 19122ms.
[12:23:08.113] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:23:08.146] <TB2> INFO: run 2 of 2
[12:23:27.327] <TB2> INFO: Test took 19180ms.
[12:23:27.492] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:23:27.520] <TB2> INFO: dacScan step from 180 .. 199
[12:23:27.520] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:23:27.520] <TB2> INFO: run 1 of 2
[12:23:49.392] <TB2> INFO: Test took 21871ms.
[12:23:49.550] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:23:49.581] <TB2> INFO: run 2 of 2
[12:24:10.345] <TB2> INFO: Test took 20764ms.
[12:24:10.529] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:37.137] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 207 (20/5) hits flags = 16 (plus default)
[12:24:37.146] <TB2> INFO: dacScan step from 0 .. 19
[12:24:37.146] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:24:37.146] <TB2> INFO: run 1 of 2
[12:24:51.629] <TB2> INFO: Test took 14483ms.
[12:24:51.650] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:51.650] <TB2> INFO: run 2 of 2
[12:25:06.214] <TB2> INFO: Test took 14564ms.
[12:25:06.241] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:06.241] <TB2> INFO: dacScan step from 20 .. 39
[12:25:06.241] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:25:06.241] <TB2> INFO: run 1 of 2
[12:25:20.798] <TB2> INFO: Test took 14557ms.
[12:25:20.819] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:20.819] <TB2> INFO: run 2 of 2
[12:25:35.692] <TB2> INFO: Test took 14872ms.
[12:25:35.713] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:35.713] <TB2> INFO: dacScan step from 40 .. 59
[12:25:35.713] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:25:35.713] <TB2> INFO: run 1 of 2
[12:25:50.866] <TB2> INFO: Test took 15153ms.
[12:25:50.890] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:50.890] <TB2> INFO: run 2 of 2
[12:26:05.968] <TB2> INFO: Test took 15078ms.
[12:26:05.995] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:26:05.995] <TB2> INFO: dacScan step from 60 .. 79
[12:26:05.995] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:26:05.995] <TB2> INFO: run 1 of 2
[12:26:21.701] <TB2> INFO: Test took 15706ms.
[12:26:21.735] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:26:21.736] <TB2> INFO: run 2 of 2
[12:26:37.921] <TB2> INFO: Test took 16185ms.
[12:26:37.950] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:26:37.951] <TB2> INFO: dacScan step from 80 .. 99
[12:26:37.951] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:26:37.951] <TB2> INFO: run 1 of 2
[12:26:56.373] <TB2> INFO: Test took 18421ms.
[12:26:56.469] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:26:56.483] <TB2> INFO: run 2 of 2
[12:27:13.836] <TB2> INFO: Test took 17353ms.
[12:27:13.936] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:13.949] <TB2> INFO: dacScan step from 100 .. 119
[12:27:13.949] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:27:13.949] <TB2> INFO: run 1 of 2
[12:27:34.228] <TB2> INFO: Test took 20279ms.
[12:27:34.435] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:34.465] <TB2> INFO: run 2 of 2
[12:27:55.253] <TB2> INFO: Test took 20789ms.
[12:27:55.434] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:55.462] <TB2> INFO: dacScan step from 120 .. 139
[12:27:55.462] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:27:55.462] <TB2> INFO: run 1 of 2
[12:28:14.448] <TB2> INFO: Test took 18986ms.
[12:28:14.642] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:14.678] <TB2> INFO: run 2 of 2
[12:28:36.175] <TB2> INFO: Test took 21497ms.
[12:28:36.348] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:36.378] <TB2> INFO: dacScan step from 140 .. 159
[12:28:36.378] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:28:36.378] <TB2> INFO: run 1 of 2
[12:28:57.559] <TB2> INFO: Test took 21181ms.
[12:28:57.739] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:57.774] <TB2> INFO: run 2 of 2
[12:29:18.268] <TB2> INFO: Test took 20494ms.
[12:29:18.453] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:18.488] <TB2> INFO: dacScan step from 160 .. 179
[12:29:18.488] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:29:18.488] <TB2> INFO: run 1 of 2
[12:29:38.728] <TB2> INFO: Test took 20240ms.
[12:29:38.891] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:38.927] <TB2> INFO: run 2 of 2
[12:30:00.583] <TB2> INFO: Test took 21656ms.
[12:30:00.747] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:00.776] <TB2> INFO: dacScan step from 180 .. 199
[12:30:00.776] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:30:00.776] <TB2> INFO: run 1 of 2
[12:30:22.128] <TB2> INFO: Test took 21352ms.
[12:30:22.292] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:22.325] <TB2> INFO: run 2 of 2
[12:30:43.121] <TB2> INFO: Test took 20795ms.
[12:30:43.289] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:43.320] <TB2> INFO: dacScan step from 200 .. 207
[12:30:43.320] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:30:43.320] <TB2> INFO: run 1 of 2
[12:30:52.638] <TB2> INFO: Test took 9318ms.
[12:30:52.703] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:52.718] <TB2> INFO: run 2 of 2
[12:31:01.954] <TB2> INFO: Test took 9236ms.
[12:31:02.017] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:31:27.808] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 197 (20/5) hits flags = 16 (plus default)
[12:31:27.816] <TB2> INFO: dacScan step from 0 .. 19
[12:31:27.816] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:31:27.816] <TB2> INFO: run 1 of 2
[12:31:42.960] <TB2> INFO: Test took 15144ms.
[12:31:42.984] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:31:42.984] <TB2> INFO: run 2 of 2
[12:31:59.104] <TB2> INFO: Test took 16119ms.
[12:31:59.131] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:31:59.131] <TB2> INFO: dacScan step from 20 .. 39
[12:31:59.131] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:31:59.131] <TB2> INFO: run 1 of 2
[12:32:15.193] <TB2> INFO: Test took 16062ms.
[12:32:15.220] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:32:15.220] <TB2> INFO: run 2 of 2
[12:32:31.039] <TB2> INFO: Test took 15819ms.
[12:32:31.061] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:32:31.061] <TB2> INFO: dacScan step from 40 .. 59
[12:32:31.061] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:32:31.061] <TB2> INFO: run 1 of 2
[12:32:45.476] <TB2> INFO: Test took 14415ms.
[12:32:45.497] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:32:45.497] <TB2> INFO: run 2 of 2
[12:33:00.440] <TB2> INFO: Test took 14943ms.
[12:33:00.463] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:00.463] <TB2> INFO: dacScan step from 60 .. 79
[12:33:00.463] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:33:00.463] <TB2> INFO: run 1 of 2
[12:33:16.020] <TB2> INFO: Test took 15556ms.
[12:33:16.051] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:16.052] <TB2> INFO: run 2 of 2
[12:33:31.594] <TB2> INFO: Test took 15542ms.
[12:33:31.629] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:31.630] <TB2> INFO: dacScan step from 80 .. 99
[12:33:31.630] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:33:31.630] <TB2> INFO: run 1 of 2
[12:33:48.735] <TB2> INFO: Test took 17105ms.
[12:33:48.833] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:48.845] <TB2> INFO: run 2 of 2
[12:34:04.903] <TB2> INFO: Test took 16058ms.
[12:34:04.998] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:05.011] <TB2> INFO: dacScan step from 100 .. 119
[12:34:05.011] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:34:05.011] <TB2> INFO: run 1 of 2
[12:34:24.035] <TB2> INFO: Test took 19024ms.
[12:34:24.209] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:24.239] <TB2> INFO: run 2 of 2
[12:34:45.158] <TB2> INFO: Test took 20919ms.
[12:34:45.330] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:45.357] <TB2> INFO: dacScan step from 120 .. 139
[12:34:45.357] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:34:45.357] <TB2> INFO: run 1 of 2
[12:35:06.952] <TB2> INFO: Test took 21595ms.
[12:35:07.133] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:07.168] <TB2> INFO: run 2 of 2
[12:35:27.631] <TB2> INFO: Test took 20463ms.
[12:35:27.818] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:27.851] <TB2> INFO: dacScan step from 140 .. 159
[12:35:27.851] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:35:27.851] <TB2> INFO: run 1 of 2
[12:35:47.554] <TB2> INFO: Test took 19703ms.
[12:35:47.786] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:47.823] <TB2> INFO: run 2 of 2
[12:36:07.972] <TB2> INFO: Test took 20148ms.
[12:36:08.159] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:36:08.195] <TB2> INFO: dacScan step from 160 .. 179
[12:36:08.195] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:36:08.195] <TB2> INFO: run 1 of 2
[12:36:29.779] <TB2> INFO: Test took 21584ms.
[12:36:29.955] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:36:29.986] <TB2> INFO: run 2 of 2
[12:36:51.695] <TB2> INFO: Test took 21709ms.
[12:36:51.877] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:36:51.915] <TB2> INFO: dacScan step from 180 .. 197
[12:36:51.915] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:36:51.915] <TB2> INFO: run 1 of 2
[12:37:12.277] <TB2> INFO: Test took 20361ms.
[12:37:12.429] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:37:12.457] <TB2> INFO: run 2 of 2
[12:37:30.763] <TB2> INFO: Test took 18306ms.
[12:37:30.983] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:37:56.224] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 197 (20/5) hits flags = 16 (plus default)
[12:37:56.232] <TB2> INFO: dacScan step from 0 .. 19
[12:37:56.232] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:37:56.232] <TB2> INFO: run 1 of 2
[12:38:11.298] <TB2> INFO: Test took 15066ms.
[12:38:11.322] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:38:11.322] <TB2> INFO: run 2 of 2
[12:38:26.440] <TB2> INFO: Test took 15117ms.
[12:38:26.463] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:38:26.463] <TB2> INFO: dacScan step from 20 .. 39
[12:38:26.463] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:38:26.463] <TB2> INFO: run 1 of 2
[12:38:41.271] <TB2> INFO: Test took 14808ms.
[12:38:41.291] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:38:41.291] <TB2> INFO: run 2 of 2
[12:38:55.667] <TB2> INFO: Test took 14376ms.
[12:38:55.690] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:38:55.690] <TB2> INFO: dacScan step from 40 .. 59
[12:38:55.690] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:38:55.690] <TB2> INFO: run 1 of 2
[12:39:10.892] <TB2> INFO: Test took 15202ms.
[12:39:10.914] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:39:10.914] <TB2> INFO: run 2 of 2
[12:39:26.241] <TB2> INFO: Test took 15327ms.
[12:39:26.264] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:39:26.264] <TB2> INFO: dacScan step from 60 .. 79
[12:39:26.264] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:39:26.264] <TB2> INFO: run 1 of 2
[12:39:41.488] <TB2> INFO: Test took 15224ms.
[12:39:41.518] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:39:41.519] <TB2> INFO: run 2 of 2
[12:39:57.178] <TB2> INFO: Test took 15658ms.
[12:39:57.213] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:39:57.214] <TB2> INFO: dacScan step from 80 .. 99
[12:39:57.214] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:39:57.214] <TB2> INFO: run 1 of 2
[12:40:14.872] <TB2> INFO: Test took 17658ms.
[12:40:14.972] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:40:14.985] <TB2> INFO: run 2 of 2
[12:40:32.957] <TB2> INFO: Test took 17972ms.
[12:40:33.066] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:40:33.081] <TB2> INFO: dacScan step from 100 .. 119
[12:40:33.081] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:40:33.081] <TB2> INFO: run 1 of 2
[12:40:53.298] <TB2> INFO: Test took 20217ms.
[12:40:53.477] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:40:53.510] <TB2> INFO: run 2 of 2
[12:41:14.359] <TB2> INFO: Test took 20849ms.
[12:41:14.531] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:14.561] <TB2> INFO: dacScan step from 120 .. 139
[12:41:14.561] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:41:14.561] <TB2> INFO: run 1 of 2
[12:41:37.328] <TB2> INFO: Test took 22767ms.
[12:41:37.520] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:37.584] <TB2> INFO: run 2 of 2
[12:41:58.175] <TB2> INFO: Test took 20591ms.
[12:41:58.377] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:58.416] <TB2> INFO: dacScan step from 140 .. 159
[12:41:58.416] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:41:58.416] <TB2> INFO: run 1 of 2
[12:42:17.631] <TB2> INFO: Test took 19215ms.
[12:42:17.800] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:42:17.830] <TB2> INFO: run 2 of 2
[12:42:38.234] <TB2> INFO: Test took 20404ms.
[12:42:38.400] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:42:38.428] <TB2> INFO: dacScan step from 160 .. 179
[12:42:38.428] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:42:38.428] <TB2> INFO: run 1 of 2
[12:43:00.958] <TB2> INFO: Test took 22530ms.
[12:43:01.132] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:01.164] <TB2> INFO: run 2 of 2
[12:43:22.654] <TB2> INFO: Test took 21490ms.
[12:43:22.816] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:22.848] <TB2> INFO: dacScan step from 180 .. 197
[12:43:22.848] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:43:22.848] <TB2> INFO: run 1 of 2
[12:43:42.163] <TB2> INFO: Test took 19315ms.
[12:43:42.310] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:42.339] <TB2> INFO: run 2 of 2
[12:44:03.486] <TB2> INFO: Test took 21147ms.
[12:44:03.642] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:30.539] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 158 (20/5) hits flags = 16 (plus default)
[12:44:30.547] <TB2> INFO: dacScan step from 0 .. 19
[12:44:30.547] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:44:30.547] <TB2> INFO: run 1 of 2
[12:44:45.936] <TB2> INFO: Test took 15389ms.
[12:44:45.962] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:45.962] <TB2> INFO: run 2 of 2
[12:45:01.241] <TB2> INFO: Test took 15279ms.
[12:45:01.266] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:45:01.266] <TB2> INFO: dacScan step from 20 .. 39
[12:45:01.266] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:45:01.266] <TB2> INFO: run 1 of 2
[12:45:16.705] <TB2> INFO: Test took 15439ms.
[12:45:16.732] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:45:16.732] <TB2> INFO: run 2 of 2
[12:45:32.290] <TB2> INFO: Test took 15558ms.
[12:45:32.314] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:45:32.314] <TB2> INFO: dacScan step from 40 .. 59
[12:45:32.314] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:45:32.314] <TB2> INFO: run 1 of 2
[12:45:48.100] <TB2> INFO: Test took 15786ms.
[12:45:48.123] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:45:48.123] <TB2> INFO: run 2 of 2
[12:46:03.866] <TB2> INFO: Test took 15743ms.
[12:46:03.886] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:03.886] <TB2> INFO: dacScan step from 60 .. 79
[12:46:03.886] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:46:03.886] <TB2> INFO: run 1 of 2
[12:46:19.775] <TB2> INFO: Test took 15889ms.
[12:46:19.812] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:19.814] <TB2> INFO: run 2 of 2
[12:46:36.254] <TB2> INFO: Test took 16440ms.
[12:46:36.283] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:36.285] <TB2> INFO: dacScan step from 80 .. 99
[12:46:36.285] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:46:36.285] <TB2> INFO: run 1 of 2
[12:46:54.869] <TB2> INFO: Test took 18584ms.
[12:46:54.970] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:54.982] <TB2> INFO: run 2 of 2
[12:47:13.220] <TB2> INFO: Test took 18238ms.
[12:47:13.315] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:47:13.330] <TB2> INFO: dacScan step from 100 .. 119
[12:47:13.330] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:47:13.330] <TB2> INFO: run 1 of 2
[12:47:34.256] <TB2> INFO: Test took 20926ms.
[12:47:34.441] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:47:34.469] <TB2> INFO: run 2 of 2
[12:47:56.207] <TB2> INFO: Test took 21738ms.
[12:47:56.403] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:47:56.435] <TB2> INFO: dacScan step from 120 .. 139
[12:47:56.435] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:47:56.435] <TB2> INFO: run 1 of 2
[12:48:18.105] <TB2> INFO: Test took 21670ms.
[12:48:18.285] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:18.319] <TB2> INFO: run 2 of 2
[12:48:40.478] <TB2> INFO: Test took 22159ms.
[12:48:40.654] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:40.685] <TB2> INFO: dacScan step from 140 .. 158
[12:48:40.686] <TB2> INFO: dacScan split into 2 runs with ntrig = 5
[12:48:40.686] <TB2> INFO: run 1 of 2
[12:49:01.281] <TB2> INFO: Test took 20595ms.
[12:49:01.434] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:01.464] <TB2> INFO: run 2 of 2
[12:49:22.327] <TB2> INFO: Test took 20863ms.
[12:49:22.485] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:44.823] <TB2> INFO: PixTestTrim::trimBitTest() done
[12:49:44.825] <TB2> INFO: PixTestTrim::doTest() done, duration: 3352 seconds
[12:49:45.508] <TB2> INFO: ######################################################################
[12:49:45.508] <TB2> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[12:49:45.508] <TB2> INFO: ######################################################################
[12:49:49.139] <TB2> INFO: Test took 3629ms.
[12:49:49.161] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:53.013] <TB2> INFO: Test took 3655ms.
[12:49:53.085] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:56.591] <TB2> INFO: Test took 3494ms.
[12:49:56.662] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:56.668] <TB2> INFO: The DUT currently contains the following objects:
[12:49:56.668] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:49:56.668] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:49:56.668] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:49:56.668] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[12:49:56.668] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:56.668] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:56.668] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:56.669] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:56.669] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:56.669] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:56.669] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:56.669] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:56.669] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:56.669] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:56.669] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:56.669] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:56.669] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:56.669] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:56.669] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:56.669] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:57.778] <TB2> INFO: Test took 1109ms.
[12:49:57.779] <TB2> INFO: The DUT currently contains the following objects:
[12:49:57.779] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:49:57.779] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:49:57.779] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:49:57.779] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[12:49:57.779] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:57.780] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:57.780] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:57.780] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:57.780] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:57.780] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:57.780] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:57.780] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:57.780] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:57.780] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:57.780] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:57.780] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:57.780] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:57.780] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:57.780] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:57.780] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:58.886] <TB2> INFO: Test took 1106ms.
[12:49:58.887] <TB2> INFO: The DUT currently contains the following objects:
[12:49:58.887] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:49:58.887] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:49:58.887] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:49:58.887] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[12:49:58.887] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:58.887] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:58.887] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:58.887] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:58.887] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:58.887] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:58.887] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:58.887] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:58.887] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:58.887] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:58.887] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:58.887] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:58.887] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:58.887] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:58.887] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:58.887] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:59.994] <TB2> INFO: Test took 1107ms.
[12:49:59.996] <TB2> INFO: The DUT currently contains the following objects:
[12:49:59.996] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:49:59.996] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:49:59.996] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:49:59.996] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[12:49:59.996] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:59.996] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:59.996] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:59.996] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:59.996] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:59.996] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:59.996] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:59.996] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:59.996] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:59.996] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:59.996] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:59.996] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:59.996] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:59.996] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:59.996] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:49:59.996] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:01.102] <TB2> INFO: Test took 1106ms.
[12:50:01.103] <TB2> INFO: The DUT currently contains the following objects:
[12:50:01.103] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:50:01.104] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:50:01.104] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:50:01.104] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[12:50:01.104] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:01.104] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:01.104] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:01.104] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:01.104] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:01.104] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:01.104] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:01.104] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:01.104] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:01.104] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:01.104] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:01.104] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:01.104] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:01.104] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:01.104] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:01.104] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:02.252] <TB2> INFO: Test took 1148ms.
[12:50:02.253] <TB2> INFO: The DUT currently contains the following objects:
[12:50:02.253] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:50:02.253] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:50:02.253] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:50:02.253] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[12:50:02.253] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:02.253] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:02.253] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:02.253] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:02.253] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:02.253] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:02.253] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:02.253] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:02.253] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:02.253] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:02.253] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:02.253] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:02.253] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:02.253] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:02.253] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:02.253] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:03.387] <TB2> INFO: Test took 1134ms.
[12:50:03.388] <TB2> INFO: The DUT currently contains the following objects:
[12:50:03.388] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:50:03.388] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:50:03.388] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:50:03.388] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[12:50:03.388] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:03.388] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:03.388] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:03.388] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:03.388] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:03.388] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:03.388] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:03.388] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:03.388] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:03.389] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:03.389] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:03.389] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:03.389] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:03.389] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:03.389] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:03.389] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:04.495] <TB2> INFO: Test took 1106ms.
[12:50:04.496] <TB2> INFO: The DUT currently contains the following objects:
[12:50:04.496] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:50:04.496] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:50:04.496] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:50:04.496] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[12:50:04.496] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:04.496] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:04.496] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:04.496] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:04.496] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:04.496] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:04.496] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:04.496] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:04.496] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:04.496] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:04.496] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:04.496] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:04.496] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:04.496] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:04.496] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:04.496] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:05.604] <TB2> INFO: Test took 1108ms.
[12:50:05.605] <TB2> INFO: The DUT currently contains the following objects:
[12:50:05.605] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:50:05.605] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:50:05.605] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:50:05.605] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[12:50:05.606] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:05.606] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:05.606] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:05.606] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:05.606] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:05.606] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:05.606] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:05.606] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:05.606] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:05.606] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:05.606] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:05.606] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:05.606] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:05.606] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:05.606] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:05.606] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:06.743] <TB2> INFO: Test took 1136ms.
[12:50:06.744] <TB2> INFO: The DUT currently contains the following objects:
[12:50:06.744] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:50:06.744] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:50:06.744] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:50:06.744] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[12:50:06.744] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:06.744] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:06.744] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:06.744] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:06.744] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:06.744] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:06.744] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:06.744] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:06.744] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:06.744] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:06.744] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:06.744] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:06.744] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:06.744] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:06.744] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:06.744] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:07.881] <TB2> INFO: Test took 1137ms.
[12:50:07.883] <TB2> INFO: The DUT currently contains the following objects:
[12:50:07.883] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:50:07.883] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:50:07.883] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:50:07.883] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[12:50:07.883] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:07.883] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:07.883] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:07.883] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:07.883] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:07.883] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:07.883] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:07.883] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:07.883] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:07.883] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:07.883] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:07.883] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:07.883] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:07.883] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:07.883] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:07.883] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:08.990] <TB2> INFO: Test took 1107ms.
[12:50:08.991] <TB2> INFO: The DUT currently contains the following objects:
[12:50:08.991] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:50:08.991] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:50:08.991] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:50:08.991] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[12:50:08.991] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:08.991] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:08.991] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:08.991] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:08.991] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:08.991] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:08.991] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:08.991] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:08.991] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:08.991] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:08.992] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:08.992] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:08.992] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:08.992] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:08.992] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:08.992] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:10.099] <TB2> INFO: Test took 1107ms.
[12:50:10.100] <TB2> INFO: The DUT currently contains the following objects:
[12:50:10.100] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:50:10.100] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:50:10.100] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:50:10.100] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[12:50:10.100] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:10.100] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:10.100] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:10.100] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:10.100] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:10.100] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:10.101] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:10.101] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:10.101] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:10.101] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:10.101] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:10.101] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:10.101] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:10.101] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:10.101] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:10.101] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:11.223] <TB2> INFO: Test took 1122ms.
[12:50:11.225] <TB2> INFO: The DUT currently contains the following objects:
[12:50:11.225] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:50:11.225] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:50:11.225] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:50:11.225] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[12:50:11.225] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:11.225] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:11.225] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:11.225] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:11.225] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:11.225] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:11.225] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:11.225] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:11.225] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:11.225] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:11.225] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:11.225] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:11.225] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:11.225] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:11.226] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:11.226] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:12.333] <TB2> INFO: Test took 1107ms.
[12:50:12.335] <TB2> INFO: The DUT currently contains the following objects:
[12:50:12.345] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:50:12.345] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:50:12.345] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:50:12.345] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[12:50:12.345] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:12.346] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:12.346] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:12.346] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:12.346] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:12.346] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:12.346] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:12.346] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:12.346] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:12.346] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:12.346] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:12.346] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:12.346] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:12.346] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:12.346] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:12.346] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:13.443] <TB2> INFO: Test took 1097ms.
[12:50:13.444] <TB2> INFO: The DUT currently contains the following objects:
[12:50:13.444] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:50:13.444] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:50:13.444] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:50:13.444] <TB2> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[12:50:13.444] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:13.444] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:13.444] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:13.444] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:13.444] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:13.444] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:13.444] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:13.444] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:13.444] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:13.444] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:13.444] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:13.445] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:13.445] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:13.445] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:13.445] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:13.445] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[12:50:14.568] <TB2> INFO: Test took 1123ms.
[12:50:14.573] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:54:08.124] <TB2> INFO: Test took 233551ms.
[12:54:09.798] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:57:48.051] <TB2> INFO: Test took 218253ms.
[12:57:49.775] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[12:57:49.784] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[12:57:49.791] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[12:57:49.797] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[12:57:49.804] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[12:57:49.811] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[12:57:49.817] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[12:57:49.824] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[12:57:49.831] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[12:57:49.838] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[12:57:49.844] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[12:57:49.851] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[12:57:49.858] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[12:57:49.864] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[12:57:49.871] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[12:57:49.878] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[12:57:49.884] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[12:57:49.891] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[12:57:49.929] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C0.dat
[12:57:49.929] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C1.dat
[12:57:49.929] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C2.dat
[12:57:49.929] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C3.dat
[12:57:49.929] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C4.dat
[12:57:49.929] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C5.dat
[12:57:49.929] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C6.dat
[12:57:49.929] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C7.dat
[12:57:49.929] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C8.dat
[12:57:49.930] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C9.dat
[12:57:49.930] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C10.dat
[12:57:49.930] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C11.dat
[12:57:49.930] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C12.dat
[12:57:49.930] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C13.dat
[12:57:49.930] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C14.dat
[12:57:49.930] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//dacParameters35_C15.dat
[12:57:53.252] <TB2> INFO: Test took 3319ms.
[12:57:56.852] <TB2> INFO: Test took 3336ms.
[12:58:00.385] <TB2> INFO: Test took 3269ms.
[12:58:00.653] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:01.564] <TB2> INFO: Test took 911ms.
[12:58:01.567] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:02.667] <TB2> INFO: Test took 1100ms.
[12:58:02.669] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:03.769] <TB2> INFO: Test took 1100ms.
[12:58:03.771] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:04.872] <TB2> INFO: Test took 1101ms.
[12:58:04.874] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:05.989] <TB2> INFO: Test took 1115ms.
[12:58:05.991] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:07.092] <TB2> INFO: Test took 1101ms.
[12:58:07.094] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:08.194] <TB2> INFO: Test took 1100ms.
[12:58:08.196] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:09.297] <TB2> INFO: Test took 1101ms.
[12:58:09.299] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:10.400] <TB2> INFO: Test took 1101ms.
[12:58:10.402] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:11.502] <TB2> INFO: Test took 1100ms.
[12:58:11.504] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:12.605] <TB2> INFO: Test took 1101ms.
[12:58:12.608] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:13.710] <TB2> INFO: Test took 1102ms.
[12:58:13.712] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:14.812] <TB2> INFO: Test took 1100ms.
[12:58:14.814] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:15.915] <TB2> INFO: Test took 1101ms.
[12:58:15.917] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:17.024] <TB2> INFO: Test took 1107ms.
[12:58:17.028] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:18.136] <TB2> INFO: Test took 1108ms.
[12:58:18.139] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:19.247] <TB2> INFO: Test took 1109ms.
[12:58:19.250] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:20.357] <TB2> INFO: Test took 1107ms.
[12:58:20.361] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:21.469] <TB2> INFO: Test took 1108ms.
[12:58:21.472] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:22.580] <TB2> INFO: Test took 1108ms.
[12:58:22.583] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:23.691] <TB2> INFO: Test took 1108ms.
[12:58:23.694] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:24.801] <TB2> INFO: Test took 1107ms.
[12:58:24.804] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:25.911] <TB2> INFO: Test took 1107ms.
[12:58:25.914] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:27.020] <TB2> INFO: Test took 1106ms.
[12:58:27.022] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:28.129] <TB2> INFO: Test took 1107ms.
[12:58:28.132] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:29.239] <TB2> INFO: Test took 1107ms.
[12:58:29.243] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:30.349] <TB2> INFO: Test took 1106ms.
[12:58:30.353] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:31.459] <TB2> INFO: Test took 1107ms.
[12:58:31.462] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:32.569] <TB2> INFO: Test took 1107ms.
[12:58:32.572] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:33.678] <TB2> INFO: Test took 1106ms.
[12:58:33.682] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:34.788] <TB2> INFO: Test took 1107ms.
[12:58:34.791] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:58:35.898] <TB2> INFO: Test took 1107ms.
[12:58:36.425] <TB2> INFO: PixTestPhOptimization::doTest() done, duration: 530 seconds
[12:58:36.425] <TB2> INFO: PH scale (per ROC): 79 78 80 75 80 83 79 91 85 91 84 86 86 90 90 88
[12:58:36.425] <TB2> INFO: PH offset (per ROC): 164 172 158 157 166 161 173 151 161 158 143 141 153 149 135 147
[12:58:36.615] <TB2> INFO: ######################################################################
[12:58:36.615] <TB2> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[12:58:36.615] <TB2> INFO: ######################################################################
[12:58:36.625] <TB2> INFO: scanning low vcal = 10
[12:58:40.505] <TB2> INFO: Test took 3880ms.
[12:58:40.511] <TB2> INFO: scanning low vcal = 20
[12:58:44.385] <TB2> INFO: Test took 3874ms.
[12:58:44.390] <TB2> INFO: scanning low vcal = 30
[12:58:48.268] <TB2> INFO: Test took 3878ms.
[12:58:48.278] <TB2> INFO: scanning low vcal = 40
[12:58:52.644] <TB2> INFO: Test took 4366ms.
[12:58:52.708] <TB2> INFO: scanning low vcal = 50
[12:58:57.097] <TB2> INFO: Test took 4389ms.
[12:58:57.156] <TB2> INFO: scanning low vcal = 60
[12:59:01.487] <TB2> INFO: Test took 4331ms.
[12:59:01.553] <TB2> INFO: scanning low vcal = 70
[12:59:05.889] <TB2> INFO: Test took 4336ms.
[12:59:05.954] <TB2> INFO: scanning low vcal = 80
[12:59:10.340] <TB2> INFO: Test took 4386ms.
[12:59:10.404] <TB2> INFO: scanning low vcal = 90
[12:59:14.783] <TB2> INFO: Test took 4379ms.
[12:59:14.862] <TB2> INFO: scanning low vcal = 100
[12:59:19.213] <TB2> INFO: Test took 4351ms.
[12:59:19.274] <TB2> INFO: scanning low vcal = 110
[12:59:23.621] <TB2> INFO: Test took 4347ms.
[12:59:23.678] <TB2> INFO: scanning low vcal = 120
[12:59:28.036] <TB2> INFO: Test took 4358ms.
[12:59:28.104] <TB2> INFO: scanning low vcal = 130
[12:59:32.474] <TB2> INFO: Test took 4370ms.
[12:59:32.535] <TB2> INFO: scanning low vcal = 140
[12:59:36.895] <TB2> INFO: Test took 4360ms.
[12:59:36.960] <TB2> INFO: scanning low vcal = 150
[12:59:41.337] <TB2> INFO: Test took 4377ms.
[12:59:41.396] <TB2> INFO: scanning low vcal = 160
[12:59:45.738] <TB2> INFO: Test took 4342ms.
[12:59:45.797] <TB2> INFO: scanning low vcal = 170
[12:59:50.157] <TB2> INFO: Test took 4360ms.
[12:59:50.228] <TB2> INFO: scanning low vcal = 180
[12:59:54.599] <TB2> INFO: Test took 4371ms.
[12:59:54.665] <TB2> INFO: scanning low vcal = 190
[12:59:59.146] <TB2> INFO: Test took 4481ms.
[12:59:59.207] <TB2> INFO: scanning low vcal = 200
[13:00:03.580] <TB2> INFO: Test took 4373ms.
[13:00:03.663] <TB2> INFO: scanning low vcal = 210
[13:00:08.091] <TB2> INFO: Test took 4428ms.
[13:00:08.165] <TB2> INFO: scanning low vcal = 220
[13:00:12.587] <TB2> INFO: Test took 4422ms.
[13:00:12.647] <TB2> INFO: scanning low vcal = 230
[13:00:16.963] <TB2> INFO: Test took 4316ms.
[13:00:17.024] <TB2> INFO: scanning low vcal = 240
[13:00:21.414] <TB2> INFO: Test took 4390ms.
[13:00:21.475] <TB2> INFO: scanning low vcal = 250
[13:00:25.883] <TB2> INFO: Test took 4408ms.
[13:00:25.979] <TB2> INFO: scanning high vcal = 30 (= 210 in low range)
[13:00:30.336] <TB2> INFO: Test took 4357ms.
[13:00:30.396] <TB2> INFO: scanning high vcal = 50 (= 350 in low range)
[13:00:34.734] <TB2> INFO: Test took 4338ms.
[13:00:34.796] <TB2> INFO: scanning high vcal = 70 (= 490 in low range)
[13:00:39.156] <TB2> INFO: Test took 4360ms.
[13:00:39.236] <TB2> INFO: scanning high vcal = 90 (= 630 in low range)
[13:00:43.535] <TB2> INFO: Test took 4299ms.
[13:00:43.594] <TB2> INFO: scanning high vcal = 200 (= 1400 in low range)
[13:00:47.924] <TB2> INFO: Test took 4330ms.
[13:00:48.391] <TB2> INFO: PixTestGainPedestal::measure() done
[13:01:19.831] <TB2> INFO: PixTestGainPedestal::fit() done
[13:01:19.831] <TB2> INFO: non-linearity mean: 0.956 0.955 0.966 0.956 0.952 0.957 0.959 0.954 0.954 0.954 0.951 0.956 0.957 0.954 0.953 0.960
[13:01:19.831] <TB2> INFO: non-linearity RMS: 0.006 0.006 0.007 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.005 0.006 0.005
[13:01:19.832] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[13:01:19.871] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[13:01:19.908] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[13:01:19.926] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[13:01:19.945] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[13:01:19.963] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[13:01:19.986] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[13:01:20.004] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[13:01:20.022] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[13:01:20.040] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[13:01:20.058] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[13:01:20.076] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[13:01:20.094] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[13:01:20.112] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[13:01:20.130] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[13:01:20.147] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[13:01:20.166] <TB2> INFO: PixTestGainPedestal::doTest() done, duration: 163 seconds
[13:01:20.171] <TB2> INFO: readReadbackCal: /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C15.dat
[13:01:20.173] <TB2> INFO: PixTestReadback::doTest() start.
[13:01:20.173] <TB2> INFO: PixTestReadback::RES sent once
[13:01:36.702] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C0.dat
[13:01:36.703] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C1.dat
[13:01:36.703] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C2.dat
[13:01:36.703] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C3.dat
[13:01:36.703] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C4.dat
[13:01:36.704] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C5.dat
[13:01:36.704] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C6.dat
[13:01:36.704] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C7.dat
[13:01:36.704] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C8.dat
[13:01:36.704] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C9.dat
[13:01:36.704] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C10.dat
[13:01:36.704] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C11.dat
[13:01:36.704] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C12.dat
[13:01:36.705] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C13.dat
[13:01:36.705] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C14.dat
[13:01:36.705] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C15.dat
[13:01:36.736] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[13:01:36.736] <TB2> INFO: PixTestReadback::RES sent once
[13:01:53.169] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C0.dat
[13:01:53.169] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C1.dat
[13:01:53.169] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C2.dat
[13:01:53.169] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C3.dat
[13:01:53.169] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C4.dat
[13:01:53.169] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C5.dat
[13:01:53.169] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C6.dat
[13:01:53.169] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C7.dat
[13:01:53.169] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C8.dat
[13:01:53.169] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C9.dat
[13:01:53.170] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C10.dat
[13:01:53.170] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C11.dat
[13:01:53.170] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C12.dat
[13:01:53.170] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C13.dat
[13:01:53.170] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C14.dat
[13:01:53.170] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C15.dat
[13:01:53.203] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[13:01:53.203] <TB2> INFO: PixTestReadback::RES sent once
[13:02:05.923] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[13:02:05.923] <TB2> INFO: Vbg will be calibrated using Vd calibration
[13:02:05.923] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 163.8calibrated Vbg = 1.18958 :::*/*/*/*/
[13:02:05.923] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 158.9calibrated Vbg = 1.18589 :::*/*/*/*/
[13:02:05.923] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 156.5calibrated Vbg = 1.19174 :::*/*/*/*/
[13:02:05.923] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 255calibrated Vbg = 1.1725 :::*/*/*/*/
[13:02:05.923] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 156calibrated Vbg = 1.20506 :::*/*/*/*/
[13:02:05.923] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 150.1calibrated Vbg = 1.19929 :::*/*/*/*/
[13:02:05.923] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 155.9calibrated Vbg = 1.20755 :::*/*/*/*/
[13:02:05.923] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 255calibrated Vbg = 1.1725 :::*/*/*/*/
[13:02:05.923] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 148.8calibrated Vbg = 1.20139 :::*/*/*/*/
[13:02:05.923] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 161.4calibrated Vbg = 1.20637 :::*/*/*/*/
[13:02:05.923] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 151.8calibrated Vbg = 1.19954 :::*/*/*/*/
[13:02:05.923] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 255calibrated Vbg = 1.1725 :::*/*/*/*/
[13:02:05.923] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 162.9calibrated Vbg = 1.19613 :::*/*/*/*/
[13:02:05.923] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 155.6calibrated Vbg = 1.18423 :::*/*/*/*/
[13:02:05.923] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 156.1calibrated Vbg = 1.18371 :::*/*/*/*/
[13:02:05.923] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 248calibrated Vbg = 1.19813 :::*/*/*/*/
[13:02:05.927] <TB2> INFO: PixTestReadback::RES sent once
[13:06:00.361] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C0.dat
[13:06:00.361] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C1.dat
[13:06:00.361] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C2.dat
[13:06:00.362] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C3.dat
[13:06:00.362] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C4.dat
[13:06:00.362] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C5.dat
[13:06:00.362] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C6.dat
[13:06:00.362] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C7.dat
[13:06:00.362] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C8.dat
[13:06:00.362] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C9.dat
[13:06:00.362] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C10.dat
[13:06:00.362] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C11.dat
[13:06:00.362] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C12.dat
[13:06:00.362] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C13.dat
[13:06:00.362] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C14.dat
[13:06:00.362] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2086_FullQualification_2015-08-25_10h29m_1440491371//002_FulltestPxar_m20//readbackCal_C15.dat
[13:06:00.390] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[13:06:00.391] <TB2> INFO: PixTestReadback::doTest() done
[13:06:00.403] <TB2> INFO: enter test to run
[13:06:00.403] <TB2> INFO: test: exit no parameter change
[13:06:00.989] <TB2> QUIET: Connection to board 156 closed.
[13:06:01.068] <TB2> INFO: pXar: this is the end, my friend