[10:45:17.651] <TB3> INFO: *** Welcome to pxar ***
[10:45:17.651] <TB3> INFO: *** Today: 2015/08/21
[10:45:17.651] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters_C15.dat
[10:45:17.664] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//tbmParameters_C0b.dat
[10:45:17.665] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//defaultMaskFile.dat
[10:45:17.666] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//trimParameters_C15.dat
[10:45:17.783] <TB3> INFO: clk: 4
[10:45:17.783] <TB3> INFO: ctr: 4
[10:45:17.784] <TB3> INFO: sda: 19
[10:45:17.784] <TB3> INFO: tin: 9
[10:45:17.784] <TB3> INFO: level: 15
[10:45:17.784] <TB3> INFO: triggerdelay: 0
[10:45:17.784] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[10:45:17.784] <TB3> INFO: Log level: INFO
[10:45:17.791] <TB3> INFO: Found DTB DTB_WZ4I6J
[10:45:17.801] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[10:45:17.805] <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:45:17.807] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[10:45:19.348] <TB3> INFO: DUT info:
[10:45:19.348] <TB3> INFO: The DUT currently contains the following objects:
[10:45:19.348] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[10:45:19.348] <TB3> INFO: TBM Core alpha (0): 7 registers set
[10:45:19.348] <TB3> INFO: TBM Core beta (1): 7 registers set
[10:45:19.348] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:45:19.348] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:19.348] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:19.348] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:19.348] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:19.348] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:19.348] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:19.348] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:19.348] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:19.348] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:19.348] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:19.348] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:19.348] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:19.348] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:19.348] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:19.348] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:19.348] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:45:19.750] <TB3> INFO: enter 'restricted' command line mode
[10:45:19.750] <TB3> INFO: enter test to run
[10:45:19.750] <TB3> INFO: test: pretest no parameter change
[10:45:19.750] <TB3> INFO: running: pretest
[10:45:19.756] <TB3> INFO: ######################################################################
[10:45:19.756] <TB3> INFO: PixTestPretest::doTest()
[10:45:19.756] <TB3> INFO: ######################################################################
[10:45:19.758] <TB3> INFO: ----------------------------------------------------------------------
[10:45:19.758] <TB3> INFO: PixTestPretest::programROC()
[10:45:19.758] <TB3> INFO: ----------------------------------------------------------------------
[10:45:37.776] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[10:45:37.776] <TB3> INFO: IA differences per ROC: 19.3 18.5 20.1 16.9 16.9 20.9 17.7 17.7 18.5 20.1 20.1 19.3 16.9 20.9 19.3 19.3
[10:45:37.838] <TB3> INFO: ----------------------------------------------------------------------
[10:45:37.838] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[10:45:37.838] <TB3> INFO: ----------------------------------------------------------------------
[10:45:57.394] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 380.2 mA = 23.7625 mA/ROC
[10:45:57.397] <TB3> INFO: ----------------------------------------------------------------------
[10:45:57.397] <TB3> INFO: PixTestPretest::findWorkingPixel()
[10:45:57.397] <TB3> INFO: ----------------------------------------------------------------------
[10:46:05.566] <TB3> INFO: Test took 8165ms.
[10:46:05.858] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[10:46:05.894] <TB3> INFO: ----------------------------------------------------------------------
[10:46:05.894] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[10:46:05.894] <TB3> INFO: ----------------------------------------------------------------------
[10:46:14.084] <TB3> INFO: Test took 8186ms.
[10:46:14.396] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[10:46:14.396] <TB3> INFO: CalDel: 138 157 138 127 156 132 155 148 157 139 152 146 145 145 148 153
[10:46:14.396] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[10:46:14.400] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters_C0.dat
[10:46:14.400] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters_C1.dat
[10:46:14.400] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters_C2.dat
[10:46:14.400] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters_C3.dat
[10:46:14.401] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters_C4.dat
[10:46:14.401] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters_C5.dat
[10:46:14.401] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters_C6.dat
[10:46:14.401] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters_C7.dat
[10:46:14.401] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters_C8.dat
[10:46:14.402] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters_C9.dat
[10:46:14.402] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters_C10.dat
[10:46:14.402] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters_C11.dat
[10:46:14.402] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters_C12.dat
[10:46:14.403] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters_C13.dat
[10:46:14.403] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters_C14.dat
[10:46:14.403] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters_C15.dat
[10:46:14.403] <TB3> INFO: PixTestPretest::doTest() done, duration: 54 seconds
[10:46:14.502] <TB3> INFO: enter test to run
[10:46:14.502] <TB3> INFO: test: fulltest no parameter change
[10:46:14.502] <TB3> INFO: running: fulltest
[10:46:14.502] <TB3> INFO: ######################################################################
[10:46:14.502] <TB3> INFO: PixTestFullTest::doTest()
[10:46:14.502] <TB3> INFO: ######################################################################
[10:46:14.505] <TB3> INFO: ######################################################################
[10:46:14.505] <TB3> INFO: PixTestAlive::doTest()
[10:46:14.505] <TB3> INFO: ######################################################################
[10:46:14.506] <TB3> INFO: ----------------------------------------------------------------------
[10:46:14.507] <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:46:14.507] <TB3> INFO: ----------------------------------------------------------------------
[10:46:17.894] <TB3> INFO: Test took 3386ms.
[10:46:17.919] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:46:18.144] <TB3> INFO: PixTestAlive::aliveTest() done
[10:46:18.144] <TB3> INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:46:18.145] <TB3> INFO: ----------------------------------------------------------------------
[10:46:18.146] <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:46:18.146] <TB3> INFO: ----------------------------------------------------------------------
[10:46:20.842] <TB3> INFO: Test took 2695ms.
[10:46:20.844] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:46:20.844] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:46:21.073] <TB3> INFO: PixTestAlive::maskTest() done
[10:46:21.073] <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:46:21.075] <TB3> INFO: ----------------------------------------------------------------------
[10:46:21.075] <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:46:21.075] <TB3> INFO: ----------------------------------------------------------------------
[10:46:24.485] <TB3> INFO: Test took 3408ms.
[10:46:24.509] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:46:24.740] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[10:46:24.740] <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:46:24.740] <TB3> INFO: PixTestAlive::doTest() done, duration: 10 seconds
[10:46:24.747] <TB3> INFO: ######################################################################
[10:46:24.747] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[10:46:24.747] <TB3> INFO: ######################################################################
[10:46:24.750] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30) hits flags = 2 (plus default)
[10:46:24.886] <TB3> INFO: dacScan step from 0 .. 29
[10:46:46.046] <TB3> INFO: Test took 21160ms.
[10:46:46.078] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:46:46.078] <TB3> INFO: dacScan step from 30 .. 59
[10:47:09.816] <TB3> INFO: Test took 23738ms.
[10:47:09.959] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:47:09.989] <TB3> INFO: dacScan step from 60 .. 89
[10:47:38.530] <TB3> INFO: Test took 28541ms.
[10:47:38.813] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:47:38.902] <TB3> INFO: dacScan step from 90 .. 119
[10:48:06.480] <TB3> INFO: Test took 27578ms.
[10:48:06.851] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:06.940] <TB3> INFO: dacScan step from 120 .. 149
[10:48:30.875] <TB3> INFO: Test took 23935ms.
[10:48:30.996] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:53.703] <TB3> INFO: PixTestBBMap::doTest() done, duration: 148 seconds
[10:48:53.703] <TB3> INFO: number of dead bumps (per ROC): 0 1 0 0 0 0 0 0 0 0 2 0 0 0 0 0
[10:48:53.703] <TB3> INFO: separation cut (per ROC): 72 88 76 98 70 87 77 74 72 70 73 75 87 81 84 78
[10:48:53.776] <TB3> INFO: ######################################################################
[10:48:53.776] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50
[10:48:53.776] <TB3> INFO: ######################################################################
[10:48:53.776] <TB3> INFO: ----------------------------------------------------------------------
[10:48:53.777] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50
[10:48:53.777] <TB3> INFO: ----------------------------------------------------------------------
[10:48:53.777] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (4) hits flags = 16 (plus default)
[10:48:53.785] <TB3> INFO: dacScan step from 0 .. 3
[10:49:11.385] <TB3> INFO: Test took 17600ms.
[10:49:11.414] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:11.414] <TB3> INFO: dacScan step from 4 .. 7
[10:49:28.948] <TB3> INFO: Test took 17534ms.
[10:49:28.974] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:28.974] <TB3> INFO: dacScan step from 8 .. 11
[10:49:46.771] <TB3> INFO: Test took 17796ms.
[10:49:46.796] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:46.798] <TB3> INFO: dacScan step from 12 .. 15
[10:50:05.178] <TB3> INFO: Test took 18380ms.
[10:50:05.209] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:05.209] <TB3> INFO: dacScan step from 16 .. 19
[10:50:23.794] <TB3> INFO: Test took 18585ms.
[10:50:23.820] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:23.820] <TB3> INFO: dacScan step from 20 .. 23
[10:50:42.376] <TB3> INFO: Test took 18556ms.
[10:50:42.406] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:42.406] <TB3> INFO: dacScan step from 24 .. 27
[10:51:01.072] <TB3> INFO: Test took 18666ms.
[10:51:01.100] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:01.100] <TB3> INFO: dacScan step from 28 .. 31
[10:51:19.683] <TB3> INFO: Test took 18583ms.
[10:51:19.710] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:19.710] <TB3> INFO: dacScan step from 32 .. 35
[10:51:38.193] <TB3> INFO: Test took 18483ms.
[10:51:38.223] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:38.223] <TB3> INFO: dacScan step from 36 .. 39
[10:51:56.622] <TB3> INFO: Test took 18399ms.
[10:51:56.649] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:56.649] <TB3> INFO: dacScan step from 40 .. 43
[10:52:15.030] <TB3> INFO: Test took 18381ms.
[10:52:15.057] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:15.057] <TB3> INFO: dacScan step from 44 .. 47
[10:52:33.850] <TB3> INFO: Test took 18793ms.
[10:52:33.878] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:33.878] <TB3> INFO: dacScan step from 48 .. 51
[10:52:52.307] <TB3> INFO: Test took 18429ms.
[10:52:52.336] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:52.336] <TB3> INFO: dacScan step from 52 .. 55
[10:53:10.836] <TB3> INFO: Test took 18500ms.
[10:53:10.862] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:10.862] <TB3> INFO: dacScan step from 56 .. 59
[10:53:29.472] <TB3> INFO: Test took 18610ms.
[10:53:29.501] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:29.501] <TB3> INFO: dacScan step from 60 .. 63
[10:53:48.179] <TB3> INFO: Test took 18678ms.
[10:53:48.216] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:48.216] <TB3> INFO: dacScan step from 64 .. 67
[10:54:07.078] <TB3> INFO: Test took 18862ms.
[10:54:07.121] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:07.122] <TB3> INFO: dacScan step from 68 .. 71
[10:54:26.584] <TB3> INFO: Test took 19462ms.
[10:54:26.641] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:26.643] <TB3> INFO: dacScan step from 72 .. 75
[10:54:47.436] <TB3> INFO: Test took 20792ms.
[10:54:47.520] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:47.525] <TB3> INFO: dacScan step from 76 .. 79
[10:55:10.044] <TB3> INFO: Test took 22519ms.
[10:55:10.167] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:10.173] <TB3> INFO: dacScan step from 80 .. 83
[10:55:35.590] <TB3> INFO: Test took 25417ms.
[10:55:35.774] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:35.786] <TB3> INFO: dacScan step from 84 .. 87
[10:56:02.322] <TB3> INFO: Test took 26535ms.
[10:56:02.540] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:02.549] <TB3> INFO: dacScan step from 88 .. 91



