[08:26:23.308] <TB3> INFO: *** Welcome to pxar ***
[08:26:23.308] <TB3> INFO: *** Today: 2015/07/23
[08:26:23.308] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters_C15.dat
[08:26:23.310] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//tbmParameters_C0b.dat
[08:26:23.310] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//defaultMaskFile.dat
[08:26:23.310] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//trimParameters_C15.dat
[08:26:23.405] <TB3> INFO: clk: 4
[08:26:23.405] <TB3> INFO: ctr: 4
[08:26:23.405] <TB3> INFO: sda: 19
[08:26:23.405] <TB3> INFO: tin: 9
[08:26:23.405] <TB3> INFO: level: 15
[08:26:23.405] <TB3> INFO: triggerdelay: 0
[08:26:23.405] <TB3> QUIET: Instanciating API for pxar v2.2.5+46~gdbe75a1
[08:26:23.405] <TB3> INFO: Log level: INFO
[08:26:23.413] <TB3> INFO: Found DTB DTB_WZ4I6J
[08:26:23.421] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[08:26:23.425] <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:
------------------------------------------------------
[08:26:23.427] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[08:26:24.969] <TB3> INFO: DUT info:
[08:26:24.969] <TB3> INFO: The DUT currently contains the following objects:
[08:26:24.969] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[08:26:24.969] <TB3> INFO: TBM Core alpha (0): 7 registers set
[08:26:24.969] <TB3> INFO: TBM Core beta (1): 7 registers set
[08:26:24.970] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:26:24.970] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:24.970] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:24.970] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:24.970] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:24.970] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:24.970] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:24.970] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:24.970] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:24.970] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:24.970] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:24.970] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:24.970] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:24.970] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:24.970] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:24.970] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:24.970] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:25.371] <TB3> INFO: enter 'restricted' command line mode
[08:26:25.371] <TB3> INFO: enter test to run
[08:26:25.371] <TB3> INFO: test: pretest no parameter change
[08:26:25.371] <TB3> INFO: running: pretest
[08:26:25.378] <TB3> INFO: ######################################################################
[08:26:25.378] <TB3> INFO: PixTestPretest::doTest()
[08:26:25.378] <TB3> INFO: ######################################################################
[08:26:25.380] <TB3> INFO: ----------------------------------------------------------------------
[08:26:25.380] <TB3> INFO: PixTestPretest::programROC()
[08:26:25.380] <TB3> INFO: ----------------------------------------------------------------------
[08:26:43.398] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[08:26:43.398] <TB3> INFO: IA differences per ROC: 18.5 18.5 18.5 20.1 20.1 20.1 17.7 19.3 16.1 20.1 17.7 19.3 20.1 18.5 19.3 17.7
[08:26:43.470] <TB3> INFO: ----------------------------------------------------------------------
[08:26:43.470] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[08:26:43.470] <TB3> INFO: ----------------------------------------------------------------------
[08:27:03.038] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 386.7 mA = 24.1687 mA/ROC
[08:27:03.041] <TB3> INFO: ----------------------------------------------------------------------
[08:27:03.041] <TB3> INFO: PixTestPretest::findWorkingPixel()
[08:27:03.041] <TB3> INFO: ----------------------------------------------------------------------
[08:27:11.256] <TB3> INFO: Test took 8209ms.
[08:27:11.546] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[08:27:11.585] <TB3> INFO: ----------------------------------------------------------------------
[08:27:11.585] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[08:27:11.585] <TB3> INFO: ----------------------------------------------------------------------
[08:27:19.867] <TB3> INFO: Test took 8275ms.
[08:27:20.172] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[08:27:20.172] <TB3> INFO: CalDel: 141 143 135 157 157 155 148 150 138 142 145 144 143 147 144 150
[08:27:20.172] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[08:27:20.175] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters_C0.dat
[08:27:20.176] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters_C1.dat
[08:27:20.176] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters_C2.dat
[08:27:20.176] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters_C3.dat
[08:27:20.176] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters_C4.dat
[08:27:20.177] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters_C5.dat
[08:27:20.177] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters_C6.dat
[08:27:20.177] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters_C7.dat
[08:27:20.177] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters_C8.dat
[08:27:20.177] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters_C9.dat
[08:27:20.178] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters_C10.dat
[08:27:20.178] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters_C11.dat
[08:27:20.178] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters_C12.dat
[08:27:20.178] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters_C13.dat
[08:27:20.178] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters_C14.dat
[08:27:20.179] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters_C15.dat
[08:27:20.179] <TB3> INFO: PixTestPretest::doTest() done, duration: 54 seconds
[08:27:20.265] <TB3> INFO: enter test to run
[08:27:20.265] <TB3> INFO: test: fulltest no parameter change
[08:27:20.265] <TB3> INFO: running: fulltest
[08:27:20.265] <TB3> INFO: ######################################################################
[08:27:20.265] <TB3> INFO: PixTestFullTest::doTest()
[08:27:20.265] <TB3> INFO: ######################################################################
[08:27:20.266] <TB3> INFO: ######################################################################
[08:27:20.266] <TB3> INFO: PixTestAlive::doTest()
[08:27:20.266] <TB3> INFO: ######################################################################
[08:27:20.268] <TB3> INFO: ----------------------------------------------------------------------
[08:27:20.268] <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)
[08:27:20.268] <TB3> INFO: ----------------------------------------------------------------------
[08:27:23.695] <TB3> INFO: Test took 3426ms.
[08:27:23.716] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:27:23.942] <TB3> INFO: PixTestAlive::aliveTest() done
[08:27:23.942] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:27:23.944] <TB3> INFO: ----------------------------------------------------------------------
[08:27:23.944] <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)
[08:27:23.944] <TB3> INFO: ----------------------------------------------------------------------
[08:27:26.679] <TB3> INFO: Test took 2733ms.
[08:27:26.682] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:27:26.682] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:27:26.904] <TB3> INFO: PixTestAlive::maskTest() done
[08:27:26.904] <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
[08:27:26.905] <TB3> INFO: ----------------------------------------------------------------------
[08:27:26.905] <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)
[08:27:26.905] <TB3> INFO: ----------------------------------------------------------------------
[08:27:30.364] <TB3> INFO: Test took 3457ms.
[08:27:30.389] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:27:30.615] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[08:27:30.615] <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
[08:27:30.616] <TB3> INFO: PixTestAlive::doTest() done, duration: 10 seconds
[08:27:30.626] <TB3> INFO: ######################################################################
[08:27:30.626] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[08:27:30.626] <TB3> INFO: ######################################################################
[08:27:30.628] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30) hits flags = 2 (plus default)
[08:27:30.639] <TB3> INFO: dacScan step from 0 .. 29
[08:27:52.068] <TB3> INFO: Test took 21429ms.
[08:27:52.100] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:27:52.100] <TB3> INFO: dacScan step from 30 .. 59
[08:28:15.814] <TB3> INFO: Test took 23714ms.
[08:28:15.954] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:28:15.978] <TB3> INFO: dacScan step from 60 .. 89
[08:28:44.900] <TB3> INFO: Test took 28921ms.
[08:28:45.215] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:28:45.310] <TB3> INFO: dacScan step from 90 .. 119
[08:29:12.040] <TB3> INFO: Test took 26730ms.
[08:29:12.352] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:29:12.438] <TB3> INFO: dacScan step from 120 .. 149
[08:29:35.921] <TB3> INFO: Test took 23482ms.
[08:29:36.095] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:30:00.206] <TB3> INFO: PixTestBBMap::doTest() done, duration: 149 seconds
[08:30:00.206] <TB3> INFO: number of dead bumps (per ROC): 4 0 0 0 0 0 0 0 1 0 1 0 0 0 0 0
[08:30:00.206] <TB3> INFO: separation cut (per ROC): 90 81 71 75 72 77 67 89 66 76 89 80 75 75 87 79
[08:30:00.282] <TB3> INFO: ######################################################################
[08:30:00.282] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50
[08:30:00.282] <TB3> INFO: ######################################################################
[08:30:00.282] <TB3> INFO: ----------------------------------------------------------------------
[08:30:00.282] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50
[08:30:00.282] <TB3> INFO: ----------------------------------------------------------------------
[08:30:00.282] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (4) hits flags = 16 (plus default)
[08:30:00.291] <TB3> INFO: dacScan step from 0 .. 3
[08:30:19.261] <TB3> INFO: Test took 18970ms.
[08:30:19.287] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:30:19.289] <TB3> INFO: dacScan step from 4 .. 7
[08:30:38.175] <TB3> INFO: Test took 18886ms.
[08:30:38.200] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:30:38.204] <TB3> INFO: dacScan step from 8 .. 11
[08:30:57.064] <TB3> INFO: Test took 18860ms.
[08:30:57.093] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:30:57.093] <TB3> INFO: dacScan step from 12 .. 15
[08:31:15.997] <TB3> INFO: Test took 18903ms.
[08:31:16.028] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:31:16.028] <TB3> INFO: dacScan step from 16 .. 19
[08:31:34.897] <TB3> INFO: Test took 18869ms.
[08:31:34.924] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:31:34.924] <TB3> INFO: dacScan step from 20 .. 23
[08:31:53.860] <TB3> INFO: Test took 18936ms.
[08:31:53.886] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:31:53.886] <TB3> INFO: dacScan step from 24 .. 27
[08:32:12.616] <TB3> INFO: Test took 18730ms.
[08:32:12.650] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:32:12.650] <TB3> INFO: dacScan step from 28 .. 31
[08:32:31.475] <TB3> INFO: Test took 18825ms.
[08:32:31.503] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:32:31.503] <TB3> INFO: dacScan step from 32 .. 35
[08:32:50.382] <TB3> INFO: Test took 18879ms.
[08:32:50.407] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:32:50.407] <TB3> INFO: dacScan step from 36 .. 39
[08:33:09.242] <TB3> INFO: Test took 18835ms.
[08:33:09.273] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:09.273] <TB3> INFO: dacScan step from 40 .. 43
[08:33:28.135] <TB3> INFO: Test took 18862ms.
[08:33:28.168] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:28.168] <TB3> INFO: dacScan step from 44 .. 47
[08:33:47.021] <TB3> INFO: Test took 18853ms.
[08:33:47.046] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:47.046] <TB3> INFO: dacScan step from 48 .. 51
[08:34:06.098] <TB3> INFO: Test took 19051ms.
[08:34:06.130] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:06.130] <TB3> INFO: dacScan step from 52 .. 55
[08:34:25.030] <TB3> INFO: Test took 18900ms.
[08:34:25.067] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:25.067] <TB3> INFO: dacScan step from 56 .. 59
[08:34:44.018] <TB3> INFO: Test took 18951ms.
[08:34:44.051] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:44.051] <TB3> INFO: dacScan step from 60 .. 63
[08:35:03.026] <TB3> INFO: Test took 18975ms.
[08:35:03.063] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:35:03.063] <TB3> INFO: dacScan step from 64 .. 67
[08:35:22.741] <TB3> INFO: Test took 19678ms.
[08:35:22.789] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:35:22.791] <TB3> INFO: dacScan step from 68 .. 71
[08:35:43.141] <TB3> INFO: Test took 20350ms.
[08:35:43.234] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:35:43.237] <TB3> INFO: dacScan step from 72 .. 75
[08:36:04.738] <TB3> INFO: Test took 21501ms.
[08:36:04.831] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:04.835] <TB3> INFO: dacScan step from 76 .. 79
[08:36:27.761] <TB3> INFO: Test took 22926ms.
[08:36:27.885] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:27.890] <TB3> INFO: dacScan step from 80 .. 83
[08:36:53.759] <TB3> INFO: Test took 25869ms.
[08:36:53.978] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:53.989] <TB3> INFO: dacScan step from 84 .. 87
[08:37:21.035] <TB3> INFO: Test took 27046ms.
[08:37:21.241] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:21.255] <TB3> INFO: dacScan step from 88 .. 91


