[10:29:21.088] <TB3> INFO: *** Welcome to pxar ***
[10:29:21.088] <TB3> INFO: *** Today: 2015/08/20
[10:29:21.088] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters_C15.dat
[10:29:21.089] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//tbmParameters_C0b.dat
[10:29:21.089] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//defaultMaskFile.dat
[10:29:21.089] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//trimParameters_C15.dat
[10:29:21.156] <TB3> INFO: clk: 4
[10:29:21.156] <TB3> INFO: ctr: 4
[10:29:21.156] <TB3> INFO: sda: 19
[10:29:21.156] <TB3> INFO: tin: 9
[10:29:21.156] <TB3> INFO: level: 15
[10:29:21.156] <TB3> INFO: triggerdelay: 0
[10:29:21.156] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[10:29:21.156] <TB3> INFO: Log level: INFO
[10:29:21.161] <TB3> INFO: Found DTB DTB_WZ4I6J
[10:29:21.173] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[10:29:21.176] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[10:29:21.179] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[10:29:22.709] <TB3> INFO: DUT info:
[10:29:22.709] <TB3> INFO: The DUT currently contains the following objects:
[10:29:22.709] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[10:29:22.709] <TB3> INFO: TBM Core alpha (0): 7 registers set
[10:29:22.709] <TB3> INFO: TBM Core beta (1): 7 registers set
[10:29:22.709] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:29:22.709] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:22.709] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:22.709] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:22.709] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:22.709] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:22.709] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:22.709] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:22.709] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:22.710] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:22.710] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:22.710] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:22.710] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:22.710] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:22.710] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:22.710] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:22.710] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:29:23.111] <TB3> INFO: enter 'restricted' command line mode
[10:29:23.111] <TB3> INFO: enter test to run
[10:29:23.111] <TB3> INFO: test: pretest no parameter change
[10:29:23.111] <TB3> INFO: running: pretest
[10:29:23.117] <TB3> INFO: ######################################################################
[10:29:23.117] <TB3> INFO: PixTestPretest::doTest()
[10:29:23.117] <TB3> INFO: ######################################################################
[10:29:23.119] <TB3> INFO: ----------------------------------------------------------------------
[10:29:23.119] <TB3> INFO: PixTestPretest::programROC()
[10:29:23.119] <TB3> INFO: ----------------------------------------------------------------------
[10:29:41.137] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[10:29:41.137] <TB3> INFO: IA differences per ROC: 16.9 20.9 19.3 16.9 18.5 17.7 16.9 20.1 16.9 19.3 16.9 18.5 17.7 16.1 18.5 17.7
[10:29:41.210] <TB3> INFO: ----------------------------------------------------------------------
[10:29:41.210] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[10:29:41.210] <TB3> INFO: ----------------------------------------------------------------------
[10:30:00.776] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 392.3 mA = 24.5187 mA/ROC
[10:30:00.780] <TB3> INFO: ----------------------------------------------------------------------
[10:30:00.780] <TB3> INFO: PixTestPretest::findWorkingPixel()
[10:30:00.780] <TB3> INFO: ----------------------------------------------------------------------
[10:30:09.125] <TB3> INFO: Test took 8340ms.
[10:30:09.412] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[10:30:09.445] <TB3> INFO: ----------------------------------------------------------------------
[10:30:09.445] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[10:30:09.445] <TB3> INFO: ----------------------------------------------------------------------
[10:30:17.809] <TB3> INFO: Test took 8360ms.
[10:30:18.132] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[10:30:18.132] <TB3> INFO: CalDel: 129 125 136 135 121 138 140 147 164 128 139 127 152 142 170 140
[10:30:18.132] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[10:30:18.136] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters_C0.dat
[10:30:18.136] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters_C1.dat
[10:30:18.136] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters_C2.dat
[10:30:18.137] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters_C3.dat
[10:30:18.137] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters_C4.dat
[10:30:18.137] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters_C5.dat
[10:30:18.138] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters_C6.dat
[10:30:18.138] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters_C7.dat
[10:30:18.138] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters_C8.dat
[10:30:18.138] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters_C9.dat
[10:30:18.139] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters_C10.dat
[10:30:18.139] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters_C11.dat
[10:30:18.139] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters_C12.dat
[10:30:18.139] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters_C13.dat
[10:30:18.139] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters_C14.dat
[10:30:18.140] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters_C15.dat
[10:30:18.140] <TB3> INFO: PixTestPretest::doTest() done, duration: 55 seconds
[10:30:18.258] <TB3> INFO: enter test to run
[10:30:18.258] <TB3> INFO: test: fulltest no parameter change
[10:30:18.258] <TB3> INFO: running: fulltest
[10:30:18.258] <TB3> INFO: ######################################################################
[10:30:18.258] <TB3> INFO: PixTestFullTest::doTest()
[10:30:18.258] <TB3> INFO: ######################################################################
[10:30:18.259] <TB3> INFO: ######################################################################
[10:30:18.259] <TB3> INFO: PixTestAlive::doTest()
[10:30:18.259] <TB3> INFO: ######################################################################
[10:30:18.261] <TB3> INFO: ----------------------------------------------------------------------
[10:30:18.261] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:30:18.261] <TB3> INFO: ----------------------------------------------------------------------
[10:30:21.733] <TB3> INFO: Test took 3468ms.
[10:30:21.758] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:21.989] <TB3> INFO: PixTestAlive::aliveTest() done
[10:30:21.989] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 2 0 0
[10:30:21.990] <TB3> INFO: ----------------------------------------------------------------------
[10:30:21.990] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:30:21.990] <TB3> INFO: ----------------------------------------------------------------------
[10:30:24.769] <TB3> INFO: Test took 2777ms.
[10:30:24.773] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:24.773] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:30:25.010] <TB3> INFO: PixTestAlive::maskTest() done
[10:30:25.010] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:30:25.011] <TB3> INFO: ----------------------------------------------------------------------
[10:30:25.011] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:30:25.011] <TB3> INFO: ----------------------------------------------------------------------
[10:30:28.467] <TB3> INFO: Test took 3454ms.
[10:30:28.489] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:28.721] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[10:30:28.721] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:30:28.721] <TB3> INFO: PixTestAlive::doTest() done, duration: 10 seconds
[10:30:28.735] <TB3> INFO: ######################################################################
[10:30:28.735] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[10:30:28.735] <TB3> INFO: ######################################################################
[10:30:28.738] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30) hits flags = 2 (plus default)
[10:30:28.749] <TB3> INFO: dacScan step from 0 .. 29
[10:30:50.188] <TB3> INFO: Test took 21439ms.
[10:30:50.220] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:50.220] <TB3> INFO: dacScan step from 30 .. 59
[10:31:13.379] <TB3> INFO: Test took 23159ms.
[10:31:13.480] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:13.491] <TB3> INFO: dacScan step from 60 .. 89
[10:31:41.857] <TB3> INFO: Test took 28366ms.
[10:31:42.113] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:42.181] <TB3> INFO: dacScan step from 90 .. 119
[10:32:11.204] <TB3> INFO: Test took 29023ms.
[10:32:11.524] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:11.629] <TB3> INFO: dacScan step from 120 .. 149
[10:32:36.939] <TB3> INFO: Test took 25310ms.
[10:32:37.174] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:00.142] <TB3> INFO: PixTestBBMap::doTest() done, duration: 151 seconds
[10:33:00.142] <TB3> INFO: number of dead bumps (per ROC): 1 1 0 0 0 0 2 0 0 1 0 0 0 4 2 1
[10:33:00.142] <TB3> INFO: separation cut (per ROC): 103 101 99 71 91 94 85 95 78 105 104 108 71 83 87 73
[10:33:00.222] <TB3> INFO: ######################################################################
[10:33:00.222] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50
[10:33:00.222] <TB3> INFO: ######################################################################
[10:33:00.222] <TB3> INFO: ----------------------------------------------------------------------
[10:33:00.222] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50
[10:33:00.222] <TB3> INFO: ----------------------------------------------------------------------
[10:33:00.222] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (4) hits flags = 16 (plus default)
[10:33:00.230] <TB3> INFO: dacScan step from 0 .. 3
[10:33:19.145] <TB3> INFO: Test took 18914ms.
[10:33:19.170] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:19.170] <TB3> INFO: dacScan step from 4 .. 7
[10:33:38.076] <TB3> INFO: Test took 18906ms.
[10:33:38.110] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:38.110] <TB3> INFO: dacScan step from 8 .. 11
[10:33:57.067] <TB3> INFO: Test took 18957ms.
[10:33:57.097] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:33:57.097] <TB3> INFO: dacScan step from 12 .. 15
[10:34:16.019] <TB3> INFO: Test took 18922ms.
[10:34:16.047] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:34:16.047] <TB3> INFO: dacScan step from 16 .. 19
[10:34:34.903] <TB3> INFO: Test took 18857ms.
[10:34:34.929] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:34:34.929] <TB3> INFO: dacScan step from 20 .. 23
[10:34:53.925] <TB3> INFO: Test took 18996ms.
[10:34:53.956] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:34:53.956] <TB3> INFO: dacScan step from 24 .. 27
[10:35:12.922] <TB3> INFO: Test took 18966ms.
[10:35:12.949] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:35:12.949] <TB3> INFO: dacScan step from 28 .. 31
[10:35:31.813] <TB3> INFO: Test took 18864ms.
[10:35:31.841] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:35:31.841] <TB3> INFO: dacScan step from 32 .. 35
[10:35:50.745] <TB3> INFO: Test took 18903ms.
[10:35:50.770] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:35:50.770] <TB3> INFO: dacScan step from 36 .. 39
[10:36:09.656] <TB3> INFO: Test took 18886ms.
[10:36:09.681] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:09.681] <TB3> INFO: dacScan step from 40 .. 43
[10:36:28.532] <TB3> INFO: Test took 18851ms.
[10:36:28.559] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:28.559] <TB3> INFO: dacScan step from 44 .. 47
[10:36:47.399] <TB3> INFO: Test took 18840ms.
[10:36:47.427] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:36:47.427] <TB3> INFO: dacScan step from 48 .. 51
[10:37:06.329] <TB3> INFO: Test took 18902ms.
[10:37:06.357] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:06.357] <TB3> INFO: dacScan step from 52 .. 55
[10:37:25.242] <TB3> INFO: Test took 18885ms.
[10:37:25.273] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:25.273] <TB3> INFO: dacScan step from 56 .. 59
[10:37:44.284] <TB3> INFO: Test took 19010ms.
[10:37:44.311] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:44.311] <TB3> INFO: dacScan step from 60 .. 63
[10:38:03.403] <TB3> INFO: Test took 19092ms.
[10:38:03.436] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:03.437] <TB3> INFO: dacScan step from 64 .. 67
[10:38:22.820] <TB3> INFO: Test took 19383ms.
[10:38:22.863] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:22.863] <TB3> INFO: dacScan step from 68 .. 71
[10:38:42.674] <TB3> INFO: Test took 19811ms.
[10:38:42.726] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:42.727] <TB3> INFO: dacScan step from 72 .. 75
[10:39:02.875] <TB3> INFO: Test took 20148ms.
[10:39:02.944] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:39:02.946] <TB3> INFO: dacScan step from 76 .. 79
[10:39:24.208] <TB3> INFO: Test took 21262ms.
[10:39:24.293] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:39:24.295] <TB3> INFO: dacScan step from 80 .. 83
[10:39:47.488] <TB3> INFO: Test took 23192ms.
[10:39:47.607] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:39:47.612] <TB3> INFO: dacScan step from 84 .. 87
[10:40:12.803] <TB3> INFO: Test took 25191ms.
[10:40:12.958] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:12.965] <TB3> INFO: dacScan step from 88 .. 91
[10:40:39.339] <TB3> INFO: Test took 26373ms.
[10:40:39.520] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:39.529] <TB3> INFO: dacScan step from 92 .. 95
[10:41:07.257] <TB3> INFO: Test took 27728ms.
[10:41:07.467] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:07.481] <TB3> INFO: dacScan step from 96 .. 99