[10:56:29.666] <TB3> INFO: Test took 27117ms.
[10:56:29.881] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:29.891] <TB3> INFO: dacScan step from 92 .. 95



[10:56:57.463] <TB3> INFO: Test took 27571ms.
[10:56:57.704] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:57.716] <TB3> INFO: dacScan step from 96 .. 99





[10:57:25.964] <TB3> INFO: Test took 28248ms.
[10:57:26.200] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:26.211] <TB3> INFO: dacScan step from 100 .. 103


[10:57:54.863] <TB3> INFO: Test took 28652ms.
[10:57:55.115] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:55.127] <TB3> INFO: dacScan step from 104 .. 107


[10:58:23.242] <TB3> INFO: Test took 28115ms.
[10:58:23.546] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:58:23.558] <TB3> INFO: dacScan step from 108 .. 111





[10:58:52.011] <TB3> INFO: Test took 28453ms.
[10:58:52.271] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:58:52.283] <TB3> INFO: dacScan step from 112 .. 115
[10:59:21.348] <TB3> INFO: Test took 29065ms.
[10:59:21.583] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:59:21.595] <TB3> INFO: dacScan step from 116 .. 119
[10:59:50.711] <TB3> INFO: Test took 29116ms.
[10:59:50.960] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:59:50.973] <TB3> INFO: dacScan step from 120 .. 123
[11:00:20.066] <TB3> INFO: Test took 29093ms.
[11:00:20.292] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:00:20.305] <TB3> INFO: dacScan step from 124 .. 127
[11:00:49.216] <TB3> INFO: Test took 28911ms.
[11:00:49.457] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:00:49.468] <TB3> INFO: dacScan step from 128 .. 131
[11:01:18.298] <TB3> INFO: Test took 28830ms.
[11:01:18.522] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:18.534] <TB3> INFO: dacScan step from 132 .. 135
[11:01:47.478] <TB3> INFO: Test took 28944ms.
[11:01:47.720] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:47.734] <TB3> INFO: dacScan step from 136 .. 139
[11:02:16.769] <TB3> INFO: Test took 29036ms.
[11:02:16.998] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:17.010] <TB3> INFO: dacScan step from 140 .. 143
[11:02:46.015] <TB3> INFO: Test took 29005ms.
[11:02:46.255] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:02:46.266] <TB3> INFO: dacScan step from 144 .. 147
[11:03:12.990] <TB3> INFO: Test took 26724ms.
[11:03:13.218] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:03:13.229] <TB3> INFO: dacScan step from 148 .. 149
[11:03:29.040] <TB3> INFO: Test took 15811ms.
[11:03:29.160] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:03:29.167] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:03:30.691] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:03:32.156] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:03:33.691] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:03:35.059] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:03:36.592] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:03:38.111] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:03:39.635] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:03:41.397] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:03:43.211] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:03:44.850] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:03:46.544] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:03:48.079] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:03:49.579] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:03:51.076] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:03:52.646] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[11:03:54.197] <TB3> INFO: PixTestScurves::scurves() done
[11:03:54.197] <TB3> INFO: Vcal mean: 80.01 94.72 77.31 105.03 76.73 81.41 91.78 81.92 79.95 70.68 74.21 77.43 87.66 86.94 84.81 84.20
[11:03:54.197] <TB3> INFO: Vcal RMS: 4.77 6.40 5.15 6.20 4.21 4.38 5.54 4.72 4.88 4.65 4.85 4.14 5.71 5.90 5.01 5.90
[11:03:54.197] <TB3> INFO: PixTestScurves::fullTest() done, duration: 900 seconds
[11:03:54.301] <TB3> INFO: ######################################################################
[11:03:54.301] <TB3> INFO: PixTestTrim::doTest()
[11:03:54.301] <TB3> INFO: ######################################################################
[11:03:54.303] <TB3> INFO: ----------------------------------------------------------------------
[11:03:54.303] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[11:03:54.303] <TB3> INFO: ----------------------------------------------------------------------
[11:03:54.383] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[11:03:54.383] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20) hits flags = 16 (plus default)
[11:03:54.392] <TB3> INFO: dacScan step from 0 .. 19
[11:04:09.434] <TB3> INFO: Test took 15042ms.
[11:04:09.461] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:04:09.461] <TB3> INFO: dacScan step from 20 .. 39
[11:04:23.844] <TB3> INFO: Test took 14383ms.
[11:04:23.865] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:04:23.865] <TB3> INFO: dacScan step from 40 .. 59
[11:04:38.137] <TB3> INFO: Test took 14272ms.
[11:04:38.157] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:04:38.157] <TB3> INFO: dacScan step from 60 .. 79
[11:04:53.179] <TB3> INFO: Test took 15022ms.
[11:04:53.204] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:04:53.204] <TB3> INFO: dacScan step from 80 .. 99
[11:05:09.689] <TB3> INFO: Test took 16485ms.
[11:05:09.777] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:09.794] <TB3> INFO: dacScan step from 100 .. 119
[11:05:27.866] <TB3> INFO: Test took 18072ms.
[11:05:28.058] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:28.094] <TB3> INFO: dacScan step from 120 .. 139
[11:05:46.072] <TB3> INFO: Test took 17978ms.
[11:05:46.210] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:46.232] <TB3> INFO: dacScan step from 140 .. 159
[11:06:01.960] <TB3> INFO: Test took 15728ms.
[11:06:02.016] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:06:24.537] <TB3> INFO: ROC 0 VthrComp = 87
[11:06:24.537] <TB3> INFO: ROC 1 VthrComp = 95
[11:06:24.544] <TB3> INFO: ROC 2 VthrComp = 82
[11:06:24.544] <TB3> INFO: ROC 3 VthrComp = 101
[11:06:24.544] <TB3> INFO: ROC 4 VthrComp = 81
[11:06:24.544] <TB3> INFO: ROC 5 VthrComp = 92
[11:06:24.544] <TB3> INFO: ROC 6 VthrComp = 87
[11:06:24.544] <TB3> INFO: ROC 7 VthrComp = 87
[11:06:24.544] <TB3> INFO: ROC 8 VthrComp = 81
[11:06:24.544] <TB3> INFO: ROC 9 VthrComp = 76
[11:06:24.544] <TB3> INFO: ROC 10 VthrComp = 78
[11:06:24.544] <TB3> INFO: ROC 11 VthrComp = 83
[11:06:24.545] <TB3> INFO: ROC 12 VthrComp = 89
[11:06:24.545] <TB3> INFO: ROC 13 VthrComp = 90
[11:06:24.545] <TB3> INFO: ROC 14 VthrComp = 90
[11:06:24.545] <TB3> INFO: ROC 15 VthrComp = 87
[11:06:24.545] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[11:06:24.545] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20) hits flags = 16 (plus default)
[11:06:24.555] <TB3> INFO: dacScan step from 0 .. 19
[11:06:39.644] <TB3> INFO: Test took 15089ms.
[11:06:39.670] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:06:39.670] <TB3> INFO: dacScan step from 20 .. 39
[11:06:54.216] <TB3> INFO: Test took 14546ms.
[11:06:54.246] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:06:54.249] <TB3> INFO: dacScan step from 40 .. 59
[11:07:11.885] <TB3> INFO: Test took 17636ms.
[11:07:12.037] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:07:12.071] <TB3> INFO: dacScan step from 60 .. 79
[11:07:31.406] <TB3> INFO: Test took 19335ms.
[11:07:31.574] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:07:31.614] <TB3> INFO: dacScan step from 80 .. 99
[11:07:51.625] <TB3> INFO: Test took 20011ms.
[11:07:51.786] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:07:51.826] <TB3> INFO: dacScan step from 100 .. 119
[11:08:11.157] <TB3> INFO: Test took 19331ms.
[11:08:11.313] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:08:11.363] <TB3> INFO: dacScan step from 120 .. 139
[11:08:29.980] <TB3> INFO: Test took 18617ms.
[11:08:30.138] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:08:30.188] <TB3> INFO: dacScan step from 140 .. 159
[11:08:50.299] <TB3> INFO: Test took 20111ms.
[11:08:50.461] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:16.777] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 57.6318 for pixel 18/2 mean/min/max = 44.5979/31.52/57.6758
[11:09:16.777] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 63.9148 for pixel 3/79 mean/min/max = 47.7824/31.4636/64.1011
[11:09:16.777] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 60.1952 for pixel 0/59 mean/min/max = 45.7091/31.1054/60.3127
[11:09:16.778] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 65.3203 for pixel 14/0 mean/min/max = 48.7577/32.1764/65.3389
[11:09:16.778] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 56.9638 for pixel 1/35 mean/min/max = 44.85/32.7043/56.9957
[11:09:16.778] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 56.1372 for pixel 20/67 mean/min/max = 44.0284/31.8225/56.2342
[11:09:16.778] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 62.7372 for pixel 12/3 mean/min/max = 47.2953/31.7574/62.8332
[11:09:16.779] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 58.0504 for pixel 51/9 mean/min/max = 45.1687/32.0666/58.2709
[11:09:16.779] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 59.8949 for pixel 18/79 mean/min/max = 46.2815/32.5745/59.9886
[11:09:16.779] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 57.6831 for pixel 21/8 mean/min/max = 46.3524/35.0087/57.6962
[11:09:16.779] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 60.7046 for pixel 8/2 mean/min/max = 47.676/34.6197/60.7323
[11:09:16.780] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 57.2761 for pixel 43/2 mean/min/max = 45.0724/32.8431/57.3017
[11:09:16.780] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 60.3916 for pixel 39/73 mean/min/max = 46.5386/32.4309/60.6463
[11:09:16.780] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 62.0133 for pixel 0/63 mean/min/max = 46.9558/31.7438/62.1679
[11:09:16.781] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 58.31 for pixel 0/2 mean/min/max = 45.4063/32.3396/58.4729
[11:09:16.781] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 62.2345 for pixel 3/5 mean/min/max = 46.6085/30.9429/62.2741
[11:09:16.781] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:48.570] <TB3> INFO: Test took 91789ms.
[11:10:49.944] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20) hits flags = 16 (plus default)
[11:10:49.958] <TB3> INFO: dacScan step from 0 .. 19
[11:11:13.043] <TB3> INFO: Test took 23085ms.
[11:11:13.105] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:11:13.109] <TB3> INFO: dacScan step from 20 .. 39
[11:11:42.195] <TB3> INFO: Test took 29086ms.
[11:11:42.442] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:11:42.475] <TB3> INFO: dacScan step from 40 .. 59
[11:12:15.068] <TB3> INFO: Test took 32593ms.
[11:12:15.396] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:12:15.439] <TB3> INFO: dacScan step from 60 .. 79