[08:37:49.364] <TB3> INFO: Test took 28110ms.
[08:37:49.665] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:49.679] <TB3> INFO: dacScan step from 92 .. 95


[08:38:18.552] <TB3> INFO: Test took 28872ms.
[08:38:18.846] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:18.861] <TB3> INFO: dacScan step from 96 .. 99

[08:38:48.119] <TB3> INFO: Test took 29258ms.
[08:38:48.444] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:48.459] <TB3> INFO: dacScan step from 100 .. 103

[08:39:17.686] <TB3> INFO: Test took 29227ms.
[08:39:17.952] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:39:17.968] <TB3> INFO: dacScan step from 104 .. 107



[08:39:47.057] <TB3> INFO: Test took 29089ms.
[08:39:47.371] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:39:47.386] <TB3> INFO: dacScan step from 108 .. 111


[08:40:16.654] <TB3> INFO: Test took 29268ms.
[08:40:16.983] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:40:16.998] <TB3> INFO: dacScan step from 112 .. 115
[08:40:45.922] <TB3> INFO: Test took 28924ms.
[08:40:46.172] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:40:46.184] <TB3> INFO: dacScan step from 116 .. 119
[08:41:15.344] <TB3> INFO: Test took 29159ms.
[08:41:15.600] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:41:15.612] <TB3> INFO: dacScan step from 120 .. 123
[08:41:44.799] <TB3> INFO: Test took 29187ms.
[08:41:45.067] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:41:45.080] <TB3> INFO: dacScan step from 124 .. 127
[08:42:14.032] <TB3> INFO: Test took 28951ms.
[08:42:14.288] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:42:14.300] <TB3> INFO: dacScan step from 128 .. 131
[08:42:43.151] <TB3> INFO: Test took 28850ms.
[08:42:43.406] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:42:43.420] <TB3> INFO: dacScan step from 132 .. 135
[08:43:12.273] <TB3> INFO: Test took 28853ms.
[08:43:12.602] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:12.618] <TB3> INFO: dacScan step from 136 .. 139
[08:43:41.409] <TB3> INFO: Test took 28791ms.
[08:43:41.657] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:41.673] <TB3> INFO: dacScan step from 140 .. 143
[08:44:10.817] <TB3> INFO: Test took 29144ms.
[08:44:11.074] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:11.087] <TB3> INFO: dacScan step from 144 .. 147
[08:44:37.277] <TB3> INFO: Test took 26190ms.
[08:44:37.542] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:37.554] <TB3> INFO: dacScan step from 148 .. 149
[08:44:51.933] <TB3> INFO: Test took 14379ms.
[08:44:52.062] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:52.069] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:44:53.574] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:44:55.102] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:44:56.564] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:44:58.230] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:44:59.706] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:45:01.115] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:45:02.598] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:45:03.979] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:45:05.396] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:45:06.834] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:45:08.298] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:45:09.830] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:45:11.281] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:45:12.792] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:45:14.315] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:45:15.881] <TB3> INFO: PixTestScurves::scurves() done
[08:45:15.881] <TB3> INFO: Vcal mean: 92.66 85.11 67.35 75.51 77.58 83.20 74.44 93.50 80.22 75.59 86.98 78.18 83.43 74.35 82.47 86.33
[08:45:15.881] <TB3> INFO: Vcal RMS: 5.58 5.85 5.12 4.66 4.18 4.88 4.26 5.58 4.39 4.92 5.47 4.39 5.30 4.43 4.86 4.97
[08:45:15.881] <TB3> INFO: PixTestScurves::fullTest() done, duration: 915 seconds
[08:45:15.969] <TB3> INFO: ######################################################################
[08:45:15.969] <TB3> INFO: PixTestTrim::doTest()
[08:45:15.969] <TB3> INFO: ######################################################################
[08:45:15.971] <TB3> INFO: ----------------------------------------------------------------------
[08:45:15.971] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[08:45:15.971] <TB3> INFO: ----------------------------------------------------------------------
[08:45:16.072] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[08:45:16.072] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20) hits flags = 16 (plus default)
[08:45:16.082] <TB3> INFO: dacScan step from 0 .. 19
[08:45:31.228] <TB3> INFO: Test took 15146ms.
[08:45:31.255] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:45:31.255] <TB3> INFO: dacScan step from 20 .. 39
[08:45:46.301] <TB3> INFO: Test took 15046ms.
[08:45:46.326] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:45:46.326] <TB3> INFO: dacScan step from 40 .. 59
[08:46:01.559] <TB3> INFO: Test took 15233ms.
[08:46:01.581] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:46:01.581] <TB3> INFO: dacScan step from 60 .. 79
[08:46:16.602] <TB3> INFO: Test took 15021ms.
[08:46:16.628] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:46:16.628] <TB3> INFO: dacScan step from 80 .. 99
[08:46:33.153] <TB3> INFO: Test took 16525ms.
[08:46:33.237] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:46:33.249] <TB3> INFO: dacScan step from 100 .. 119
[08:46:52.013] <TB3> INFO: Test took 18764ms.
[08:46:52.195] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:46:52.238] <TB3> INFO: dacScan step from 120 .. 139
[08:47:09.367] <TB3> INFO: Test took 17129ms.
[08:47:09.512] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:47:09.538] <TB3> INFO: dacScan step from 140 .. 159
[08:47:24.611] <TB3> INFO: Test took 15073ms.
[08:47:24.659] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:47:45.720] <TB3> INFO: ROC 0 VthrComp = 97
[08:47:45.720] <TB3> INFO: ROC 1 VthrComp = 87
[08:47:45.720] <TB3> INFO: ROC 2 VthrComp = 75
[08:47:45.720] <TB3> INFO: ROC 3 VthrComp = 81
[08:47:45.720] <TB3> INFO: ROC 4 VthrComp = 81
[08:47:45.721] <TB3> INFO: ROC 5 VthrComp = 88
[08:47:45.721] <TB3> INFO: ROC 6 VthrComp = 79
[08:47:45.721] <TB3> INFO: ROC 7 VthrComp = 99
[08:47:45.721] <TB3> INFO: ROC 8 VthrComp = 84
[08:47:45.721] <TB3> INFO: ROC 9 VthrComp = 84
[08:47:45.721] <TB3> INFO: ROC 10 VthrComp = 94
[08:47:45.721] <TB3> INFO: ROC 11 VthrComp = 85
[08:47:45.721] <TB3> INFO: ROC 12 VthrComp = 86
[08:47:45.721] <TB3> INFO: ROC 13 VthrComp = 79
[08:47:45.722] <TB3> INFO: ROC 14 VthrComp = 89
[08:47:45.722] <TB3> INFO: ROC 15 VthrComp = 88
[08:47:45.722] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[08:47:45.722] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20) hits flags = 16 (plus default)
[08:47:45.731] <TB3> INFO: dacScan step from 0 .. 19
[08:48:00.674] <TB3> INFO: Test took 14943ms.
[08:48:00.703] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:48:00.703] <TB3> INFO: dacScan step from 20 .. 39
[08:48:15.949] <TB3> INFO: Test took 15246ms.
[08:48:15.978] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:48:15.980] <TB3> INFO: dacScan step from 40 .. 59
[08:48:34.823] <TB3> INFO: Test took 18842ms.
[08:48:35.021] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:48:35.060] <TB3> INFO: dacScan step from 60 .. 79
[08:48:55.259] <TB3> INFO: Test took 20199ms.
[08:48:55.445] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:48:55.489] <TB3> INFO: dacScan step from 80 .. 99
[08:49:15.682] <TB3> INFO: Test took 20193ms.
[08:49:15.850] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:49:15.900] <TB3> INFO: dacScan step from 100 .. 119
[08:49:35.915] <TB3> INFO: Test took 20015ms.
[08:49:36.142] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:49:36.192] <TB3> INFO: dacScan step from 120 .. 139
[08:49:54.957] <TB3> INFO: Test took 18765ms.
[08:49:55.170] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:49:55.227] <TB3> INFO: dacScan step from 140 .. 159
[08:50:14.138] <TB3> INFO: Test took 18911ms.
[08:50:14.310] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:50:39.504] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 57.9173 for pixel 42/79 mean/min/max = 44.9726/31.9615/57.9837
[08:50:39.505] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 61.8726 for pixel 0/78 mean/min/max = 46.7505/31.6263/61.8746
[08:50:39.505] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 58.5114 for pixel 2/47 mean/min/max = 46.114/33.596/58.632
[08:50:39.505] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 57.7198 for pixel 3/12 mean/min/max = 45.1619/32.4679/57.8559
[08:50:39.506] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 57.5111 for pixel 0/77 mean/min/max = 45.0439/32.5595/57.5283
[08:50:39.507] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 58.2443 for pixel 51/79 mean/min/max = 45.8904/33.0521/58.7287
[08:50:39.507] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 57.4883 for pixel 2/2 mean/min/max = 46.2581/35.0134/57.5028
[08:50:39.507] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 58.4912 for pixel 11/73 mean/min/max = 45.1561/31.8194/58.4927
[08:50:39.508] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 57.6781 for pixel 2/0 mean/min/max = 44.9665/32.0304/57.9026
[08:50:39.508] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 57.8696 for pixel 5/79 mean/min/max = 44.7392/31.5888/57.8896
[08:50:39.509] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 59.3133 for pixel 7/45 mean/min/max = 45.5916/31.6079/59.5753
[08:50:39.509] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 57.2924 for pixel 19/77 mean/min/max = 44.9265/32.3001/57.553
[08:50:39.510] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 60.3415 for pixel 0/79 mean/min/max = 45.9344/31.5254/60.3433
[08:50:39.510] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 58.1051 for pixel 0/49 mean/min/max = 46.4306/34.713/58.1482
[08:50:39.511] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 59.1175 for pixel 0/65 mean/min/max = 45.8601/32.5336/59.1867
[08:50:39.511] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 59.8883 for pixel 0/16 mean/min/max = 46.8792/33.8497/59.9088
[08:50:39.511] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:52:14.117] <TB3> INFO: Test took 94606ms.
[08:52:15.699] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20) hits flags = 16 (plus default)
[08:52:15.709] <TB3> INFO: dacScan step from 0 .. 19
[08:52:38.321] <TB3> INFO: Test took 22612ms.
[08:52:38.362] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:52:38.363] <TB3> INFO: dacScan step from 20 .. 39
[08:53:05.966] <TB3> INFO: Test took 27603ms.
[08:53:06.196] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:53:06.228] <TB3> INFO: dacScan step from 40 .. 59