[10:41:35.904] <TB3> INFO: Test took 28423ms.
[10:41:36.122] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:36.132] <TB3> INFO: dacScan step from 100 .. 103


[10:42:05.320] <TB3> INFO: Test took 29188ms.
[10:42:05.581] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:42:05.592] <TB3> INFO: dacScan step from 104 .. 107



[10:42:35.044] <TB3> INFO: Test took 29452ms.
[10:42:35.325] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:42:35.337] <TB3> INFO: dacScan step from 108 .. 111


[10:43:04.614] <TB3> INFO: Test took 29277ms.
[10:43:04.840] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:04.851] <TB3> INFO: dacScan step from 112 .. 115


[10:43:34.215] <TB3> INFO: Test took 29364ms.
[10:43:34.440] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:34.452] <TB3> INFO: dacScan step from 116 .. 119





[10:44:03.846] <TB3> INFO: Test took 29394ms.
[10:44:04.141] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:04.153] <TB3> INFO: dacScan step from 120 .. 123
[10:44:33.325] <TB3> INFO: Test took 29172ms.
[10:44:33.580] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:33.592] <TB3> INFO: dacScan step from 124 .. 127
[10:45:02.695] <TB3> INFO: Test took 29103ms.
[10:45:02.990] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:45:03.001] <TB3> INFO: dacScan step from 128 .. 131
[10:45:31.982] <TB3> INFO: Test took 28981ms.
[10:45:32.196] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:45:32.207] <TB3> INFO: dacScan step from 132 .. 135
[10:46:01.132] <TB3> INFO: Test took 28925ms.
[10:46:01.412] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:46:01.423] <TB3> INFO: dacScan step from 136 .. 139
[10:46:30.530] <TB3> INFO: Test took 29107ms.
[10:46:30.856] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:46:30.872] <TB3> INFO: dacScan step from 140 .. 143
[10:47:00.061] <TB3> INFO: Test took 29189ms.
[10:47:00.282] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:47:00.294] <TB3> INFO: dacScan step from 144 .. 147
[10:47:29.285] <TB3> INFO: Test took 28991ms.
[10:47:29.523] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:47:29.535] <TB3> INFO: dacScan step from 148 .. 149
[10:47:45.386] <TB3> INFO: Test took 15851ms.
[10:47:45.496] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:47:45.502] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:47:46.899] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:47:48.361] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:47:49.861] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:47:51.344] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:47:52.846] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:47:54.341] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:47:55.748] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:47:57.179] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:47:58.624] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:48:00.025] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:48:01.451] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:48:02.868] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:48:04.357] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:48:05.826] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:48:07.448] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:48:09.134] <TB3> INFO: PixTestScurves::scurves() done
[10:48:09.134] <TB3> INFO: Vcal mean: 102.18 89.46 88.45 67.55 81.10 94.48 82.93 85.67 79.17 94.56 103.26 97.84 79.69 82.69 91.70 83.37
[10:48:09.134] <TB3> INFO: Vcal RMS: 5.81 5.18 5.02 4.84 4.22 4.69 4.22 5.04 4.02 5.94 5.90 5.87 4.38 5.44 6.77 4.69
[10:48:09.134] <TB3> INFO: PixTestScurves::fullTest() done, duration: 908 seconds
[10:48:09.205] <TB3> INFO: ######################################################################
[10:48:09.205] <TB3> INFO: PixTestTrim::doTest()
[10:48:09.205] <TB3> INFO: ######################################################################
[10:48:09.207] <TB3> INFO: ----------------------------------------------------------------------
[10:48:09.207] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:48:09.207] <TB3> INFO: ----------------------------------------------------------------------
[10:48:09.286] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:48:09.286] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20) hits flags = 16 (plus default)
[10:48:09.295] <TB3> INFO: dacScan step from 0 .. 19
[10:48:23.807] <TB3> INFO: Test took 14512ms.
[10:48:23.834] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:23.834] <TB3> INFO: dacScan step from 20 .. 39
[10:48:38.224] <TB3> INFO: Test took 14390ms.
[10:48:38.246] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:38.246] <TB3> INFO: dacScan step from 40 .. 59
[10:48:52.649] <TB3> INFO: Test took 14403ms.
[10:48:52.671] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:52.671] <TB3> INFO: dacScan step from 60 .. 79
[10:49:07.344] <TB3> INFO: Test took 14673ms.
[10:49:07.375] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:07.375] <TB3> INFO: dacScan step from 80 .. 99
[10:49:22.531] <TB3> INFO: Test took 15156ms.
[10:49:22.596] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:22.603] <TB3> INFO: dacScan step from 100 .. 119
[10:49:41.108] <TB3> INFO: Test took 18504ms.
[10:49:41.256] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:41.289] <TB3> INFO: dacScan step from 120 .. 139
[10:50:00.298] <TB3> INFO: Test took 19009ms.
[10:50:00.448] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:00.478] <TB3> INFO: dacScan step from 140 .. 159
[10:50:17.398] <TB3> INFO: Test took 16920ms.
[10:50:17.506] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:38.693] <TB3> INFO: ROC 0 VthrComp = 103
[10:50:38.693] <TB3> INFO: ROC 1 VthrComp = 98
[10:50:38.699] <TB3> INFO: ROC 2 VthrComp = 97
[10:50:38.699] <TB3> INFO: ROC 3 VthrComp = 75
[10:50:38.700] <TB3> INFO: ROC 4 VthrComp = 89
[10:50:38.700] <TB3> INFO: ROC 5 VthrComp = 101
[10:50:38.700] <TB3> INFO: ROC 6 VthrComp = 90
[10:50:38.700] <TB3> INFO: ROC 7 VthrComp = 92
[10:50:38.700] <TB3> INFO: ROC 8 VthrComp = 84
[10:50:38.700] <TB3> INFO: ROC 9 VthrComp = 101
[10:50:38.700] <TB3> INFO: ROC 10 VthrComp = 105
[10:50:38.700] <TB3> INFO: ROC 11 VthrComp = 102
[10:50:38.701] <TB3> INFO: ROC 12 VthrComp = 83
[10:50:38.701] <TB3> INFO: ROC 13 VthrComp = 86
[10:50:38.701] <TB3> INFO: ROC 14 VthrComp = 92
[10:50:38.778] <TB3> INFO: ROC 15 VthrComp = 86
[10:50:38.778] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:50:38.778] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20) hits flags = 16 (plus default)
[10:50:38.787] <TB3> INFO: dacScan step from 0 .. 19
[10:50:53.871] <TB3> INFO: Test took 15084ms.
[10:50:53.894] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:53.894] <TB3> INFO: dacScan step from 20 .. 39
[10:51:09.240] <TB3> INFO: Test took 15346ms.
[10:51:09.275] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:09.278] <TB3> INFO: dacScan step from 40 .. 59
[10:51:26.804] <TB3> INFO: Test took 17526ms.
[10:51:26.948] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:26.987] <TB3> INFO: dacScan step from 60 .. 79
[10:51:45.639] <TB3> INFO: Test took 18652ms.
[10:51:45.799] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:45.855] <TB3> INFO: dacScan step from 80 .. 99
[10:52:06.054] <TB3> INFO: Test took 20199ms.
[10:52:06.217] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:06.271] <TB3> INFO: dacScan step from 100 .. 119
[10:52:26.405] <TB3> INFO: Test took 20134ms.
[10:52:26.562] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:26.617] <TB3> INFO: dacScan step from 120 .. 139
[10:52:46.677] <TB3> INFO: Test took 20060ms.
[10:52:46.835] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:46.889] <TB3> INFO: dacScan step from 140 .. 159
[10:53:07.022] <TB3> INFO: Test took 20133ms.
[10:53:07.219] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:31.500] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 61.1966 for pixel 20/2 mean/min/max = 46.6148/31.7702/61.4595
[10:53:31.500] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 56.93 for pixel 4/6 mean/min/max = 44.0698/31.0891/57.0505
[10:53:31.500] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 56.5854 for pixel 51/18 mean/min/max = 44.2729/31.9339/56.612
[10:53:31.500] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 57.0142 for pixel 23/57 mean/min/max = 45.8313/34.5866/57.076
[10:53:31.501] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 56.102 for pixel 2/21 mean/min/max = 44.3189/32.2492/56.3886
[10:53:31.501] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 56.2169 for pixel 28/3 mean/min/max = 44.3123/32.0958/56.5289
[10:53:31.501] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 56.1908 for pixel 7/79 mean/min/max = 44.6069/32.9409/56.273
[10:53:31.501] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 57.6297 for pixel 3/79 mean/min/max = 45.2359/32.6546/57.8171
[10:53:31.502] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 56.6987 for pixel 0/56 mean/min/max = 44.7522/32.7022/56.8022
[10:53:31.502] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 59.39 for pixel 19/1 mean/min/max = 45.54/31.4961/59.5839
[10:53:31.502] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 61.4595 for pixel 2/2 mean/min/max = 47.2477/32.9452/61.5503
[10:53:31.502] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 61.3074 for pixel 18/12 mean/min/max = 46.6115/31.9122/61.3107
[10:53:31.503] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 57.599 for pixel 28/74 mean/min/max = 44.904/32.1669/57.6412
[10:53:31.503] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 58.7111 for pixel 9/2 mean/min/max = 45.1687/31.2602/59.0772
[10:53:31.503] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 61.5833 for pixel 7/67 mean/min/max = 46.8173/31.9388/61.6959
[10:53:31.503] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 58.6134 for pixel 3/1 mean/min/max = 45.5971/32.5565/58.6378
[10:53:31.504] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:55:04.296] <TB3> INFO: Test took 92793ms.
[10:55:05.947] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20) hits flags = 16 (plus default)
[10:55:05.956] <TB3> INFO: dacScan step from 0 .. 19
[10:55:28.778] <TB3> INFO: Test took 22822ms.
[10:55:28.828] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:28.830] <TB3> INFO: dacScan step from 20 .. 39
[10:55:57.764] <TB3> INFO: Test took 28934ms.
[10:55:57.994] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:58.026] <TB3> INFO: dacScan step from 40 .. 59