[11:12:48.278] <TB3> INFO: Test took 32839ms.
[11:12:48.560] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:12:48.605] <TB3> INFO: dacScan step from 80 .. 99
[11:13:21.556] <TB3> INFO: Test took 32951ms.
[11:13:21.856] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:21.899] <TB3> INFO: dacScan step from 100 .. 119





[11:13:54.876] <TB3> INFO: Test took 32977ms.
[11:13:55.188] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:13:55.233] <TB3> INFO: dacScan step from 120 .. 139


[11:14:28.313] <TB3> INFO: Test took 33080ms.
[11:14:28.599] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:28.645] <TB3> INFO: dacScan step from 140 .. 159


[11:15:01.394] <TB3> INFO: Test took 32749ms.
[11:15:01.670] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:01.715] <TB3> INFO: dacScan step from 160 .. 179


[11:15:33.981] <TB3> INFO: Test took 32266ms.
[11:15:34.252] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:34.306] <TB3> INFO: dacScan step from 180 .. 199


[11:16:06.738] <TB3> INFO: Test took 32432ms.
[11:16:07.035] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:33.630] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.003211 .. 255.000000
[11:16:33.710] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 255 (20) hits flags = 16 (plus default)
[11:16:33.718] <TB3> INFO: dacScan step from 0 .. 19
[11:16:47.321] <TB3> INFO: Test took 13602ms.
[11:16:47.350] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:47.350] <TB3> INFO: dacScan step from 20 .. 39
[11:17:02.136] <TB3> INFO: Test took 14786ms.
[11:17:02.222] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:02.240] <TB3> INFO: dacScan step from 40 .. 59
[11:17:19.753] <TB3> INFO: Test took 17513ms.
[11:17:19.908] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:19.955] <TB3> INFO: dacScan step from 60 .. 79
[11:17:37.393] <TB3> INFO: Test took 17438ms.
[11:17:37.533] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:37.578] <TB3> INFO: dacScan step from 80 .. 99
[11:17:54.391] <TB3> INFO: Test took 16813ms.
[11:17:54.620] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:54.697] <TB3> INFO: dacScan step from 100 .. 119
[11:18:11.324] <TB3> INFO: Test took 16627ms.
[11:18:11.474] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:11.541] <TB3> INFO: dacScan step from 120 .. 139
[11:18:28.072] <TB3> INFO: Test took 16531ms.
[11:18:28.216] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:28.279] <TB3> INFO: dacScan step from 140 .. 159
[11:18:44.667] <TB3> INFO: Test took 16388ms.
[11:18:44.813] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:44.874] <TB3> INFO: dacScan step from 160 .. 179
[11:19:01.599] <TB3> INFO: Test took 16725ms.
[11:19:01.757] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:01.823] <TB3> INFO: dacScan step from 180 .. 199
[11:19:19.353] <TB3> INFO: Test took 17530ms.
[11:19:19.498] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:19.547] <TB3> INFO: dacScan step from 200 .. 219
[11:19:36.059] <TB3> INFO: Test took 16512ms.
[11:19:36.221] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:36.276] <TB3> INFO: dacScan step from 220 .. 239
[11:19:52.880] <TB3> INFO: Test took 16604ms.
[11:19:53.019] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:53.070] <TB3> INFO: dacScan step from 240 .. 255
[11:20:07.640] <TB3> INFO: Test took 14570ms.
[11:20:07.769] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:40.713] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 12.217652 .. 67.306647
[11:20:40.796] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 2 .. 77 (20) hits flags = 16 (plus default)
[11:20:40.806] <TB3> INFO: dacScan step from 2 .. 21
[11:20:53.812] <TB3> INFO: Test took 13006ms.
[11:20:53.840] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:53.840] <TB3> INFO: dacScan step from 22 .. 41
[11:21:08.352] <TB3> INFO: Test took 14512ms.
[11:21:08.445] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:08.467] <TB3> INFO: dacScan step from 42 .. 61
[11:21:26.124] <TB3> INFO: Test took 17657ms.
[11:21:26.281] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:26.322] <TB3> INFO: dacScan step from 62 .. 77
[11:21:40.742] <TB3> INFO: Test took 14420ms.
[11:21:40.857] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:00.035] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 20.983265 .. 67.306647
[11:22:00.112] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 10 .. 77 (20) hits flags = 16 (plus default)
[11:22:00.121] <TB3> INFO: dacScan step from 10 .. 29
[11:22:13.145] <TB3> INFO: Test took 13024ms.
[11:22:13.167] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:13.168] <TB3> INFO: dacScan step from 30 .. 49
[11:22:29.942] <TB3> INFO: Test took 16774ms.
[11:22:30.080] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:30.115] <TB3> INFO: dacScan step from 50 .. 69
[11:22:47.709] <TB3> INFO: Test took 17594ms.
[11:22:47.851] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:47.895] <TB3> INFO: dacScan step from 70 .. 77
[11:22:56.194] <TB3> INFO: Test took 8299ms.
[11:22:56.259] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:16.242] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 2.798366 .. 67.306647
[11:23:16.335] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 2 .. 77 (20) hits flags = 16 (plus default)
[11:23:16.344] <TB3> INFO: dacScan step from 2 .. 21
[11:23:29.255] <TB3> INFO: Test took 12911ms.
[11:23:29.281] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:29.281] <TB3> INFO: dacScan step from 22 .. 41
[11:23:44.276] <TB3> INFO: Test took 14995ms.
[11:23:44.370] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:44.392] <TB3> INFO: dacScan step from 42 .. 61
[11:24:00.870] <TB3> INFO: Test took 16478ms.
[11:24:01.010] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:24:01.060] <TB3> INFO: dacScan step from 62 .. 77
[11:24:15.776] <TB3> INFO: Test took 14716ms.
[11:24:15.893] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:24:34.679] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[11:24:34.679] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20) hits flags = 16 (plus default)
[11:24:34.688] <TB3> INFO: dacScan step from 15 .. 34
[11:24:56.894] <TB3> INFO: Test took 22206ms.
[11:24:56.963] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:24:56.972] <TB3> INFO: dacScan step from 35 .. 54