[08:53:36.848] <TB3> INFO: Test took 30620ms.
[08:53:37.242] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:53:37.298] <TB3> INFO: dacScan step from 60 .. 79


[08:54:10.321] <TB3> INFO: Test took 33023ms.
[08:54:10.627] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:54:10.683] <TB3> INFO: dacScan step from 80 .. 99


[08:54:43.770] <TB3> INFO: Test took 33087ms.
[08:54:44.054] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:54:44.106] <TB3> INFO: dacScan step from 100 .. 119


[08:55:17.075] <TB3> INFO: Test took 32969ms.
[08:55:17.469] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:55:17.523] <TB3> INFO: dacScan step from 120 .. 139



[08:55:50.549] <TB3> INFO: Test took 33026ms.
[08:55:50.823] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:55:50.880] <TB3> INFO: dacScan step from 140 .. 159
[08:56:23.770] <TB3> INFO: Test took 32890ms.
[08:56:24.107] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:56:24.167] <TB3> INFO: dacScan step from 160 .. 179


[08:56:57.378] <TB3> INFO: Test took 33211ms.
[08:56:57.681] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:56:57.751] <TB3> INFO: dacScan step from 180 .. 199


[08:57:29.740] <TB3> INFO: Test took 31988ms.
[08:57:30.038] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:57:57.652] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.112191 .. 52.631743
[08:57:57.748] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 62 (20) hits flags = 16 (plus default)
[08:57:57.758] <TB3> INFO: dacScan step from 0 .. 19
[08:58:11.246] <TB3> INFO: Test took 13488ms.
[08:58:11.271] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:58:11.271] <TB3> INFO: dacScan step from 20 .. 39
[08:58:26.028] <TB3> INFO: Test took 14757ms.
[08:58:26.116] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:58:26.132] <TB3> INFO: dacScan step from 40 .. 59
[08:58:43.669] <TB3> INFO: Test took 17537ms.
[08:58:43.838] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:58:43.892] <TB3> INFO: dacScan step from 60 .. 62
[08:58:48.998] <TB3> INFO: Test took 5106ms.
[08:58:49.022] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:59:08.433] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 14.648243 .. 47.324179
[08:59:08.509] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 4 .. 57 (20) hits flags = 16 (plus default)
[08:59:08.517] <TB3> INFO: dacScan step from 4 .. 23
[08:59:21.914] <TB3> INFO: Test took 13397ms.
[08:59:21.943] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:59:21.943] <TB3> INFO: dacScan step from 24 .. 43
[08:59:37.431] <TB3> INFO: Test took 15488ms.
[08:59:37.558] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:59:37.583] <TB3> INFO: dacScan step from 44 .. 57
[08:59:50.761] <TB3> INFO: Test took 13178ms.
[08:59:50.873] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:00:06.920] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 19.630381 .. 44.378512
[09:00:07.002] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 9 .. 54 (20) hits flags = 16 (plus default)
[09:00:07.011] <TB3> INFO: dacScan step from 9 .. 28
[09:00:20.365] <TB3> INFO: Test took 13354ms.
[09:00:20.386] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:00:20.386] <TB3> INFO: dacScan step from 29 .. 48
[09:00:36.752] <TB3> INFO: Test took 16366ms.
[09:00:36.885] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:00:36.923] <TB3> INFO: dacScan step from 49 .. 54
[09:00:44.233] <TB3> INFO: Test took 7310ms.
[09:00:44.280] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:00:59.544] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 20.623964 .. 43.423588
[09:00:59.625] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 10 .. 53 (20) hits flags = 16 (plus default)
[09:00:59.634] <TB3> INFO: dacScan step from 10 .. 29
[09:01:12.595] <TB3> INFO: Test took 12961ms.
[09:01:12.615] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:01:12.615] <TB3> INFO: dacScan step from 30 .. 49
[09:01:28.220] <TB3> INFO: Test took 15605ms.
[09:01:28.377] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:01:28.418] <TB3> INFO: dacScan step from 50 .. 53
[09:01:33.977] <TB3> INFO: Test took 5559ms.
[09:01:34.005] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:01:49.761] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[09:01:49.761] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20) hits flags = 16 (plus default)
[09:01:49.769] <TB3> INFO: dacScan step from 15 .. 34
[09:02:11.298] <TB3> INFO: Test took 21529ms.
[09:02:11.379] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:02:11.389] <TB3> INFO: dacScan step from 35 .. 54
[09:02:41.848] <TB3> INFO: Test took 30459ms.
[09:02:42.224] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:02:42.279] <TB3> INFO: dacScan step from 55 .. 55
[09:02:46.671] <TB3> INFO: Test took 4392ms.
[09:02:46.692] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:03:02.070] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:03:02.071] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:03:02.071] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:03:02.071] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:03:02.071] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:03:02.071] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:03:02.071] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:03:02.071] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:03:02.071] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:03:02.072] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:03:02.072] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:03:02.072] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:03:02.072] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:03:02.072] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:03:02.072] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:03:02.072] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:03:02.072] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//trimParameters35_C0.dat
[09:03:02.080] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//trimParameters35_C1.dat
[09:03:02.086] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//trimParameters35_C2.dat
[09:03:02.093] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//trimParameters35_C3.dat
[09:03:02.099] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//trimParameters35_C4.dat
[09:03:02.105] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//trimParameters35_C5.dat
[09:03:02.111] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//trimParameters35_C6.dat
[09:03:02.118] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//trimParameters35_C7.dat
[09:03:02.124] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//trimParameters35_C8.dat
[09:03:02.130] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//trimParameters35_C9.dat
[09:03:02.136] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//trimParameters35_C10.dat
[09:03:02.142] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//trimParameters35_C11.dat
[09:03:02.148] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//trimParameters35_C12.dat
[09:03:02.155] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//trimParameters35_C13.dat
[09:03:02.161] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//trimParameters35_C14.dat
[09:03:02.167] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//trimParameters35_C15.dat
[09:03:02.173] <TB3> INFO: PixTestTrim::trimTest() done
[09:03:02.173] <TB3> INFO: vtrim: 106 97 95 121 104 98 106 117 99 102 113 108 106 101 104 98
[09:03:02.173] <TB3> INFO: vthrcomp: 97 87 75 81 81 88 79 99 84 84 94 85 86 79 89 88
[09:03:02.173] <TB3> INFO: vcal mean: 35.03 35.02 35.05 35.03 35.00 35.05 35.01 35.05 34.99 34.99 35.02 35.01 35.03 35.07 35.01 35.07
[09:03:02.173] <TB3> INFO: vcal RMS: 1.05 1.13 0.91 1.00 0.98 0.97 0.93 1.10 1.02 0.95 1.06 0.95 1.01 0.96 0.99 1.01
[09:03:02.173] <TB3> INFO: bits mean: 9.88 9.35 8.98 10.05 9.64 8.57 9.28 10.32 10.04 9.90 10.18 9.79 9.26 9.42 9.49 9.02
[09:03:02.173] <TB3> INFO: bits RMS: 2.57 2.71 2.66 2.45 2.63 2.94 2.33 2.38 2.47 2.59 2.43 2.57 2.86 2.33 2.60 2.66
[09:03:02.180] <TB3> INFO: ----------------------------------------------------------------------
[09:03:02.180] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[09:03:02.180] <TB3> INFO: ----------------------------------------------------------------------
[09:03:02.183] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20) hits flags = 16 (plus default)
[09:03:02.194] <TB3> INFO: dacScan step from 0 .. 19
[09:03:23.240] <TB3> INFO: Test took 21046ms.
[09:03:23.280] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:03:23.280] <TB3> INFO: dacScan step from 20 .. 39
[09:03:45.817] <TB3> INFO: Test took 22537ms.
[09:03:45.853] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:03:45.853] <TB3> INFO: dacScan step from 40 .. 59
[09:04:07.811] <TB3> INFO: Test took 21958ms.
[09:04:07.853] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:04:07.853] <TB3> INFO: dacScan step from 60 .. 79
[09:04:30.476] <TB3> INFO: Test took 22622ms.
[09:04:30.520] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:04:30.520] <TB3> INFO: dacScan step from 80 .. 99
[09:04:54.471] <TB3> INFO: Test took 23951ms.
[09:04:54.565] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:04:54.573] <TB3> INFO: dacScan step from 100 .. 119
[09:05:23.505] <TB3> INFO: Test took 28932ms.
[09:05:23.805] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:05:23.840] <TB3> INFO: dacScan step from 120 .. 139