[10:56:30.926] <TB3> INFO: Test took 32900ms.
[10:56:31.240] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:31.297] <TB3> INFO: dacScan step from 60 .. 79


[10:57:04.348] <TB3> INFO: Test took 33051ms.
[10:57:04.617] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:04.672] <TB3> INFO: dacScan step from 80 .. 99



[10:57:37.914] <TB3> INFO: Test took 33242ms.
[10:57:38.200] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:38.257] <TB3> INFO: dacScan step from 100 .. 119


[10:58:11.363] <TB3> INFO: Test took 33106ms.
[10:58:11.647] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:58:11.705] <TB3> INFO: dacScan step from 120 .. 139


[10:58:45.004] <TB3> INFO: Test took 33299ms.
[10:58:45.291] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:58:45.347] <TB3> INFO: dacScan step from 140 .. 159

[10:59:18.443] <TB3> INFO: Test took 33096ms.
[10:59:18.708] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:59:18.762] <TB3> INFO: dacScan step from 160 .. 179


[10:59:51.806] <TB3> INFO: Test took 33044ms.
[10:59:52.083] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:59:52.137] <TB3> INFO: dacScan step from 180 .. 199



[11:00:26.055] <TB3> INFO: Test took 33917ms.
[11:00:26.343] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:00:50.970] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.034453 .. 255.000000
[11:00:51.045] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 255 (20) hits flags = 16 (plus default)
[11:00:51.053] <TB3> INFO: dacScan step from 0 .. 19
[11:01:04.610] <TB3> INFO: Test took 13557ms.
[11:01:04.641] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:04.641] <TB3> INFO: dacScan step from 20 .. 39
[11:01:19.520] <TB3> INFO: Test took 14879ms.
[11:01:19.627] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:19.647] <TB3> INFO: dacScan step from 40 .. 59
[11:01:37.240] <TB3> INFO: Test took 17593ms.
[11:01:37.389] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:37.441] <TB3> INFO: dacScan step from 60 .. 79
[11:01:55.183] <TB3> INFO: Test took 17741ms.
[11:01:55.375] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:55.429] <TB3> INFO: dacScan step from 80 .. 99
[11:02:12.135] <TB3> INFO: Test took 16706ms.
[11:02:12.279] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:12.346] <TB3> INFO: dacScan step from 100 .. 119
[11:02:28.978] <TB3> INFO: Test took 16631ms.
[11:02:29.152] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:29.207] <TB3> INFO: dacScan step from 120 .. 139
[11:02:46.829] <TB3> INFO: Test took 17622ms.
[11:02:46.979] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:47.037] <TB3> INFO: dacScan step from 140 .. 159
[11:03:04.708] <TB3> INFO: Test took 17671ms.
[11:03:04.856] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:03:04.915] <TB3> INFO: dacScan step from 160 .. 179
[11:03:22.741] <TB3> INFO: Test took 17826ms.
[11:03:22.883] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:03:22.939] <TB3> INFO: dacScan step from 180 .. 199
[11:03:41.417] <TB3> INFO: Test took 18478ms.
[11:03:41.558] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:03:41.612] <TB3> INFO: dacScan step from 200 .. 219
[11:03:59.150] <TB3> INFO: Test took 17538ms.
[11:03:59.292] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:03:59.348] <TB3> INFO: dacScan step from 220 .. 239
[11:04:16.966] <TB3> INFO: Test took 17617ms.
[11:04:17.115] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:04:17.169] <TB3> INFO: dacScan step from 240 .. 255
[11:04:31.917] <TB3> INFO: Test took 14748ms.
[11:04:32.063] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:02.842] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 14.983778 .. 71.070662
[11:05:02.916] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 4 .. 81 (20) hits flags = 16 (plus default)
[11:05:02.924] <TB3> INFO: dacScan step from 4 .. 23
[11:05:16.428] <TB3> INFO: Test took 13504ms.
[11:05:16.451] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:16.451] <TB3> INFO: dacScan step from 24 .. 43
[11:05:31.953] <TB3> INFO: Test took 15502ms.
[11:05:32.060] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:32.085] <TB3> INFO: dacScan step from 44 .. 63
[11:05:49.768] <TB3> INFO: Test took 17682ms.
[11:05:49.915] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:49.969] <TB3> INFO: dacScan step from 64 .. 81
[11:06:05.928] <TB3> INFO: Test took 15959ms.
[11:06:06.084] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:06:25.303] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 20.655315 .. 44.435614
[11:06:25.378] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 10 .. 54 (20) hits flags = 16 (plus default)
[11:06:25.386] <TB3> INFO: dacScan step from 10 .. 29
[11:06:38.408] <TB3> INFO: Test took 13022ms.
[11:06:38.429] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:06:38.429] <TB3> INFO: dacScan step from 30 .. 49
[11:06:55.161] <TB3> INFO: Test took 16732ms.
[11:06:55.309] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:06:55.353] <TB3> INFO: dacScan step from 50 .. 54
[11:07:01.989] <TB3> INFO: Test took 6636ms.
[11:07:02.035] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:07:17.575] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 3.703502 .. 44.435614
[11:07:17.664] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 3 .. 54 (20) hits flags = 16 (plus default)
[11:07:17.672] <TB3> INFO: dacScan step from 3 .. 22
[11:07:30.764] <TB3> INFO: Test took 13092ms.
[11:07:30.789] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:07:30.789] <TB3> INFO: dacScan step from 23 .. 42
[11:07:46.045] <TB3> INFO: Test took 15256ms.
[11:07:46.144] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:07:46.167] <TB3> INFO: dacScan step from 43 .. 54
[11:07:57.977] <TB3> INFO: Test took 11810ms.
[11:07:58.072] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:08:13.044] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[11:08:13.044] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20) hits flags = 16 (plus default)
[11:08:13.052] <TB3> INFO: dacScan step from 15 .. 34
[11:08:36.295] <TB3> INFO: Test took 23243ms.
[11:08:36.365] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:08:36.376] <TB3> INFO: dacScan step from 35 .. 54