[11:25:29.358] <TB3> INFO: Test took 32386ms.
[11:25:29.647] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:29.696] <TB3> INFO: dacScan step from 55 .. 55
[11:25:34.050] <TB3> INFO: Test took 4354ms.
[11:25:34.073] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:47.694] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C0.dat
[11:25:47.694] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C1.dat
[11:25:47.694] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C2.dat
[11:25:47.694] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C3.dat
[11:25:47.695] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C4.dat
[11:25:47.695] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C5.dat
[11:25:47.695] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C6.dat
[11:25:47.695] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C7.dat
[11:25:47.695] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C8.dat
[11:25:47.695] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C9.dat
[11:25:47.695] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C10.dat
[11:25:47.695] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C11.dat
[11:25:47.695] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C12.dat
[11:25:47.695] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C13.dat
[11:25:47.695] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C14.dat
[11:25:47.695] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C15.dat
[11:25:47.696] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//trimParameters35_C0.dat
[11:25:47.701] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//trimParameters35_C1.dat
[11:25:47.707] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//trimParameters35_C2.dat
[11:25:47.713] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//trimParameters35_C3.dat
[11:25:47.719] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//trimParameters35_C4.dat
[11:25:47.725] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//trimParameters35_C5.dat
[11:25:47.731] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//trimParameters35_C6.dat
[11:25:47.737] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//trimParameters35_C7.dat
[11:25:47.743] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//trimParameters35_C8.dat
[11:25:47.749] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//trimParameters35_C9.dat
[11:25:47.754] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//trimParameters35_C10.dat
[11:25:47.760] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//trimParameters35_C11.dat
[11:25:47.766] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//trimParameters35_C12.dat
[11:25:47.772] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//trimParameters35_C13.dat
[11:25:47.778] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//trimParameters35_C14.dat
[11:25:47.784] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//trimParameters35_C15.dat
[11:25:47.790] <TB3> INFO: PixTestTrim::trimTest() done
[11:25:47.790] <TB3> INFO: vtrim: 183 125 106 124 93 225 117 105 98 105 107 101 103 116 89 118
[11:25:47.790] <TB3> INFO: vthrcomp: 87 95 82 101 81 92 87 87 81 76 78 83 89 90 90 87
[11:25:47.790] <TB3> INFO: vcal mean: 34.87 35.08 35.07 35.10 35.01 34.95 35.04 35.02 35.08 35.04 35.07 35.08 35.06 35.07 35.05 35.09
[11:25:47.790] <TB3> INFO: vcal RMS: 2.18 1.23 1.03 1.53 1.00 2.02 1.31 1.01 1.05 0.99 0.99 0.96 1.17 1.06 1.00 1.14
[11:25:47.790] <TB3> INFO: bits mean: 12.15 9.42 9.60 9.65 10.14 12.45 10.08 9.95 9.46 9.37 9.04 9.61 9.92 9.48 9.17 10.00
[11:25:47.790] <TB3> INFO: bits RMS: 1.54 2.64 2.74 2.38 2.33 1.36 2.34 2.48 2.56 2.31 2.37 2.59 2.39 2.59 2.88 2.54
[11:25:47.798] <TB3> INFO: ----------------------------------------------------------------------
[11:25:47.798] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[11:25:47.798] <TB3> INFO: ----------------------------------------------------------------------
[11:25:47.801] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20) hits flags = 16 (plus default)
[11:25:47.810] <TB3> INFO: dacScan step from 0 .. 19
[11:26:09.211] <TB3> INFO: Test took 21401ms.
[11:26:09.246] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:09.246] <TB3> INFO: dacScan step from 20 .. 39
[11:26:30.434] <TB3> INFO: Test took 21188ms.
[11:26:30.468] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:30.469] <TB3> INFO: dacScan step from 40 .. 59
[11:26:53.005] <TB3> INFO: Test took 22536ms.
[11:26:53.042] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:53.042] <TB3> INFO: dacScan step from 60 .. 79
[11:27:15.756] <TB3> INFO: Test took 22714ms.
[11:27:15.798] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:15.798] <TB3> INFO: dacScan step from 80 .. 99
[11:27:38.877] <TB3> INFO: Test took 23079ms.
[11:27:38.954] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:38.962] <TB3> INFO: dacScan step from 100 .. 119
[11:28:09.143] <TB3> INFO: Test took 30181ms.
[11:28:09.387] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:09.413] <TB3> INFO: dacScan step from 120 .. 139