[09:05:54.081] <TB3> INFO: Test took 30241ms.
[09:05:54.392] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:05:54.448] <TB3> INFO: dacScan step from 140 .. 159
[09:06:25.991] <TB3> INFO: Test took 31543ms.
[09:06:26.375] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:06:26.435] <TB3> INFO: dacScan step from 160 .. 179
[09:06:59.465] <TB3> INFO: Test took 33030ms.
[09:06:59.764] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:06:59.819] <TB3> INFO: dacScan step from 180 .. 199
[09:07:31.694] <TB3> INFO: Test took 31875ms.
[09:07:31.984] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:07:58.156] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 193 (20) hits flags = 16 (plus default)
[09:07:58.164] <TB3> INFO: dacScan step from 0 .. 19
[09:08:20.875] <TB3> INFO: Test took 22711ms.
[09:08:20.911] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:08:20.911] <TB3> INFO: dacScan step from 20 .. 39
[09:08:43.484] <TB3> INFO: Test took 22573ms.
[09:08:43.524] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:08:43.524] <TB3> INFO: dacScan step from 40 .. 59
[09:09:04.834] <TB3> INFO: Test took 21310ms.
[09:09:04.879] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:04.879] <TB3> INFO: dacScan step from 60 .. 79
[09:09:27.043] <TB3> INFO: Test took 22164ms.
[09:09:27.090] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:27.091] <TB3> INFO: dacScan step from 80 .. 99
[09:09:53.680] <TB3> INFO: Test took 26589ms.
[09:09:53.865] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:53.882] <TB3> INFO: dacScan step from 100 .. 119