[11:09:07.140] <TB3> INFO: Test took 30764ms.
[11:09:07.416] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:07.471] <TB3> INFO: dacScan step from 55 .. 55
[11:09:11.670] <TB3> INFO: Test took 4199ms.
[11:09:11.684] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:25.406] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C0.dat
[11:09:25.406] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C1.dat
[11:09:25.406] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C2.dat
[11:09:25.406] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C3.dat
[11:09:25.407] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C4.dat
[11:09:25.407] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C5.dat
[11:09:25.407] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C6.dat
[11:09:25.407] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C7.dat
[11:09:25.407] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C8.dat
[11:09:25.408] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C9.dat
[11:09:25.408] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C10.dat
[11:09:25.408] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C11.dat
[11:09:25.408] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C12.dat
[11:09:25.408] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C13.dat
[11:09:25.409] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C14.dat
[11:09:25.409] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C15.dat
[11:09:25.409] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//trimParameters35_C0.dat
[11:09:25.419] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//trimParameters35_C1.dat
[11:09:25.426] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//trimParameters35_C2.dat
[11:09:25.432] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//trimParameters35_C3.dat
[11:09:25.439] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//trimParameters35_C4.dat
[11:09:25.445] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//trimParameters35_C5.dat
[11:09:25.452] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//trimParameters35_C6.dat
[11:09:25.459] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//trimParameters35_C7.dat
[11:09:25.465] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//trimParameters35_C8.dat
[11:09:25.472] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//trimParameters35_C9.dat
[11:09:25.479] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//trimParameters35_C10.dat
[11:09:25.485] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//trimParameters35_C11.dat
[11:09:25.492] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//trimParameters35_C12.dat
[11:09:25.499] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//trimParameters35_C13.dat
[11:09:25.505] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//trimParameters35_C14.dat
[11:09:25.512] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//trimParameters35_C15.dat
[11:09:25.518] <TB3> INFO: PixTestTrim::trimTest() done
[11:09:25.518] <TB3> INFO: vtrim: 121 112 106 105 174 108 105 115 108 116 122 118 115 102 110 116
[11:09:25.518] <TB3> INFO: vthrcomp: 103 98 97 75 89 101 90 92 84 101 105 102 83 86 92 86
[11:09:25.518] <TB3> INFO: vcal mean: 35.03 35.01 35.03 35.06 34.97 35.05 35.05 35.01 35.00 34.99 35.01 35.06 35.05 35.05 35.02 35.05
[11:09:25.518] <TB3> INFO: vcal RMS: 1.08 0.98 0.97 0.96 1.61 1.00 0.99 0.94 0.98 1.00 1.02 1.11 1.19 1.23 1.12 1.08
[11:09:25.518] <TB3> INFO: bits mean: 9.67 10.50 9.79 9.50 11.94 10.30 9.99 9.85 9.91 9.82 9.47 9.58 10.10 9.85 9.42 9.99
[11:09:25.518] <TB3> INFO: bits RMS: 2.52 2.38 2.62 2.31 1.53 2.35 2.38 2.47 2.50 2.57 2.49 2.58 2.42 2.65 2.66 2.39
[11:09:25.526] <TB3> INFO: ----------------------------------------------------------------------
[11:09:25.526] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[11:09:25.526] <TB3> INFO: ----------------------------------------------------------------------
[11:09:25.529] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20) hits flags = 16 (plus default)
[11:09:25.539] <TB3> INFO: dacScan step from 0 .. 19
[11:09:48.152] <TB3> INFO: Test took 22613ms.
[11:09:48.187] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:48.187] <TB3> INFO: dacScan step from 20 .. 39
[11:10:10.564] <TB3> INFO: Test took 22377ms.
[11:10:10.599] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:10.599] <TB3> INFO: dacScan step from 40 .. 59
[11:10:31.781] <TB3> INFO: Test took 21182ms.
[11:10:31.815] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:31.815] <TB3> INFO: dacScan step from 60 .. 79
[11:10:54.392] <TB3> INFO: Test took 22577ms.
[11:10:54.429] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:54.429] <TB3> INFO: dacScan step from 80 .. 99
[11:11:17.863] <TB3> INFO: Test took 23434ms.
[11:11:17.939] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:11:17.941] <TB3> INFO: dacScan step from 100 .. 119
[11:11:46.023] <TB3> INFO: Test took 28081ms.
[11:11:46.235] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:11:46.256] <TB3> INFO: dacScan step from 120 .. 139