[11:28:42.093] <TB3> INFO: Test took 32680ms.
[11:28:42.394] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:42.432] <TB3> INFO: dacScan step from 140 .. 159
[11:29:15.558] <TB3> INFO: Test took 33126ms.
[11:29:15.841] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:15.889] <TB3> INFO: dacScan step from 160 .. 179
[11:29:46.111] <TB3> INFO: Test took 30222ms.
[11:29:46.376] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:46.423] <TB3> INFO: dacScan step from 180 .. 199
[11:30:20.089] <TB3> INFO: Test took 33666ms.
[11:30:20.355] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:48.528] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 169 (20) hits flags = 16 (plus default)
[11:30:48.537] <TB3> INFO: dacScan step from 0 .. 19
[11:31:09.710] <TB3> INFO: Test took 21173ms.
[11:31:09.744] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:09.744] <TB3> INFO: dacScan step from 20 .. 39
[11:31:32.239] <TB3> INFO: Test took 22495ms.
[11:31:32.279] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:32.279] <TB3> INFO: dacScan step from 40 .. 59
[11:31:55.007] <TB3> INFO: Test took 22728ms.
[11:31:55.044] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:55.044] <TB3> INFO: dacScan step from 60 .. 79
[11:32:17.553] <TB3> INFO: Test took 22509ms.
[11:32:17.591] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:32:17.592] <TB3> INFO: dacScan step from 80 .. 99
[11:32:43.784] <TB3> INFO: Test took 26192ms.
[11:32:43.934] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:32:43.949] <TB3> INFO: dacScan step from 100 .. 119



[11:33:13.972] <TB3> INFO: Test took 30023ms.
[11:33:14.262] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:33:14.298] <TB3> INFO: dacScan step from 120 .. 139


[11:33:45.977] <TB3> INFO: Test took 31679ms.
[11:33:46.256] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:33:46.299] <TB3> INFO: dacScan step from 140 .. 159
[11:34:18.415] <TB3> INFO: Test took 32116ms.
[11:34:18.702] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:34:18.750] <TB3> INFO: dacScan step from 160 .. 169
[11:34:36.581] <TB3> INFO: Test took 17831ms.
[11:34:36.722] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:35:02.233] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 155 (20) hits flags = 16 (plus default)
[11:35:02.242] <TB3> INFO: dacScan step from 0 .. 19
[11:35:24.876] <TB3> INFO: Test took 22634ms.
[11:35:24.911] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:35:24.911] <TB3> INFO: dacScan step from 20 .. 39
[11:35:47.633] <TB3> INFO: Test took 22722ms.
[11:35:47.668] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:35:47.668] <TB3> INFO: dacScan step from 40 .. 59
[11:36:08.867] <TB3> INFO: Test took 21199ms.
[11:36:08.913] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:36:08.913] <TB3> INFO: dacScan step from 60 .. 79
[11:36:31.663] <TB3> INFO: Test took 22750ms.
[11:36:31.701] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:36:31.701] <TB3> INFO: dacScan step from 80 .. 99
[11:36:57.096] <TB3> INFO: Test took 25395ms.
[11:36:57.254] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:36:57.270] <TB3> INFO: dacScan step from 100 .. 119