[09:10:23.457] <TB3> INFO: Test took 29575ms.
[09:10:23.904] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:23.980] <TB3> INFO: dacScan step from 120 .. 139


[09:10:57.153] <TB3> INFO: Test took 33173ms.
[09:10:57.438] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:57.486] <TB3> INFO: dacScan step from 140 .. 159
[09:11:30.638] <TB3> INFO: Test took 33152ms.
[09:11:31.024] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:11:31.079] <TB3> INFO: dacScan step from 160 .. 179
[09:12:04.079] <TB3> INFO: Test took 33000ms.
[09:12:04.379] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:12:04.429] <TB3> INFO: dacScan step from 180 .. 193
[09:12:29.383] <TB3> INFO: Test took 24954ms.
[09:12:29.593] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:12:58.535] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 174 (20) hits flags = 16 (plus default)
[09:12:58.544] <TB3> INFO: dacScan step from 0 .. 19
[09:13:21.164] <TB3> INFO: Test took 22620ms.
[09:13:21.209] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:13:21.209] <TB3> INFO: dacScan step from 20 .. 39
[09:13:43.897] <TB3> INFO: Test took 22688ms.
[09:13:43.940] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:13:43.940] <TB3> INFO: dacScan step from 40 .. 59
[09:14:06.489] <TB3> INFO: Test took 22549ms.
[09:14:06.530] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:14:06.530] <TB3> INFO: dacScan step from 60 .. 79
[09:14:27.850] <TB3> INFO: Test took 21320ms.
[09:14:27.892] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:14:27.893] <TB3> INFO: dacScan step from 80 .. 99
[09:14:54.570] <TB3> INFO: Test took 26677ms.
[09:14:54.761] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:14:54.780] <TB3> INFO: dacScan step from 100 .. 119


[09:15:25.346] <TB3> INFO: Test took 30566ms.
[09:15:25.705] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:15:25.757] <TB3> INFO: dacScan step from 120 .. 139