[11:12:18.932] <TB3> INFO: Test took 32675ms.
[11:12:19.209] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:12:19.264] <TB3> INFO: dacScan step from 140 .. 159


[11:12:52.492] <TB3> INFO: Test took 33228ms.
[11:12:52.761] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:12:52.817] <TB3> INFO: dacScan step from 160 .. 179
[11:13:26.012] <TB3> INFO: Test took 33195ms.
[11:13:26.275] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:26.328] <TB3> INFO: dacScan step from 180 .. 199
[11:14:00.105] <TB3> INFO: Test took 33777ms.
[11:14:00.371] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:25.896] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 177 (20) hits flags = 16 (plus default)
[11:14:25.904] <TB3> INFO: dacScan step from 0 .. 19
[11:14:48.452] <TB3> INFO: Test took 22548ms.
[11:14:48.488] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:48.488] <TB3> INFO: dacScan step from 20 .. 39
[11:15:10.813] <TB3> INFO: Test took 22325ms.
[11:15:10.858] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:10.858] <TB3> INFO: dacScan step from 40 .. 59
[11:15:32.064] <TB3> INFO: Test took 21205ms.
[11:15:32.098] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:32.098] <TB3> INFO: dacScan step from 60 .. 79
[11:15:54.655] <TB3> INFO: Test took 22557ms.
[11:15:54.701] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:54.701] <TB3> INFO: dacScan step from 80 .. 99
[11:16:19.456] <TB3> INFO: Test took 24755ms.
[11:16:19.581] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:19.591] <TB3> INFO: dacScan step from 100 .. 119
[11:16:50.615] <TB3> INFO: Test took 31024ms.
[11:16:50.866] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:50.899] <TB3> INFO: dacScan step from 120 .. 139





[11:17:24.073] <TB3> INFO: Test took 33174ms.
[11:17:24.370] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:24.424] <TB3> INFO: dacScan step from 140 .. 159





[11:17:57.488] <TB3> INFO: Test took 33063ms.
[11:17:57.762] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:57.817] <TB3> INFO: dacScan step from 160 .. 177
[11:18:25.436] <TB3> INFO: Test took 27620ms.
[11:18:25.676] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:49.856] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 163 (20) hits flags = 16 (plus default)
[11:18:49.865] <TB3> INFO: dacScan step from 0 .. 19
[11:19:11.240] <TB3> INFO: Test took 21375ms.
[11:19:11.276] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:11.276] <TB3> INFO: dacScan step from 20 .. 39
[11:19:32.492] <TB3> INFO: Test took 21216ms.
[11:19:32.538] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:32.538] <TB3> INFO: dacScan step from 40 .. 59
[11:19:53.707] <TB3> INFO: Test took 21169ms.
[11:19:53.741] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:53.741] <TB3> INFO: dacScan step from 60 .. 79
[11:20:16.471] <TB3> INFO: Test took 22730ms.
[11:20:16.513] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:16.513] <TB3> INFO: dacScan step from 80 .. 99
[11:20:41.459] <TB3> INFO: Test took 24946ms.
[11:20:41.581] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:41.590] <TB3> INFO: dacScan step from 100 .. 119
[11:21:12.996] <TB3> INFO: Test took 31406ms.
[11:21:13.248] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:13.281] <TB3> INFO: dacScan step from 120 .. 139