[11:37:28.164] <TB3> INFO: Test took 30894ms.
[11:37:28.456] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:37:28.494] <TB3> INFO: dacScan step from 120 .. 139





[11:37:59.563] <TB3> INFO: Test took 31069ms.
[11:37:59.845] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:37:59.887] <TB3> INFO: dacScan step from 140 .. 155
[11:38:26.595] <TB3> INFO: Test took 26708ms.
[11:38:26.814] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:48.955] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 155 (20) hits flags = 16 (plus default)
[11:38:48.964] <TB3> INFO: dacScan step from 0 .. 19
[11:39:11.750] <TB3> INFO: Test took 22786ms.
[11:39:11.786] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:11.786] <TB3> INFO: dacScan step from 20 .. 39
[11:39:34.423] <TB3> INFO: Test took 22636ms.
[11:39:34.464] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:34.464] <TB3> INFO: dacScan step from 40 .. 59
[11:39:56.890] <TB3> INFO: Test took 22426ms.
[11:39:56.928] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:56.928] <TB3> INFO: dacScan step from 60 .. 79
[11:40:19.618] <TB3> INFO: Test took 22690ms.
[11:40:19.658] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:40:19.659] <TB3> INFO: dacScan step from 80 .. 99
[11:40:43.905] <TB3> INFO: Test took 24246ms.
[11:40:44.061] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:40:44.077] <TB3> INFO: dacScan step from 100 .. 119
[11:41:14.582] <TB3> INFO: Test took 30505ms.
[11:41:14.873] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:41:14.914] <TB3> INFO: dacScan step from 120 .. 139



[11:41:45.509] <TB3> INFO: Test took 30595ms.
[11:41:45.832] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:41:45.876] <TB3> INFO: dacScan step from 140 .. 155
[11:42:12.733] <TB3> INFO: Test took 26857ms.
[11:42:12.963] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:35.467] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 156 (20) hits flags = 16 (plus default)
[11:42:35.476] <TB3> INFO: dacScan step from 0 .. 19
[11:42:58.004] <TB3> INFO: Test took 22528ms.
[11:42:58.039] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:58.039] <TB3> INFO: dacScan step from 20 .. 39
[11:43:20.552] <TB3> INFO: Test took 22513ms.
[11:43:20.587] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:43:20.587] <TB3> INFO: dacScan step from 40 .. 59
[11:43:43.096] <TB3> INFO: Test took 22509ms.
[11:43:43.135] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:43:43.135] <TB3> INFO: dacScan step from 60 .. 79
[11:44:05.834] <TB3> INFO: Test took 22699ms.
[11:44:05.873] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:44:05.874] <TB3> INFO: dacScan step from 80 .. 99
[11:44:32.122] <TB3> INFO: Test took 26248ms.
[11:44:32.304] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:44:32.321] <TB3> INFO: dacScan step from 100 .. 119





[11:45:02.141] <TB3> INFO: Test took 29820ms.
[11:45:02.412] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:45:02.447] <TB3> INFO: dacScan step from 120 .. 139