[09:15:55.953] <TB3> INFO: Test took 30196ms.
[09:15:56.265] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:15:56.332] <TB3> INFO: dacScan step from 140 .. 159
[09:16:28.411] <TB3> INFO: Test took 32079ms.
[09:16:28.682] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:16:28.730] <TB3> INFO: dacScan step from 160 .. 174
[09:16:54.288] <TB3> INFO: Test took 25558ms.
[09:16:54.538] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:17:24.576] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 174 (20) hits flags = 16 (plus default)
[09:17:24.584] <TB3> INFO: dacScan step from 0 .. 19
[09:17:47.275] <TB3> INFO: Test took 22691ms.
[09:17:47.320] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:17:47.320] <TB3> INFO: dacScan step from 20 .. 39
[09:18:09.075] <TB3> INFO: Test took 21755ms.
[09:18:09.110] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:09.110] <TB3> INFO: dacScan step from 40 .. 59
[09:18:31.801] <TB3> INFO: Test took 22691ms.
[09:18:31.835] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:31.835] <TB3> INFO: dacScan step from 60 .. 79
[09:18:54.709] <TB3> INFO: Test took 22874ms.
[09:18:54.756] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:54.756] <TB3> INFO: dacScan step from 80 .. 99
[09:19:21.354] <TB3> INFO: Test took 26597ms.
[09:19:21.525] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:19:21.544] <TB3> INFO: dacScan step from 100 .. 119

[09:19:53.971] <TB3> INFO: Test took 32427ms.
[09:19:54.326] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:19:54.375] <TB3> INFO: dacScan step from 120 .. 139


[09:20:24.573] <TB3> INFO: Test took 30198ms.
[09:20:24.874] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:20:24.931] <TB3> INFO: dacScan step from 140 .. 159
[09:20:57.806] <TB3> INFO: Test took 32874ms.
[09:20:58.133] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:20:58.183] <TB3> INFO: dacScan step from 160 .. 174
[09:21:23.580] <TB3> INFO: Test took 25397ms.
[09:21:23.862] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:21:48.583] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 175 (20) hits flags = 16 (plus default)
[09:21:48.591] <TB3> INFO: dacScan step from 0 .. 19
[09:22:09.737] <TB3> INFO: Test took 21146ms.
[09:22:09.772] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:22:09.772] <TB3> INFO: dacScan step from 20 .. 39
[09:22:30.723] <TB3> INFO: Test took 20951ms.
[09:22:30.757] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:22:30.757] <TB3> INFO: dacScan step from 40 .. 59
[09:22:53.526] <TB3> INFO: Test took 22769ms.
[09:22:53.563] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:22:53.563] <TB3> INFO: dacScan step from 60 .. 79
[09:23:16.317] <TB3> INFO: Test took 22754ms.
[09:23:16.376] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:23:16.376] <TB3> INFO: dacScan step from 80 .. 99
[09:23:43.014] <TB3> INFO: Test took 26638ms.
[09:23:43.186] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:23:43.203] <TB3> INFO: dacScan step from 100 .. 119



[09:24:12.815] <TB3> INFO: Test took 29612ms.
[09:24:13.128] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:24:13.176] <TB3> INFO: dacScan step from 120 .. 139