[11:21:46.282] <TB3> INFO: Test took 33001ms.
[11:21:46.592] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:46.655] <TB3> INFO: dacScan step from 140 .. 159





[11:22:19.686] <TB3> INFO: Test took 33031ms.
[11:22:19.962] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:20.019] <TB3> INFO: dacScan step from 160 .. 163
[11:22:28.912] <TB3> INFO: Test took 8893ms.
[11:22:28.970] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:52.441] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 163 (20) hits flags = 16 (plus default)
[11:22:52.449] <TB3> INFO: dacScan step from 0 .. 19
[11:23:14.999] <TB3> INFO: Test took 22550ms.
[11:23:15.038] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:15.038] <TB3> INFO: dacScan step from 20 .. 39
[11:23:36.103] <TB3> INFO: Test took 21065ms.
[11:23:36.137] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:36.137] <TB3> INFO: dacScan step from 40 .. 59
[11:23:57.136] <TB3> INFO: Test took 21000ms.
[11:23:57.170] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:57.170] <TB3> INFO: dacScan step from 60 .. 79
[11:24:19.813] <TB3> INFO: Test took 22643ms.
[11:24:19.863] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:24:19.864] <TB3> INFO: dacScan step from 80 .. 99
[11:24:44.630] <TB3> INFO: Test took 24766ms.
[11:24:44.753] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:24:44.763] <TB3> INFO: dacScan step from 100 .. 119
[11:25:15.772] <TB3> INFO: Test took 31009ms.
[11:25:16.069] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:16.110] <TB3> INFO: dacScan step from 120 .. 139


[11:25:49.192] <TB3> INFO: Test took 33082ms.
[11:25:49.486] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:49.537] <TB3> INFO: dacScan step from 140 .. 159





[11:26:22.781] <TB3> INFO: Test took 33244ms.
[11:26:23.054] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:23.107] <TB3> INFO: dacScan step from 160 .. 163
[11:26:31.924] <TB3> INFO: Test took 8817ms.
[11:26:31.984] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:56.330] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 161 (20) hits flags = 16 (plus default)
[11:26:56.338] <TB3> INFO: dacScan step from 0 .. 19
[11:27:18.364] <TB3> INFO: Test took 22026ms.
[11:27:18.398] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:18.398] <TB3> INFO: dacScan step from 20 .. 39
[11:27:40.022] <TB3> INFO: Test took 21624ms.
[11:27:40.061] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:40.061] <TB3> INFO: dacScan step from 40 .. 59
[11:28:01.300] <TB3> INFO: Test took 21239ms.
[11:28:01.334] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:01.334] <TB3> INFO: dacScan step from 60 .. 79
[11:28:24.103] <TB3> INFO: Test took 22769ms.
[11:28:24.145] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:24.145] <TB3> INFO: dacScan step from 80 .. 99
[11:28:48.879] <TB3> INFO: Test took 24734ms.
[11:28:49.003] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:49.013] <TB3> INFO: dacScan step from 100 .. 119
[11:29:20.084] <TB3> INFO: Test took 31071ms.
[11:29:20.338] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:20.371] <TB3> INFO: dacScan step from 120 .. 139