[11:45:32.581] <TB3> INFO: Test took 30134ms.
[11:45:32.918] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:45:32.969] <TB3> INFO: dacScan step from 140 .. 156
[11:46:01.509] <TB3> INFO: Test took 28540ms.
[11:46:01.752] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:23.757] <TB3> INFO: PixTestTrim::trimBitTest() done
[11:46:23.758] <TB3> INFO: PixTestTrim::doTest() done, duration: 2549 seconds
[11:46:24.456] <TB3> INFO: ######################################################################
[11:46:24.456] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[11:46:24.456] <TB3> INFO: ######################################################################
[11:46:27.833] <TB3> INFO: Test took 3375ms.
[11:46:27.859] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:31.494] <TB3> INFO: Test took 3438ms.
[11:46:31.561] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:35.007] <TB3> INFO: Test took 3436ms.
[11:46:35.072] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:35.080] <TB3> INFO: The DUT currently contains the following objects:
[11:46:35.080] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:46:35.080] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:46:35.080] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:46:35.080] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:46:35.080] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:35.080] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:35.080] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:35.080] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:35.080] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:35.080] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:35.080] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:35.080] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:35.080] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:35.080] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:35.080] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:35.080] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:35.080] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:35.080] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:35.080] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:35.080] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:36.188] <TB3> INFO: Test took 1108ms.
[11:46:36.189] <TB3> INFO: The DUT currently contains the following objects:
[11:46:36.189] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:46:36.189] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:46:36.189] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:46:36.189] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:46:36.189] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:36.189] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:36.189] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:36.189] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:36.189] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:36.190] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:36.190] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:36.190] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:36.190] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:36.190] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:36.190] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:36.190] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:36.190] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:36.190] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:36.190] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:36.190] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:37.295] <TB3> INFO: Test took 1105ms.
[11:46:37.297] <TB3> INFO: The DUT currently contains the following objects:
[11:46:37.297] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:46:37.297] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:46:37.297] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:46:37.297] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:46:37.297] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:37.297] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:37.297] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:37.297] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:37.297] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:37.297] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:37.297] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:37.297] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:37.298] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:37.298] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:37.298] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:37.298] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:37.298] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:37.298] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:37.298] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:37.298] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:38.404] <TB3> INFO: Test took 1106ms.
[11:46:38.407] <TB3> INFO: The DUT currently contains the following objects:
[11:46:38.407] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:46:38.407] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:46:38.407] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:46:38.407] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:46:38.407] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:38.407] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:38.407] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:38.407] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:38.407] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:38.407] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:38.407] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:38.407] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:38.407] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:38.407] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:38.407] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:38.407] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:38.408] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:38.408] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:38.408] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:38.408] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:39.514] <TB3> INFO: Test took 1106ms.
[11:46:39.515] <TB3> INFO: The DUT currently contains the following objects:
[11:46:39.515] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:46:39.515] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:46:39.515] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:46:39.515] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:46:39.515] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:39.515] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:39.515] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:39.515] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:39.515] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:39.515] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:39.516] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:39.516] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:39.516] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:39.516] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:39.516] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:39.516] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:39.516] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:39.516] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:39.516] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:39.516] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:40.623] <TB3> INFO: Test took 1107ms.
[11:46:40.624] <TB3> INFO: The DUT currently contains the following objects:
[11:46:40.624] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:46:40.624] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:46:40.624] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:46:40.624] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:46:40.624] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:40.624] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:40.624] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:40.624] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:40.625] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:40.625] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:40.625] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:40.625] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:40.625] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:40.625] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:40.625] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:40.625] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:40.625] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:40.625] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:40.625] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:40.625] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:41.732] <TB3> INFO: Test took 1107ms.
[11:46:41.733] <TB3> INFO: The DUT currently contains the following objects:
[11:46:41.733] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:46:41.733] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:46:41.733] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:46:41.733] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:46:41.733] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:41.733] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:41.733] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:41.733] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:41.733] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:41.733] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:41.733] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:41.733] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:41.733] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:41.733] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:41.733] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:41.733] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:41.733] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:41.734] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:41.734] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:41.734] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:42.842] <TB3> INFO: Test took 1108ms.
[11:46:42.843] <TB3> INFO: The DUT currently contains the following objects:
[11:46:42.844] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:46:42.844] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:46:42.844] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:46:42.844] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:46:42.844] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:42.844] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:42.844] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:42.844] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:42.844] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:42.844] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:42.844] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:42.844] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:42.844] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:42.844] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:42.844] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:42.844] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:42.844] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:42.844] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:42.844] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:42.844] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:43.952] <TB3> INFO: Test took 1108ms.
[11:46:43.953] <TB3> INFO: The DUT currently contains the following objects:
[11:46:43.953] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:46:43.953] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:46:43.953] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:46:43.953] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:46:43.953] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:43.953] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:43.953] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:43.953] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:43.953] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:43.954] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:43.954] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:43.954] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:43.954] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:43.954] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:43.954] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:43.954] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:43.954] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:43.954] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:43.954] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:43.954] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:45.061] <TB3> INFO: Test took 1107ms.
[11:46:45.062] <TB3> INFO: The DUT currently contains the following objects:
[11:46:45.062] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:46:45.062] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:46:45.062] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:46:45.062] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:46:45.062] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:45.062] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:45.062] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:45.063] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:45.063] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:45.063] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:45.063] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:45.063] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:45.063] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:45.063] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:45.063] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:45.063] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:45.063] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:45.063] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:45.063] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:45.063] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:46.172] <TB3> INFO: Test took 1109ms.
[11:46:46.173] <TB3> INFO: The DUT currently contains the following objects:
[11:46:46.173] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:46:46.173] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:46:46.173] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:46:46.173] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:46:46.173] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:46.173] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:46.173] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:46.173] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:46.173] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:46.173] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:46.173] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:46.173] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:46.173] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:46.173] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:46.174] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:46.174] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:46.174] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:46.174] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:46.174] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:46.174] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:47.280] <TB3> INFO: Test took 1106ms.
[11:46:47.281] <TB3> INFO: The DUT currently contains the following objects:
[11:46:47.281] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:46:47.281] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:46:47.281] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:46:47.281] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:46:47.281] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:47.281] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:47.281] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:47.281] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:47.281] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:47.281] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:47.281] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:47.281] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:47.282] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:47.282] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:47.282] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:47.282] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:47.282] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:47.282] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:47.282] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:47.282] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:48.391] <TB3> INFO: Test took 1109ms.
[11:46:48.393] <TB3> INFO: The DUT currently contains the following objects:
[11:46:48.393] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:46:48.393] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:46:48.393] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:46:48.393] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:46:48.393] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:48.393] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:48.393] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:48.393] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:48.394] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:48.394] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:48.394] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:48.394] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:48.394] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:48.394] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:48.394] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:48.394] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:48.394] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:48.394] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:48.394] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:48.394] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:49.501] <TB3> INFO: Test took 1107ms.
[11:46:49.504] <TB3> INFO: The DUT currently contains the following objects:
[11:46:49.504] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:46:49.504] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:46:49.504] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:46:49.504] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:46:49.504] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:49.504] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:49.504] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:49.504] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:49.504] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:49.504] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:49.504] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:49.504] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:49.504] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:49.504] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:49.505] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:49.505] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:49.505] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:49.505] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:49.505] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:49.505] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:50.611] <TB3> INFO: Test took 1106ms.
[11:46:50.612] <TB3> INFO: The DUT currently contains the following objects:
[11:46:50.612] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:46:50.612] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:46:50.612] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:46:50.612] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:46:50.612] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:50.612] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:50.612] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:50.613] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:50.613] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:50.613] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:50.613] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:50.613] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:50.613] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:50.613] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:50.613] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:50.613] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:50.613] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:50.613] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:50.613] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:50.613] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:51.720] <TB3> INFO: Test took 1107ms.
[11:46:51.721] <TB3> INFO: The DUT currently contains the following objects:
[11:46:51.721] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[11:46:51.721] <TB3> INFO: TBM Core alpha (0): 7 registers set
[11:46:51.721] <TB3> INFO: TBM Core beta (1): 7 registers set
[11:46:51.721] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[11:46:51.721] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:51.721] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:51.721] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:51.721] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:51.721] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:51.721] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:51.721] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:51.721] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:51.721] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:51.721] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:51.721] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:51.722] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:51.722] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:51.722] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:51.722] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:51.722] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[11:46:52.829] <TB3> INFO: Test took 1107ms.
[11:46:52.833] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:50:32.011] <TB3> INFO: Test took 219178ms.
[11:50:33.621] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:18.645] <TB3> INFO: Test took 225024ms.
[11:54:20.489] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:20.497] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:20.505] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:20.513] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[11:54:20.522] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[11:54:20.529] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[11:54:20.536] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[11:54:20.543] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[11:54:20.550] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[11:54:20.557] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[11:54:20.564] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:20.571] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:20.578] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:20.585] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:20.592] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:20.599] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:20.605] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:20.612] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[11:54:20.620] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:20.629] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:20.637] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:20.644] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:20.652] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:20.660] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:54:20.695] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C0.dat
[11:54:20.695] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C1.dat
[11:54:20.695] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C2.dat
[11:54:20.695] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C3.dat
[11:54:20.711] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C4.dat
[11:54:20.711] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C5.dat
[11:54:20.711] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C6.dat
[11:54:20.711] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C7.dat
[11:54:20.711] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C8.dat
[11:54:20.712] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C9.dat
[11:54:20.712] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C10.dat
[11:54:20.712] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C11.dat
[11:54:20.712] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C12.dat
[11:54:20.712] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C13.dat
[11:54:20.712] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C14.dat
[11:54:20.712] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//dacParameters35_C15.dat
[11:54:24.160] <TB3> INFO: Test took 3446ms.
[11:54:27.966] <TB3> INFO: Test took 3526ms.
[11:54:31.756] <TB3> INFO: Test took 3508ms.
[11:54:32.040] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:32.953] <TB3> INFO: Test took 913ms.
[11:54:32.955] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:34.063] <TB3> INFO: Test took 1108ms.
[11:54:34.067] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:35.174] <TB3> INFO: Test took 1107ms.
[11:54:35.178] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:36.286] <TB3> INFO: Test took 1108ms.
[11:54:36.290] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:37.391] <TB3> INFO: Test took 1101ms.
[11:54:37.393] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:38.503] <TB3> INFO: Test took 1110ms.
[11:54:38.506] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:39.615] <TB3> INFO: Test took 1109ms.
[11:54:39.619] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:40.727] <TB3> INFO: Test took 1108ms.
[11:54:40.730] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:41.833] <TB3> INFO: Test took 1103ms.
[11:54:41.836] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:42.958] <TB3> INFO: Test took 1122ms.
[11:54:42.962] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:44.070] <TB3> INFO: Test took 1109ms.
[11:54:44.075] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:45.182] <TB3> INFO: Test took 1107ms.
[11:54:45.187] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:46.288] <TB3> INFO: Test took 1102ms.
[11:54:46.290] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:47.397] <TB3> INFO: Test took 1107ms.
[11:54:47.400] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:48.509] <TB3> INFO: Test took 1109ms.
[11:54:48.513] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:49.619] <TB3> INFO: Test took 1107ms.
[11:54:49.623] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:50.725] <TB3> INFO: Test took 1102ms.
[11:54:50.727] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:51.839] <TB3> INFO: Test took 1112ms.
[11:54:51.842] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:52.950] <TB3> INFO: Test took 1108ms.
[11:54:52.953] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:54.062] <TB3> INFO: Test took 1109ms.
[11:54:54.066] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:55.170] <TB3> INFO: Test took 1104ms.
[11:54:55.172] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:56.281] <TB3> INFO: Test took 1109ms.
[11:54:56.284] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:57.393] <TB3> INFO: Test took 1109ms.
[11:54:57.397] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:58.505] <TB3> INFO: Test took 1109ms.
[11:54:58.510] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:54:59.614] <TB3> INFO: Test took 1104ms.
[11:54:59.617] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:00.726] <TB3> INFO: Test took 1109ms.
[11:55:00.731] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:01.839] <TB3> INFO: Test took 1109ms.
[11:55:01.844] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:02.952] <TB3> INFO: Test took 1109ms.
[11:55:02.956] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:04.058] <TB3> INFO: Test took 1102ms.
[11:55:04.060] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:05.168] <TB3> INFO: Test took 1109ms.
[11:55:05.172] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:06.276] <TB3> INFO: Test took 1104ms.
[11:55:06.279] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:55:07.386] <TB3> INFO: Test took 1108ms.
[11:55:07.938] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 523 seconds
[11:55:07.938] <TB3> INFO: PH scale (per ROC): 85 77 87 71 90 91 78 82 93 80 87 89 90 87 83 81
[11:55:07.938] <TB3> INFO: PH offset (per ROC): 150 171 176 156 156 144 166 157 163 148 159 154 171 153 156 158
[11:55:08.121] <TB3> INFO: ######################################################################
[11:55:08.121] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:55:08.121] <TB3> INFO: ######################################################################
[11:55:08.132] <TB3> INFO: scanning low vcal = 10
[11:55:11.995] <TB3> INFO: Test took 3863ms.
[11:55:12.001] <TB3> INFO: scanning low vcal = 20
[11:55:15.848] <TB3> INFO: Test took 3847ms.
[11:55:15.853] <TB3> INFO: scanning low vcal = 30
[11:55:19.733] <TB3> INFO: Test took 3880ms.
[11:55:19.747] <TB3> INFO: scanning low vcal = 40
[11:55:24.089] <TB3> INFO: Test took 4342ms.
[11:55:24.151] <TB3> INFO: scanning low vcal = 50
[11:55:28.490] <TB3> INFO: Test took 4339ms.
[11:55:28.553] <TB3> INFO: scanning low vcal = 60
[11:55:32.899] <TB3> INFO: Test took 4346ms.
[11:55:32.964] <TB3> INFO: scanning low vcal = 70
[11:55:37.302] <TB3> INFO: Test took 4338ms.
[11:55:37.364] <TB3> INFO: scanning low vcal = 80
[11:55:41.733] <TB3> INFO: Test took 4369ms.
[11:55:41.802] <TB3> INFO: scanning low vcal = 90
[11:55:46.174] <TB3> INFO: Test took 4372ms.
[11:55:46.241] <TB3> INFO: scanning low vcal = 100
[11:55:50.620] <TB3> INFO: Test took 4379ms.
[11:55:50.683] <TB3> INFO: scanning low vcal = 110
[11:55:55.053] <TB3> INFO: Test took 4370ms.
[11:55:55.120] <TB3> INFO: scanning low vcal = 120
[11:55:59.494] <TB3> INFO: Test took 4374ms.
[11:55:59.560] <TB3> INFO: scanning low vcal = 130
[11:56:03.916] <TB3> INFO: Test took 4356ms.
[11:56:03.983] <TB3> INFO: scanning low vcal = 140
[11:56:08.307] <TB3> INFO: Test took 4324ms.
[11:56:08.371] <TB3> INFO: scanning low vcal = 150
[11:56:12.735] <TB3> INFO: Test took 4364ms.
[11:56:12.802] <TB3> INFO: scanning low vcal = 160
[11:56:17.168] <TB3> INFO: Test took 4367ms.
[11:56:17.228] <TB3> INFO: scanning low vcal = 170
[11:56:21.593] <TB3> INFO: Test took 4365ms.
[11:56:21.663] <TB3> INFO: scanning low vcal = 180
[11:56:26.006] <TB3> INFO: Test took 4343ms.
[11:56:26.066] <TB3> INFO: scanning low vcal = 190
[11:56:30.424] <TB3> INFO: Test took 4358ms.
[11:56:30.479] <TB3> INFO: scanning low vcal = 200
[11:56:34.714] <TB3> INFO: Test took 4235ms.
[11:56:34.772] <TB3> INFO: scanning low vcal = 210
[11:56:39.002] <TB3> INFO: Test took 4230ms.
[11:56:39.058] <TB3> INFO: scanning low vcal = 220
[11:56:43.265] <TB3> INFO: Test took 4207ms.
[11:56:43.320] <TB3> INFO: scanning low vcal = 230
[11:56:47.512] <TB3> INFO: Test took 4192ms.
[11:56:47.569] <TB3> INFO: scanning low vcal = 240
[11:56:51.814] <TB3> INFO: Test took 4246ms.
[11:56:51.910] <TB3> INFO: scanning low vcal = 250
[11:56:56.157] <TB3> INFO: Test took 4247ms.
[11:56:56.214] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[11:57:00.419] <TB3> INFO: Test took 4205ms.
[11:57:00.481] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[11:57:04.732] <TB3> INFO: Test took 4251ms.
[11:57:04.792] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[11:57:09.177] <TB3> INFO: Test took 4385ms.
[11:57:09.236] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[11:57:13.600] <TB3> INFO: Test took 4364ms.
[11:57:13.658] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:57:18.020] <TB3> INFO: Test took 4362ms.
[11:57:18.556] <TB3> INFO: PixTestGainPedestal::measure() done
[11:57:52.803] <TB3> INFO: PixTestGainPedestal::fit() done
[11:57:52.804] <TB3> INFO: non-linearity mean: 0.950 0.952 0.962 0.957 0.956 0.957 0.959 0.954 0.958 0.952 0.956 0.949 0.955 0.962 0.954 0.952
[11:57:52.804] <TB3> INFO: non-linearity RMS: 0.008 0.006 0.004 0.007 0.006 0.005 0.005 0.006 0.006 0.006 0.005 0.006 0.006 0.006 0.006 0.006
[11:57:52.804] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[11:57:52.822] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[11:57:52.841] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[11:57:52.863] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[11:57:52.890] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[11:57:52.909] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[11:57:52.927] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[11:57:52.946] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[11:57:52.964] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[11:57:52.982] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[11:57:53.001] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[11:57:53.021] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[11:57:53.039] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[11:57:53.058] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[11:57:53.076] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[11:57:53.094] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2079_FullQualification_2015-08-21_12h37m_1440153422//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[11:57:53.113] <TB3> INFO: PixTestGainPedestal::doTest() done, duration: 164 seconds
[11:57:53.119] <TB3> INFO: enter test to run
[11:57:53.119] <TB3> INFO: test: exit no parameter change
[11:57:53.587] <TB3> QUIET: Connection to board 170 closed.
[11:57:53.666] <TB3> INFO: pXar: this is the end, my friend