[09:24:46.382] <TB3> INFO: Test took 33206ms.
[09:24:46.728] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:24:46.785] <TB3> INFO: dacScan step from 140 .. 159
[09:25:19.022] <TB3> INFO: Test took 32237ms.
[09:25:19.328] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:25:19.387] <TB3> INFO: dacScan step from 160 .. 175
[09:25:45.202] <TB3> INFO: Test took 25815ms.
[09:25:45.438] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:26:10.431] <TB3> INFO: PixTestTrim::trimBitTest() done
[09:26:10.433] <TB3> INFO: PixTestTrim::doTest() done, duration: 2454 seconds
[09:26:11.128] <TB3> INFO: ######################################################################
[09:26:11.128] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[09:26:11.128] <TB3> INFO: ######################################################################
[09:26:14.547] <TB3> INFO: Test took 3417ms.
[09:26:14.569] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:26:18.205] <TB3> INFO: Test took 3438ms.
[09:26:18.280] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:26:21.696] <TB3> INFO: Test took 3405ms.
[09:26:21.762] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:26:21.769] <TB3> INFO: The DUT currently contains the following objects:
[09:26:21.769] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:26:21.769] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:26:21.769] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:26:21.769] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:26:21.769] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:21.769] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:21.769] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:21.769] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:21.769] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:21.769] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:21.769] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:21.769] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:21.769] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:21.769] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:21.769] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:21.769] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:21.769] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:21.769] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:21.769] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:21.769] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:22.879] <TB3> INFO: Test took 1110ms.
[09:26:22.880] <TB3> INFO: The DUT currently contains the following objects:
[09:26:22.880] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:26:22.880] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:26:22.880] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:26:22.880] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:26:22.880] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:22.880] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:22.880] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:22.880] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:22.880] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:22.880] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:22.880] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:22.881] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:22.881] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:22.881] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:22.881] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:22.881] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:22.881] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:22.881] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:22.881] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:22.881] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:23.986] <TB3> INFO: Test took 1105ms.
[09:26:23.987] <TB3> INFO: The DUT currently contains the following objects:
[09:26:23.987] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:26:23.987] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:26:23.987] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:26:23.987] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:26:23.988] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:23.988] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:23.988] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:23.988] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:23.988] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:23.988] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:23.988] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:23.988] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:23.988] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:23.988] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:23.988] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:23.988] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:23.988] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:23.988] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:23.988] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:23.988] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:25.094] <TB3> INFO: Test took 1106ms.
[09:26:25.095] <TB3> INFO: The DUT currently contains the following objects:
[09:26:25.095] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:26:25.095] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:26:25.095] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:26:25.095] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:26:25.095] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:25.096] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:25.096] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:25.096] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:25.096] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:25.096] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:25.096] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:25.096] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:25.096] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:25.096] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:25.096] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:25.096] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:25.096] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:25.096] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:25.096] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:25.096] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:26.207] <TB3> INFO: Test took 1111ms.
[09:26:26.208] <TB3> INFO: The DUT currently contains the following objects:
[09:26:26.208] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:26:26.208] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:26:26.208] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:26:26.208] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:26:26.208] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:26.208] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:26.208] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:26.208] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:26.208] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:26.208] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:26.208] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:26.208] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:26.208] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:26.208] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:26.208] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:26.208] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:26.208] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:26.208] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:26.209] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:26.209] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:27.315] <TB3> INFO: Test took 1106ms.
[09:26:27.316] <TB3> INFO: The DUT currently contains the following objects:
[09:26:27.316] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:26:27.316] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:26:27.316] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:26:27.316] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:26:27.316] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:27.316] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:27.316] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:27.316] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:27.316] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:27.316] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:27.317] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:27.317] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:27.317] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:27.317] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:27.317] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:27.317] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:27.317] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:27.317] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:27.317] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:27.317] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:28.424] <TB3> INFO: Test took 1107ms.
[09:26:28.425] <TB3> INFO: The DUT currently contains the following objects:
[09:26:28.425] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:26:28.425] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:26:28.425] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:26:28.425] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:26:28.425] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:28.425] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:28.425] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:28.425] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:28.425] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:28.425] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:28.425] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:28.425] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:28.425] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:28.425] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:28.425] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:28.425] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:28.425] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:28.425] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:28.425] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:28.425] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:29.534] <TB3> INFO: Test took 1108ms.
[09:26:29.535] <TB3> INFO: The DUT currently contains the following objects:
[09:26:29.535] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:26:29.535] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:26:29.535] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:26:29.535] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:26:29.536] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:29.536] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:29.536] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:29.536] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:29.536] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:29.536] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:29.536] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:29.536] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:29.536] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:29.536] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:29.536] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:29.536] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:29.536] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:29.536] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:29.536] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:29.536] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:30.645] <TB3> INFO: Test took 1109ms.
[09:26:30.646] <TB3> INFO: The DUT currently contains the following objects:
[09:26:30.646] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:26:30.646] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:26:30.646] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:26:30.646] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:26:30.646] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:30.646] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:30.646] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:30.646] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:30.646] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:30.646] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:30.647] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:30.647] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:30.647] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:30.647] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:30.647] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:30.647] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:30.647] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:30.647] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:30.647] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:30.647] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:31.770] <TB3> INFO: Test took 1123ms.
[09:26:31.771] <TB3> INFO: The DUT currently contains the following objects:
[09:26:31.782] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:26:31.782] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:26:31.782] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:26:31.782] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:26:31.782] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:31.782] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:31.782] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:31.782] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:31.782] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:31.782] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:31.782] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:31.782] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:31.782] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:31.782] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:31.782] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:31.782] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:31.782] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:31.782] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:31.782] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:31.783] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:32.881] <TB3> INFO: Test took 1098ms.
[09:26:32.883] <TB3> INFO: The DUT currently contains the following objects:
[09:26:32.883] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:26:32.883] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:26:32.883] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:26:32.883] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:26:32.883] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:32.883] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:32.883] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:32.883] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:32.883] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:32.883] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:32.883] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:32.883] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:32.883] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:32.883] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:32.883] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:32.884] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:32.884] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:32.884] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:32.884] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:32.884] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:33.991] <TB3> INFO: Test took 1107ms.
[09:26:33.992] <TB3> INFO: The DUT currently contains the following objects:
[09:26:33.992] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:26:33.992] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:26:33.992] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:26:33.992] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:26:33.992] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:33.992] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:33.993] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:33.993] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:33.993] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:33.993] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:33.993] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:33.993] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:33.993] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:33.993] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:33.993] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:33.993] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:33.993] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:33.993] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:33.993] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:33.993] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:35.102] <TB3> INFO: Test took 1109ms.
[09:26:35.104] <TB3> INFO: The DUT currently contains the following objects:
[09:26:35.104] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:26:35.104] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:26:35.104] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:26:35.104] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:26:35.104] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:35.104] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:35.104] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:35.104] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:35.104] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:35.104] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:35.104] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:35.105] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:35.105] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:35.105] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:35.105] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:35.105] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:35.105] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:35.105] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:35.105] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:35.105] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:36.215] <TB3> INFO: Test took 1111ms.
[09:26:36.217] <TB3> INFO: The DUT currently contains the following objects:
[09:26:36.217] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:26:36.217] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:26:36.217] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:26:36.217] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:26:36.217] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:36.217] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:36.217] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:36.217] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:36.217] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:36.217] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:36.217] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:36.217] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:36.217] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:36.217] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:36.217] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:36.217] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:36.217] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:36.217] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:36.217] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:36.217] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:37.319] <TB3> INFO: Test took 1102ms.
[09:26:37.319] <TB3> INFO: The DUT currently contains the following objects:
[09:26:37.319] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:26:37.319] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:26:37.319] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:26:37.319] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:26:37.319] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:37.319] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:37.319] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:37.319] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:37.319] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:37.319] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:37.319] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:37.319] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:37.319] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:37.319] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:37.319] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:37.319] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:37.319] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:37.319] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:37.319] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:37.319] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:38.422] <TB3> INFO: Test took 1103ms.
[09:26:38.423] <TB3> INFO: The DUT currently contains the following objects:
[09:26:38.423] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:26:38.423] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:26:38.423] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:26:38.423] <TB3> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[09:26:38.423] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:38.423] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:38.423] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:38.423] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:38.423] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:38.423] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:38.423] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:38.423] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:38.423] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:38.423] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:38.423] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:38.423] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:38.423] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:38.423] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:38.423] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:38.423] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[09:26:39.531] <TB3> INFO: Test took 1108ms.
[09:26:39.535] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:18.783] <TB3> INFO: Test took 219248ms.
[09:30:20.197] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:02.974] <TB3> INFO: Test took 222777ms.
[09:34:05.824] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:34:05.831] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:34:05.837] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:34:05.844] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:34:05.851] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:34:05.857] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:34:05.864] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:34:05.871] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:34:05.878] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:34:05.885] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:34:05.891] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:34:05.898] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:34:05.904] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:34:05.911] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:34:05.918] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[09:34:05.924] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[09:34:05.931] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[09:34:05.938] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[09:34:05.944] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[09:34:05.951] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:34:05.958] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:34:06.000] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:34:06.001] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:34:06.009] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:34:06.009] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:34:06.009] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:34:06.009] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:34:06.009] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:34:06.009] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:34:06.009] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:34:06.009] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:34:06.010] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:34:06.010] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:34:06.010] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:34:06.010] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:34:06.010] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:34:06.010] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:34:09.437] <TB3> INFO: Test took 3424ms.
[09:34:13.182] <TB3> INFO: Test took 3459ms.
[09:34:16.930] <TB3> INFO: Test took 3460ms.
[09:34:17.215] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:18.132] <TB3> INFO: Test took 917ms.
[09:34:18.134] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:19.236] <TB3> INFO: Test took 1102ms.
[09:34:19.238] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:20.340] <TB3> INFO: Test took 1102ms.
[09:34:20.342] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:21.452] <TB3> INFO: Test took 1110ms.
[09:34:21.454] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:22.560] <TB3> INFO: Test took 1106ms.
[09:34:22.562] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:23.671] <TB3> INFO: Test took 1109ms.
[09:34:23.676] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:24.785] <TB3> INFO: Test took 1110ms.
[09:34:24.789] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:25.899] <TB3> INFO: Test took 1110ms.
[09:34:25.903] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:27.013] <TB3> INFO: Test took 1110ms.
[09:34:27.016] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:28.129] <TB3> INFO: Test took 1113ms.
[09:34:28.132] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:29.241] <TB3> INFO: Test took 1109ms.
[09:34:29.245] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:30.352] <TB3> INFO: Test took 1107ms.
[09:34:30.356] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:31.462] <TB3> INFO: Test took 1107ms.
[09:34:31.465] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:32.572] <TB3> INFO: Test took 1107ms.
[09:34:32.575] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:33.681] <TB3> INFO: Test took 1106ms.
[09:34:33.685] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:34.797] <TB3> INFO: Test took 1112ms.
[09:34:34.801] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:35.909] <TB3> INFO: Test took 1108ms.
[09:34:35.912] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:37.019] <TB3> INFO: Test took 1107ms.
[09:34:37.023] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:38.132] <TB3> INFO: Test took 1109ms.
[09:34:38.135] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:39.243] <TB3> INFO: Test took 1109ms.
[09:34:39.247] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:40.356] <TB3> INFO: Test took 1109ms.
[09:34:40.359] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:41.468] <TB3> INFO: Test took 1109ms.
[09:34:41.471] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:42.580] <TB3> INFO: Test took 1109ms.
[09:34:42.583] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:43.693] <TB3> INFO: Test took 1110ms.
[09:34:43.696] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:44.805] <TB3> INFO: Test took 1109ms.
[09:34:44.808] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:45.917] <TB3> INFO: Test took 1109ms.
[09:34:45.920] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:47.027] <TB3> INFO: Test took 1107ms.
[09:34:47.029] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:48.132] <TB3> INFO: Test took 1103ms.
[09:34:48.135] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:49.238] <TB3> INFO: Test took 1103ms.
[09:34:49.240] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:50.352] <TB3> INFO: Test took 1112ms.
[09:34:50.356] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:51.457] <TB3> INFO: Test took 1101ms.
[09:34:51.460] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:34:52.568] <TB3> INFO: Test took 1109ms.
[09:34:53.122] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 521 seconds
[09:34:53.122] <TB3> INFO: PH scale (per ROC): 80 75 89 90 87 83 80 81 90 87 82 86 83 85 82 80
[09:34:53.122] <TB3> INFO: PH offset (per ROC): 150 158 144 162 148 165 148 174 155 154 161 144 157 144 155 169
[09:34:53.313] <TB3> INFO: ######################################################################
[09:34:53.313] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[09:34:53.313] <TB3> INFO: ######################################################################
[09:34:53.323] <TB3> INFO: scanning low vcal = 10
[09:34:57.176] <TB3> INFO: Test took 3853ms.
[09:34:57.182] <TB3> INFO: scanning low vcal = 20
[09:35:01.029] <TB3> INFO: Test took 3847ms.
[09:35:01.033] <TB3> INFO: scanning low vcal = 30
[09:35:04.905] <TB3> INFO: Test took 3872ms.
[09:35:04.914] <TB3> INFO: scanning low vcal = 40
[09:35:09.235] <TB3> INFO: Test took 4321ms.
[09:35:09.316] <TB3> INFO: scanning low vcal = 50
[09:35:13.664] <TB3> INFO: Test took 4348ms.
[09:35:13.731] <TB3> INFO: scanning low vcal = 60
[09:35:18.123] <TB3> INFO: Test took 4392ms.
[09:35:18.196] <TB3> INFO: scanning low vcal = 70
[09:35:22.485] <TB3> INFO: Test took 4289ms.
[09:35:22.548] <TB3> INFO: scanning low vcal = 80
[09:35:26.906] <TB3> INFO: Test took 4358ms.
[09:35:26.975] <TB3> INFO: scanning low vcal = 90
[09:35:31.324] <TB3> INFO: Test took 4349ms.
[09:35:31.395] <TB3> INFO: scanning low vcal = 100
[09:35:35.779] <TB3> INFO: Test took 4384ms.
[09:35:35.839] <TB3> INFO: scanning low vcal = 110
[09:35:40.042] <TB3> INFO: Test took 4203ms.
[09:35:40.100] <TB3> INFO: scanning low vcal = 120
[09:35:44.345] <TB3> INFO: Test took 4245ms.
[09:35:44.438] <TB3> INFO: scanning low vcal = 130
[09:35:48.734] <TB3> INFO: Test took 4296ms.
[09:35:48.807] <TB3> INFO: scanning low vcal = 140
[09:35:53.047] <TB3> INFO: Test took 4240ms.
[09:35:53.102] <TB3> INFO: scanning low vcal = 150
[09:35:57.315] <TB3> INFO: Test took 4213ms.
[09:35:57.408] <TB3> INFO: scanning low vcal = 160
[09:36:01.645] <TB3> INFO: Test took 4237ms.
[09:36:01.713] <TB3> INFO: scanning low vcal = 170
[09:36:05.955] <TB3> INFO: Test took 4242ms.
[09:36:06.013] <TB3> INFO: scanning low vcal = 180
[09:36:10.226] <TB3> INFO: Test took 4213ms.
[09:36:10.283] <TB3> INFO: scanning low vcal = 190
[09:36:14.706] <TB3> INFO: Test took 4423ms.
[09:36:14.771] <TB3> INFO: scanning low vcal = 200
[09:36:19.122] <TB3> INFO: Test took 4351ms.
[09:36:19.206] <TB3> INFO: scanning low vcal = 210
[09:36:23.592] <TB3> INFO: Test took 4386ms.
[09:36:23.678] <TB3> INFO: scanning low vcal = 220
[09:36:28.045] <TB3> INFO: Test took 4367ms.
[09:36:28.111] <TB3> INFO: scanning low vcal = 230
[09:36:32.477] <TB3> INFO: Test took 4366ms.
[09:36:32.563] <TB3> INFO: scanning low vcal = 240
[09:36:36.892] <TB3> INFO: Test took 4329ms.
[09:36:36.957] <TB3> INFO: scanning low vcal = 250
[09:36:41.327] <TB3> INFO: Test took 4370ms.
[09:36:41.396] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[09:36:45.765] <TB3> INFO: Test took 4369ms.
[09:36:45.826] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[09:36:50.183] <TB3> INFO: Test took 4357ms.
[09:36:50.244] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[09:36:54.459] <TB3> INFO: Test took 4215ms.
[09:36:54.519] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[09:36:58.889] <TB3> INFO: Test took 4370ms.
[09:36:58.951] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[09:37:03.278] <TB3> INFO: Test took 4327ms.
[09:37:03.799] <TB3> INFO: PixTestGainPedestal::measure() done
[09:37:37.656] <TB3> INFO: PixTestGainPedestal::fit() done
[09:37:37.656] <TB3> INFO: non-linearity mean: 0.956 0.953 0.951 0.949 0.958 0.955 0.958 0.957 0.964 0.958 0.951 0.950 0.956 0.958 0.956 0.960
[09:37:37.656] <TB3> INFO: non-linearity RMS: 0.005 0.006 0.005 0.005 0.005 0.005 0.005 0.006 0.004 0.006 0.006 0.007 0.007 0.005 0.006 0.007
[09:37:37.656] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[09:37:37.677] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[09:37:37.699] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[09:37:37.719] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[09:37:37.740] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[09:37:37.761] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[09:37:37.781] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[09:37:37.802] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[09:37:37.822] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[09:37:37.843] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[09:37:37.864] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[09:37:37.885] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[09:37:37.907] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[09:37:37.927] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[09:37:37.950] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[09:37:37.971] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2043_FullQualification_2015-07-23_10h06m_1437638763//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[09:37:37.992] <TB3> INFO: PixTestGainPedestal::doTest() done, duration: 164 seconds
[09:37:37.998] <TB3> INFO: enter test to run
[09:37:37.998] <TB3> INFO: test: exit no parameter change
[09:37:38.404] <TB3> QUIET: Connection to board 170 closed.
[09:37:38.484] <TB3> INFO: pXar: this is the end, my friend