[11:29:53.429] <TB3> INFO: Test took 33057ms.
[11:29:53.719] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:53.773] <TB3> INFO: dacScan step from 140 .. 159
[11:30:26.613] <TB3> INFO: Test took 32840ms.
[11:30:26.892] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:26.949] <TB3> INFO: dacScan step from 160 .. 161
[11:30:32.772] <TB3> INFO: Test took 5823ms.
[11:30:32.803] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:55.442] <TB3> INFO: PixTestTrim::trimBitTest() done
[11:30:55.443] <TB3> INFO: PixTestTrim::doTest() done, duration: 2566 seconds
[11:30:56.179] <TB3> INFO: ######################################################################
[11:30:56.179] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[11:30:56.179] <TB3> INFO: ######################################################################
[11:30:59.608] <TB3> INFO: Test took 3428ms.
[11:30:59.630] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:03.265] <TB3> INFO: Test took 3437ms.
[11:31:03.342] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:06.806] <TB3> INFO: Test took 3454ms.
[11:31:06.874] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:06.881] <TB3> INFO: The DUT currently contains the following objects:
[11:31:06.881] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:31:06.881] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:31:06.881] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:31:06.881] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:31:06.881] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:06.881] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:06.881] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:06.881] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:06.881] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:06.881] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:06.881] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:06.881] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:06.881] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:06.881] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:06.881] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:06.881] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:06.881] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:06.881] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:06.881] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:06.881] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:07.990] <TB3> INFO: Test took 1109ms.
[11:31:07.991] <TB3> INFO: The DUT currently contains the following objects:
[11:31:07.992] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:31:07.992] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:31:07.992] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:31:07.992] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:31:07.992] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:07.992] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:07.992] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:07.992] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:07.992] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:07.992] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:07.992] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:07.992] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:07.992] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:07.992] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:07.992] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:07.992] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:07.992] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:07.992] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:07.992] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:07.992] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:09.100] <TB3> INFO: Test took 1108ms.
[11:31:09.101] <TB3> INFO: The DUT currently contains the following objects:
[11:31:09.101] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:31:09.101] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:31:09.101] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:31:09.101] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:31:09.101] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:09.101] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:09.101] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:09.101] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:09.101] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:09.101] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:09.101] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:09.101] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:09.101] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:09.101] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:09.101] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:09.101] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:09.101] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:09.101] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:09.102] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:09.102] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:10.205] <TB3> INFO: Test took 1103ms.
[11:31:10.205] <TB3> INFO: The DUT currently contains the following objects:
[11:31:10.205] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:31:10.205] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:31:10.205] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:31:10.205] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:31:10.206] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:10.206] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:10.206] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:10.206] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:10.206] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:10.206] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:10.206] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:10.206] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:10.206] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:10.206] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:10.206] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:10.206] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:10.206] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:10.206] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:10.206] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:10.206] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:11.307] <TB3> INFO: Test took 1101ms.
[11:31:11.307] <TB3> INFO: The DUT currently contains the following objects:
[11:31:11.307] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:31:11.307] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:31:11.307] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:31:11.308] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:31:11.308] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:11.308] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:11.308] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:11.308] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:11.308] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:11.308] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:11.308] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:11.308] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:11.308] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:11.308] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:11.308] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:11.308] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:11.308] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:11.308] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:11.308] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:11.308] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:12.410] <TB3> INFO: Test took 1102ms.
[11:31:12.411] <TB3> INFO: The DUT currently contains the following objects:
[11:31:12.411] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:31:12.411] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:31:12.411] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:31:12.411] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:31:12.411] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:12.411] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:12.411] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:12.411] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:12.411] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:12.411] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:12.411] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:12.411] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:12.411] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:12.411] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:12.411] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:12.411] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:12.411] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:12.411] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:12.411] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:12.411] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:13.514] <TB3> INFO: Test took 1103ms.
[11:31:13.515] <TB3> INFO: The DUT currently contains the following objects:
[11:31:13.515] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:31:13.515] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:31:13.515] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:31:13.515] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:31:13.515] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:13.515] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:13.515] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:13.515] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:13.515] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:13.515] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:13.515] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:13.515] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:13.515] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:13.515] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:13.515] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:13.515] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:13.515] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:13.515] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:13.515] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:13.516] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:14.616] <TB3> INFO: Test took 1100ms.
[11:31:14.617] <TB3> INFO: The DUT currently contains the following objects:
[11:31:14.617] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:31:14.617] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:31:14.617] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:31:14.617] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:31:14.617] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:14.617] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:14.617] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:14.617] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:14.617] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:14.617] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:14.617] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:14.617] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:14.617] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:14.617] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:14.617] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:14.617] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:14.617] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:14.617] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:14.617] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:14.617] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:15.719] <TB3> INFO: Test took 1102ms.
[11:31:15.719] <TB3> INFO: The DUT currently contains the following objects:
[11:31:15.719] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:31:15.719] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:31:15.719] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:31:15.720] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:31:15.720] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:15.720] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:15.720] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:15.720] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:15.720] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:15.720] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:15.720] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:15.720] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:15.720] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:15.720] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:15.720] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:15.720] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:15.720] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:15.720] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:15.720] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:15.720] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:16.822] <TB3> INFO: Test took 1102ms.
[11:31:16.822] <TB3> INFO: The DUT currently contains the following objects:
[11:31:16.822] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:31:16.822] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:31:16.822] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:31:16.822] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:31:16.822] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:16.822] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:16.822] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:16.823] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:16.823] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:16.823] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:16.823] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:16.823] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:16.823] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:16.823] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:16.823] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:16.823] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:16.823] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:16.823] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:16.823] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:16.823] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:17.924] <TB3> INFO: Test took 1101ms.
[11:31:17.924] <TB3> INFO: The DUT currently contains the following objects:
[11:31:17.924] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:31:17.924] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:31:17.924] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:31:17.924] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:31:17.924] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:17.924] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:17.924] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:17.924] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:17.924] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:17.924] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:17.924] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:17.924] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:17.924] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:17.924] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:17.924] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:17.924] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:17.924] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:17.924] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:17.924] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:17.924] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:19.027] <TB3> INFO: Test took 1103ms.
[11:31:19.028] <TB3> INFO: The DUT currently contains the following objects:
[11:31:19.028] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:31:19.028] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:31:19.028] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:31:19.028] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:31:19.028] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:19.028] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:19.028] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:19.028] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:19.028] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:19.028] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:19.028] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:19.028] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:19.028] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:19.028] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:19.028] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:19.028] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:19.028] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:19.028] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:19.028] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:19.028] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:20.129] <TB3> INFO: Test took 1101ms.
[11:31:20.129] <TB3> INFO: The DUT currently contains the following objects:
[11:31:20.129] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:31:20.129] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:31:20.129] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:31:20.129] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:31:20.129] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:20.129] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:20.129] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:20.129] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:20.129] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:20.130] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:20.130] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:20.130] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:20.130] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:20.130] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:20.130] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:20.130] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:20.130] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:20.130] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:20.130] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:20.130] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:21.231] <TB3> INFO: Test took 1101ms.
[11:31:21.231] <TB3> INFO: The DUT currently contains the following objects:
[11:31:21.235] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:31:21.235] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:31:21.235] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:31:21.235] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:31:21.235] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:21.235] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:21.235] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:21.235] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:21.236] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:21.236] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:21.236] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:21.236] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:21.236] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:21.236] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:21.236] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:21.236] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:21.236] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:21.236] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:21.236] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:21.236] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:22.333] <TB3> INFO: Test took 1097ms.
[11:31:22.333] <TB3> INFO: The DUT currently contains the following objects:
[11:31:22.333] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:31:22.333] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:31:22.333] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:31:22.333] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:31:22.333] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:22.333] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:22.333] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:22.333] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:22.333] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:22.333] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:22.333] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:22.333] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:22.333] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:22.333] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:22.333] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:22.333] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:22.333] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:22.333] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:22.333] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:22.333] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:23.435] <TB3> INFO: Test took 1102ms.
[11:31:23.436] <TB3> INFO: The DUT currently contains the following objects:
[11:31:23.436] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:31:23.436] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:31:23.436] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:31:23.436] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:31:23.436] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:23.436] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:23.436] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:23.436] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:23.436] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:23.436] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:23.436] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:23.436] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:23.436] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:23.436] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:23.436] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:23.436] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:23.436] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:23.436] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:23.436] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:23.436] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:31:24.537] <TB3> INFO: Test took 1101ms.
[11:31:24.540] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:35:07.939] <TB3> INFO: Test took 223399ms.
[11:35:09.725] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:55.057] <TB3> INFO: Test took 225332ms.
[11:38:56.830] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:56.837] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:56.844] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:56.851] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:56.858] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:56.864] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:56.871] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:56.878] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:56.885] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:56.892] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:56.899] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:56.906] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:56.912] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:56.919] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:56.926] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:56.933] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:38:56.971] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C0.dat
[11:38:56.971] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C1.dat
[11:38:56.971] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C2.dat
[11:38:56.971] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C3.dat
[11:38:56.971] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C4.dat
[11:38:56.972] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C5.dat
[11:38:56.972] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C6.dat
[11:38:56.972] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C7.dat
[11:38:56.972] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C8.dat
[11:38:56.972] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C9.dat
[11:38:56.972] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C10.dat
[11:38:56.972] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C11.dat
[11:38:56.972] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C12.dat
[11:38:56.973] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C13.dat
[11:38:56.973] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C14.dat
[11:38:56.973] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//dacParameters35_C15.dat
[11:39:00.416] <TB3> INFO: Test took 3440ms.
[11:39:04.130] <TB3> INFO: Test took 3431ms.
[11:39:07.869] <TB3> INFO: Test took 3469ms.
[11:39:08.158] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:09.078] <TB3> INFO: Test took 920ms.
[11:39:09.082] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:10.188] <TB3> INFO: Test took 1106ms.
[11:39:10.191] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:11.299] <TB3> INFO: Test took 1108ms.
[11:39:11.303] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:12.426] <TB3> INFO: Test took 1123ms.
[11:39:12.429] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:13.538] <TB3> INFO: Test took 1109ms.
[11:39:13.541] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:14.649] <TB3> INFO: Test took 1108ms.
[11:39:14.653] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:15.765] <TB3> INFO: Test took 1112ms.
[11:39:15.768] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:16.876] <TB3> INFO: Test took 1108ms.
[11:39:16.880] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:17.987] <TB3> INFO: Test took 1107ms.
[11:39:17.991] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:19.099] <TB3> INFO: Test took 1108ms.
[11:39:19.102] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:20.211] <TB3> INFO: Test took 1109ms.
[11:39:20.215] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:21.323] <TB3> INFO: Test took 1108ms.
[11:39:21.326] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:22.434] <TB3> INFO: Test took 1108ms.
[11:39:22.438] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:23.546] <TB3> INFO: Test took 1109ms.
[11:39:23.549] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:24.660] <TB3> INFO: Test took 1111ms.
[11:39:24.663] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:25.774] <TB3> INFO: Test took 1111ms.
[11:39:25.777] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:26.887] <TB3> INFO: Test took 1110ms.
[11:39:26.890] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:28.000] <TB3> INFO: Test took 1110ms.
[11:39:28.004] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:29.111] <TB3> INFO: Test took 1108ms.
[11:39:29.115] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:30.222] <TB3> INFO: Test took 1107ms.
[11:39:30.225] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:31.333] <TB3> INFO: Test took 1108ms.
[11:39:31.336] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:32.445] <TB3> INFO: Test took 1109ms.
[11:39:32.448] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:33.556] <TB3> INFO: Test took 1108ms.
[11:39:33.560] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:34.667] <TB3> INFO: Test took 1108ms.
[11:39:34.671] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:35.779] <TB3> INFO: Test took 1108ms.
[11:39:35.782] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:36.890] <TB3> INFO: Test took 1109ms.
[11:39:36.894] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:38.002] <TB3> INFO: Test took 1108ms.
[11:39:38.006] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:39.115] <TB3> INFO: Test took 1109ms.
[11:39:39.119] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:40.226] <TB3> INFO: Test took 1108ms.
[11:39:40.230] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:41.337] <TB3> INFO: Test took 1108ms.
[11:39:41.340] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:42.449] <TB3> INFO: Test took 1109ms.
[11:39:42.452] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:43.561] <TB3> INFO: Test took 1109ms.
[11:39:44.107] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 527 seconds
[11:39:44.107] <TB3> INFO: PH scale (per ROC): 81 92 87 94 82 80 81 78 80 79 79 84 87 90 74 81
[11:39:44.107] <TB3> INFO: PH offset (per ROC): 157 177 142 130 157 151 163 162 149 159 153 159 144 150 171 165
[11:39:44.282] <TB3> INFO: ######################################################################
[11:39:44.282] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:39:44.282] <TB3> INFO: ######################################################################
[11:39:44.304] <TB3> INFO: scanning low vcal = 10
[11:39:48.161] <TB3> INFO: Test took 3857ms.
[11:39:48.166] <TB3> INFO: scanning low vcal = 20
[11:39:52.042] <TB3> INFO: Test took 3876ms.
[11:39:52.047] <TB3> INFO: scanning low vcal = 30
[11:39:55.921] <TB3> INFO: Test took 3874ms.
[11:39:55.931] <TB3> INFO: scanning low vcal = 40
[11:40:00.260] <TB3> INFO: Test took 4329ms.
[11:40:00.334] <TB3> INFO: scanning low vcal = 50
[11:40:04.684] <TB3> INFO: Test took 4350ms.
[11:40:04.745] <TB3> INFO: scanning low vcal = 60
[11:40:09.110] <TB3> INFO: Test took 4365ms.
[11:40:09.174] <TB3> INFO: scanning low vcal = 70
[11:40:13.505] <TB3> INFO: Test took 4331ms.
[11:40:13.567] <TB3> INFO: scanning low vcal = 80
[11:40:17.940] <TB3> INFO: Test took 4373ms.
[11:40:17.997] <TB3> INFO: scanning low vcal = 90
[11:40:22.353] <TB3> INFO: Test took 4356ms.
[11:40:22.416] <TB3> INFO: scanning low vcal = 100
[11:40:26.719] <TB3> INFO: Test took 4302ms.
[11:40:26.778] <TB3> INFO: scanning low vcal = 110
[11:40:31.103] <TB3> INFO: Test took 4325ms.
[11:40:31.166] <TB3> INFO: scanning low vcal = 120
[11:40:35.533] <TB3> INFO: Test took 4367ms.
[11:40:35.597] <TB3> INFO: scanning low vcal = 130
[11:40:39.957] <TB3> INFO: Test took 4360ms.
[11:40:40.017] <TB3> INFO: scanning low vcal = 140
[11:40:44.388] <TB3> INFO: Test took 4371ms.
[11:40:44.453] <TB3> INFO: scanning low vcal = 150
[11:40:48.832] <TB3> INFO: Test took 4379ms.
[11:40:48.901] <TB3> INFO: scanning low vcal = 160
[11:40:53.262] <TB3> INFO: Test took 4361ms.
[11:40:53.326] <TB3> INFO: scanning low vcal = 170
[11:40:57.551] <TB3> INFO: Test took 4225ms.
[11:40:57.622] <TB3> INFO: scanning low vcal = 180
[11:41:01.958] <TB3> INFO: Test took 4336ms.
[11:41:02.015] <TB3> INFO: scanning low vcal = 190
[11:41:06.446] <TB3> INFO: Test took 4431ms.
[11:41:06.506] <TB3> INFO: scanning low vcal = 200
[11:41:10.846] <TB3> INFO: Test took 4340ms.
[11:41:10.906] <TB3> INFO: scanning low vcal = 210
[11:41:15.276] <TB3> INFO: Test took 4370ms.
[11:41:15.348] <TB3> INFO: scanning low vcal = 220
[11:41:19.689] <TB3> INFO: Test took 4341ms.
[11:41:19.774] <TB3> INFO: scanning low vcal = 230
[11:41:24.128] <TB3> INFO: Test took 4354ms.
[11:41:24.186] <TB3> INFO: scanning low vcal = 240
[11:41:28.566] <TB3> INFO: Test took 4380ms.
[11:41:28.629] <TB3> INFO: scanning low vcal = 250
[11:41:32.993] <TB3> INFO: Test took 4364ms.
[11:41:33.071] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[11:41:37.474] <TB3> INFO: Test took 4403ms.
[11:41:37.537] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[11:41:41.903] <TB3> INFO: Test took 4366ms.
[11:41:41.986] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[11:41:46.297] <TB3> INFO: Test took 4310ms.
[11:41:46.363] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[11:41:50.726] <TB3> INFO: Test took 4363ms.
[11:41:50.788] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:41:55.117] <TB3> INFO: Test took 4329ms.
[11:41:55.673] <TB3> INFO: PixTestGainPedestal::measure() done
[11:42:27.463] <TB3> INFO: PixTestGainPedestal::fit() done
[11:42:27.463] <TB3> INFO: non-linearity mean: 0.947 0.950 0.951 0.956 0.956 0.955 0.957 0.951 0.956 0.956 0.963 0.955 0.955 0.958 0.953 0.952
[11:42:27.463] <TB3> INFO: non-linearity RMS: 0.006 0.006 0.006 0.005 0.007 0.005 0.006 0.005 0.007 0.006 0.005 0.007 0.006 0.005 0.006 0.006
[11:42:27.463] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[11:42:27.482] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[11:42:27.502] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[11:42:27.521] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[11:42:27.540] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[11:42:27.559] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[11:42:27.579] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[11:42:27.598] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[11:42:27.617] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[11:42:27.636] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[11:42:27.656] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[11:42:27.675] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[11:42:27.694] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[11:42:27.713] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[11:42:27.733] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[11:42:27.752] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2075_FullQualification_2015-08-20_12h24m_1440066255//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[11:42:27.771] <TB3> INFO: PixTestGainPedestal::doTest() done, duration: 163 seconds
[11:42:27.777] <TB3> INFO: enter test to run
[11:42:27.778] <TB3> INFO: test: exit no parameter change
[11:42:28.287] <TB3> QUIET: Connection to board 170 closed.
[11:42:28.367] <TB3> INFO: pXar: this is the end, my friend