[16:12:40.906] <TB1> INFO: *** Welcome to pxar ***
[16:12:40.906] <TB1> INFO: *** Today: 2015/09/01
[16:12:40.906] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters_C15.dat
[16:12:40.906] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//tbmParameters_C0b.dat
[16:12:40.906] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//defaultMaskFile.dat
[16:12:40.906] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//trimParameters_C15.dat
[16:12:40.969] <TB1> INFO: clk: 4
[16:12:40.969] <TB1> INFO: ctr: 4
[16:12:40.969] <TB1> INFO: sda: 19
[16:12:40.969] <TB1> INFO: tin: 9
[16:12:40.969] <TB1> INFO: level: 15
[16:12:40.969] <TB1> INFO: triggerdelay: 0
[16:12:40.969] <TB1> QUIET: Instanciating API for pxar prod-10
[16:12:40.969] <TB1> INFO: Log level: INFO
[16:12:40.975] <TB1> INFO: Found DTB DTB_WXBYFL
[16:12:40.987] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[16:12:40.990] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[16:12:40.993] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[16:12:42.509] <TB1> INFO: DUT info:
[16:12:42.509] <TB1> INFO: The DUT currently contains the following objects:
[16:12:42.509] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:12:42.509] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:12:42.509] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:12:42.509] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:12:42.509] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:12:42.509] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:12:42.509] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:12:42.509] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:12:42.509] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:12:42.509] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:12:42.509] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:12:42.509] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:12:42.509] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:12:42.509] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:12:42.509] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:12:42.509] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:12:42.509] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:12:42.509] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:12:42.509] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:12:42.509] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:12:42.910] <TB1> INFO: enter 'restricted' command line mode
[16:12:42.910] <TB1> INFO: enter test to run
[16:12:42.910] <TB1> INFO: test: pretest no parameter change
[16:12:42.910] <TB1> INFO: running: pretest
[16:12:42.915] <TB1> INFO: ######################################################################
[16:12:42.915] <TB1> INFO: PixTestPretest::doTest()
[16:12:42.915] <TB1> INFO: ######################################################################
[16:12:42.916] <TB1> INFO: ----------------------------------------------------------------------
[16:12:42.916] <TB1> INFO: PixTestPretest::programROC()
[16:12:42.916] <TB1> INFO: ----------------------------------------------------------------------
[16:13:00.938] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[16:13:00.938] <TB1> INFO: IA differences per ROC: 18.5 18.5 20.1 18.5 17.7 18.5 20.9 20.1 19.3 19.3 19.3 19.3 18.5 19.3 20.1 19.3
[16:13:01.030] <TB1> INFO: ----------------------------------------------------------------------
[16:13:01.030] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[16:13:01.030] <TB1> INFO: ----------------------------------------------------------------------
[16:13:05.024] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 377.8 mA = 23.6125 mA/ROC
[16:13:05.026] <TB1> INFO: ----------------------------------------------------------------------
[16:13:05.026] <TB1> INFO: PixTestPretest::findTiming()
[16:13:05.026] <TB1> INFO: ----------------------------------------------------------------------
[16:13:05.026] <TB1> INFO: PixTestCmd::init()

[16:14:59.222] <TB1> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[16:14:59.224] <TB1> INFO: (success/tries = 100/100), width = 4
[16:14:59.226] <TB1> INFO: ----------------------------------------------------------------------
[16:14:59.228] <TB1> INFO: PixTestPretest::findWorkingPixel()
[16:14:59.228] <TB1> INFO: ----------------------------------------------------------------------
[16:14:59.366] <TB1> INFO: Expecting 231680 events.
[16:15:07.602] <TB1> INFO: 231680 events read in total (7519ms).
[16:15:07.607] <TB1> INFO: Test took 8376ms.
[16:15:07.912] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[16:15:07.961] <TB1> INFO: ----------------------------------------------------------------------
[16:15:07.972] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[16:15:07.972] <TB1> INFO: ----------------------------------------------------------------------
[16:15:08.109] <TB1> INFO: Expecting 231680 events.
[16:15:16.857] <TB1> INFO: 231680 events read in total (8027ms).
[16:15:16.863] <TB1> INFO: Test took 8886ms.
[16:15:17.196] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[16:15:17.200] <TB1> INFO: CalDel: 137 142 161 146 147 167 133 143 137 119 151 139 142 132 143 145
[16:15:17.200] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[16:15:17.203] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters_C0.dat
[16:15:17.205] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters_C1.dat
[16:15:17.205] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters_C2.dat
[16:15:17.205] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters_C3.dat
[16:15:17.206] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters_C4.dat
[16:15:17.208] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters_C5.dat
[16:15:17.208] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters_C6.dat
[16:15:17.208] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters_C7.dat
[16:15:17.208] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters_C8.dat
[16:15:17.210] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters_C9.dat
[16:15:17.210] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters_C10.dat
[16:15:17.210] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters_C11.dat
[16:15:17.210] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters_C12.dat
[16:15:17.215] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters_C13.dat
[16:15:17.215] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters_C14.dat
[16:15:17.215] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters_C15.dat
[16:15:17.215] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//tbmParameters_C0a.dat
[16:15:17.217] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//tbmParameters_C0b.dat
[16:15:17.217] <TB1> INFO: PixTestPretest::doTest() done, duration: 154 seconds
[16:15:17.285] <TB1> INFO: enter test to run
[16:15:17.285] <TB1> INFO: test: fulltest no parameter change
[16:15:17.285] <TB1> INFO: running: fulltest
[16:15:17.285] <TB1> INFO: ######################################################################
[16:15:17.285] <TB1> INFO: PixTestFullTest::doTest()
[16:15:17.285] <TB1> INFO: ######################################################################
[16:15:17.287] <TB1> INFO: ######################################################################
[16:15:17.291] <TB1> INFO: PixTestAlive::doTest()
[16:15:17.291] <TB1> INFO: ######################################################################
[16:15:17.293] <TB1> INFO: ----------------------------------------------------------------------
[16:15:17.294] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:15:17.294] <TB1> INFO: ----------------------------------------------------------------------
[16:15:17.628] <TB1> INFO: Expecting 41600 events.
[16:15:21.903] <TB1> INFO: 41600 events read in total (3558ms).
[16:15:21.907] <TB1> INFO: Test took 4611ms.
[16:15:21.917] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:15:22.215] <TB1> INFO: PixTestAlive::aliveTest() done
[16:15:22.215] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 3 0
[16:15:22.217] <TB1> INFO: ----------------------------------------------------------------------
[16:15:22.222] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:15:22.222] <TB1> INFO: ----------------------------------------------------------------------
[16:15:22.540] <TB1> INFO: Expecting 41600 events.
[16:15:25.660] <TB1> INFO: 41600 events read in total (2402ms).
[16:15:25.660] <TB1> INFO: Test took 3436ms.
[16:15:25.660] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:15:25.661] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:15:25.989] <TB1> INFO: PixTestAlive::maskTest() done
[16:15:25.991] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:15:25.993] <TB1> INFO: ----------------------------------------------------------------------
[16:15:25.995] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:15:25.995] <TB1> INFO: ----------------------------------------------------------------------
[16:15:26.323] <TB1> INFO: Expecting 41600 events.
[16:15:30.636] <TB1> INFO: 41600 events read in total (3593ms).
[16:15:30.637] <TB1> INFO: Test took 4639ms.
[16:15:30.650] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:15:30.948] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[16:15:30.954] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:15:30.954] <TB1> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[16:15:30.962] <TB1> INFO: ######################################################################
[16:15:30.962] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[16:15:30.962] <TB1> INFO: ######################################################################
[16:15:30.966] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 2 (plus default)
[16:15:30.980] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[16:15:30.980] <TB1> INFO: run 1 of 1
[16:15:31.303] <TB1> INFO: Expecting 3120000 events.
[16:16:06.902] <TB1> INFO: 869040 events read in total (34882ms).
[16:16:39.581] <TB1> INFO: 1725905 events read in total (67561ms).
[16:17:12.711] <TB1> INFO: 2596045 events read in total (100692ms).
[16:17:32.767] <TB1> INFO: 3120000 events read in total (120747ms).
[16:17:32.824] <TB1> INFO: Test took 121844ms.
[16:17:32.928] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:17:56.658] <TB1> INFO: PixTestBBMap::doTest() done, duration: 145 seconds
[16:17:56.659] <TB1> INFO: number of dead bumps (per ROC): 1 0 0 0 0 0 0 0 0 1 0 0 1 2 70 13
[16:17:56.659] <TB1> INFO: separation cut (per ROC): 89 93 88 90 73 84 90 83 92 91 81 83 92 83 78 70
[16:17:56.735] <TB1> INFO: ######################################################################
[16:17:56.736] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[16:17:56.736] <TB1> INFO: ######################################################################
[16:17:56.736] <TB1> INFO: ----------------------------------------------------------------------
[16:17:56.736] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[16:17:56.736] <TB1> INFO: ----------------------------------------------------------------------
[16:17:56.736] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (-1/-1) hits flags = 16 (plus default)
[16:17:56.746] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:17:56.748] <TB1> INFO: run 1 of 1
[16:17:57.059] <TB1> INFO: Expecting 31200000 events.
[16:18:20.209] <TB1> INFO: 963300 events read in total (22433ms).
[16:18:42.281] <TB1> INFO: 1912250 events read in total (44505ms).
[16:19:04.166] <TB1> INFO: 2857250 events read in total (66390ms).
[16:19:26.102] <TB1> INFO: 3803850 events read in total (88326ms).
[16:19:47.997] <TB1> INFO: 4745300 events read in total (110221ms).
[16:20:09.792] <TB1> INFO: 5686800 events read in total (132016ms).
[16:20:32.940] <TB1> INFO: 6627500 events read in total (155164ms).
[16:20:54.995] <TB1> INFO: 7568000 events read in total (177219ms).
[16:21:16.941] <TB1> INFO: 8510800 events read in total (199165ms).
[16:21:38.730] <TB1> INFO: 9449400 events read in total (220954ms).
[16:22:00.612] <TB1> INFO: 10390000 events read in total (242836ms).
[16:22:23.482] <TB1> INFO: 11330050 events read in total (265706ms).
[16:22:45.214] <TB1> INFO: 12268200 events read in total (287438ms).
[16:23:07.023] <TB1> INFO: 13207000 events read in total (309247ms).
[16:23:28.949] <TB1> INFO: 14144200 events read in total (331173ms).
[16:23:50.840] <TB1> INFO: 15082450 events read in total (353064ms).
[16:24:13.005] <TB1> INFO: 16011550 events read in total (375229ms).
[16:24:35.125] <TB1> INFO: 16940500 events read in total (397349ms).
[16:24:57.102] <TB1> INFO: 17864700 events read in total (419326ms).
[16:25:19.006] <TB1> INFO: 18792850 events read in total (441230ms).
[16:25:41.157] <TB1> INFO: 19715950 events read in total (463381ms).
[16:26:03.153] <TB1> INFO: 20641450 events read in total (485377ms).
[16:26:25.002] <TB1> INFO: 21565850 events read in total (507226ms).
[16:26:46.749] <TB1> INFO: 22490300 events read in total (528973ms).
[16:27:08.536] <TB1> INFO: 23413600 events read in total (550760ms).
[16:27:31.154] <TB1> INFO: 24336850 events read in total (573378ms).
[16:27:53.119] <TB1> INFO: 25259650 events read in total (595343ms).
[16:28:14.942] <TB1> INFO: 26180750 events read in total (617166ms).
[16:28:36.774] <TB1> INFO: 27100750 events read in total (638998ms).
[16:28:58.576] <TB1> INFO: 28020550 events read in total (660800ms).
[16:29:21.436] <TB1> INFO: 28942950 events read in total (683660ms).
[16:29:43.152] <TB1> INFO: 29864300 events read in total (705376ms).
[16:30:05.119] <TB1> INFO: 30793850 events read in total (727343ms).
[16:30:14.786] <TB1> INFO: 31200000 events read in total (737010ms).
[16:30:14.818] <TB1> INFO: Test took 738070ms.
[16:30:14.906] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:15.107] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:30:16.633] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:30:18.139] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:30:19.607] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:30:21.103] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:30:22.621] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:30:24.107] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:30:25.533] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:30:27.143] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:30:28.712] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:30:30.145] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:30:31.635] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:30:33.129] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:30:34.665] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:30:36.187] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:30:37.619] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:30:39.143] <TB1> INFO: PixTestScurves::scurves() done
[16:30:39.145] <TB1> INFO: Vcal mean: 95.91 95.37 90.29 85.45 80.31 90.82 91.53 81.15 96.89 95.77 88.40 85.02 93.69 91.32 87.90 83.79
[16:30:39.145] <TB1> INFO: Vcal RMS: 5.39 5.55 5.43 5.50 4.65 5.33 5.60 3.97 5.46 6.06 4.88 5.19 6.41 5.40 6.10 4.37
[16:30:39.146] <TB1> INFO: PixTestScurves::fullTest() done, duration: 762 seconds
[16:30:39.222] <TB1> INFO: ######################################################################
[16:30:39.222] <TB1> INFO: PixTestTrim::doTest()
[16:30:39.222] <TB1> INFO: ######################################################################
[16:30:39.224] <TB1> INFO: ----------------------------------------------------------------------
[16:30:39.224] <TB1> INFO: PixTestTrim::trimTest() ntrig = 20, vcal = 35
[16:30:39.224] <TB1> INFO: ----------------------------------------------------------------------
[16:30:39.309] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[16:30:39.309] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 20 dacrange: 0 .. 159 (-1/-1) hits flags = 16 (plus default)
[16:30:39.319] <TB1> INFO: dacScan split into 1 runs with ntrig = 20
[16:30:39.322] <TB1> INFO: run 1 of 1
[16:30:39.635] <TB1> INFO: Expecting 13312000 events.
[16:31:06.781] <TB1> INFO: 1092300 events read in total (26428ms).
[16:31:32.212] <TB1> INFO: 2179980 events read in total (51859ms).
[16:31:57.470] <TB1> INFO: 3264940 events read in total (77117ms).
[16:32:22.936] <TB1> INFO: 4348740 events read in total (102583ms).
[16:32:48.262] <TB1> INFO: 5426440 events read in total (127909ms).
[16:33:13.583] <TB1> INFO: 6501520 events read in total (153230ms).
[16:33:38.884] <TB1> INFO: 7583060 events read in total (178531ms).
[16:34:04.303] <TB1> INFO: 8666720 events read in total (203950ms).
[16:34:29.626] <TB1> INFO: 9749760 events read in total (229273ms).
[16:34:55.117] <TB1> INFO: 10832620 events read in total (254764ms).
[16:35:20.817] <TB1> INFO: 11915700 events read in total (280464ms).
[16:35:46.357] <TB1> INFO: 13000640 events read in total (306004ms).
[16:35:54.030] <TB1> INFO: 13312000 events read in total (313677ms).
[16:35:54.074] <TB1> INFO: Test took 314752ms.
[16:35:54.142] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:13.699] <TB1> INFO: ROC 0 VthrComp = 95
[16:36:13.703] <TB1> INFO: ROC 1 VthrComp = 98
[16:36:13.703] <TB1> INFO: ROC 2 VthrComp = 93
[16:36:13.703] <TB1> INFO: ROC 3 VthrComp = 87
[16:36:13.703] <TB1> INFO: ROC 4 VthrComp = 81
[16:36:13.706] <TB1> INFO: ROC 5 VthrComp = 88
[16:36:13.706] <TB1> INFO: ROC 6 VthrComp = 92
[16:36:13.706] <TB1> INFO: ROC 7 VthrComp = 86
[16:36:13.706] <TB1> INFO: ROC 8 VthrComp = 100
[16:36:13.706] <TB1> INFO: ROC 9 VthrComp = 94
[16:36:13.706] <TB1> INFO: ROC 10 VthrComp = 91
[16:36:13.706] <TB1> INFO: ROC 11 VthrComp = 88
[16:36:13.707] <TB1> INFO: ROC 12 VthrComp = 92
[16:36:13.714] <TB1> INFO: ROC 13 VthrComp = 92
[16:36:13.714] <TB1> INFO: ROC 14 VthrComp = 91
[16:36:13.714] <TB1> INFO: ROC 15 VthrComp = 86
[16:36:13.714] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[16:36:13.717] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 20 dacrange: 0 .. 159 (-1/-1) hits flags = 16 (plus default)
[16:36:13.728] <TB1> INFO: dacScan split into 1 runs with ntrig = 20
[16:36:13.729] <TB1> INFO: run 1 of 1
[16:36:14.042] <TB1> INFO: Expecting 13312000 events.
[16:36:38.077] <TB1> INFO: 780680 events read in total (23313ms).
[16:37:00.549] <TB1> INFO: 1558460 events read in total (45785ms).
[16:37:23.441] <TB1> INFO: 2335140 events read in total (68677ms).
[16:37:46.196] <TB1> INFO: 3111800 events read in total (91432ms).
[16:38:08.723] <TB1> INFO: 3888360 events read in total (113959ms).
[16:38:31.619] <TB1> INFO: 4665220 events read in total (136855ms).
[16:38:54.106] <TB1> INFO: 5442160 events read in total (159342ms).
[16:39:16.628] <TB1> INFO: 6219660 events read in total (181864ms).
[16:39:39.161] <TB1> INFO: 6993380 events read in total (204397ms).
[16:40:02.424] <TB1> INFO: 7763580 events read in total (227660ms).
[16:40:24.824] <TB1> INFO: 8532340 events read in total (250060ms).
[16:40:47.178] <TB1> INFO: 9300460 events read in total (272414ms).
[16:41:09.598] <TB1> INFO: 10066840 events read in total (294834ms).
[16:41:32.133] <TB1> INFO: 10833280 events read in total (317369ms).
[16:41:55.654] <TB1> INFO: 11598240 events read in total (340890ms).
[16:42:18.210] <TB1> INFO: 12363340 events read in total (363446ms).
[16:42:40.703] <TB1> INFO: 13129540 events read in total (385939ms).
[16:42:46.404] <TB1> INFO: 13312000 events read in total (391640ms).
[16:42:46.452] <TB1> INFO: Test took 392722ms.
[16:42:46.611] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:10.381] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 58.6996 for pixel 25/6 mean/min/max = 45.8291/32.9472/58.711
[16:43:10.383] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 58.1636 for pixel 14/74 mean/min/max = 45.0699/31.9191/58.2208
[16:43:10.383] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 57.8438 for pixel 49/66 mean/min/max = 45.585/33.3249/57.8452
[16:43:10.384] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 58.3182 for pixel 10/52 mean/min/max = 45.213/32.0106/58.4154
[16:43:10.384] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 57.6235 for pixel 39/78 mean/min/max = 45.1828/32.465/57.9006
[16:43:10.391] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 59.0804 for pixel 11/12 mean/min/max = 46.2786/33.2525/59.3046
[16:43:10.392] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 61.1913 for pixel 0/64 mean/min/max = 46.9407/32.4387/61.4427
[16:43:10.394] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 55.1309 for pixel 24/70 mean/min/max = 43.7309/32.0053/55.4565
[16:43:10.394] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 58.3507 for pixel 16/77 mean/min/max = 45.2508/32.103/58.3986
[16:43:10.394] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 62.1613 for pixel 0/74 mean/min/max = 47.3863/32.4615/62.3112
[16:43:10.395] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 57.1172 for pixel 30/0 mean/min/max = 45.4081/33.6608/57.1554
[16:43:10.395] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 58.3522 for pixel 9/2 mean/min/max = 45.8534/33.3427/58.3641
[16:43:10.395] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 62.4472 for pixel 0/70 mean/min/max = 47.3702/32.1951/62.5453
[16:43:10.395] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 58.8897 for pixel 16/79 mean/min/max = 45.9222/32.8942/58.9501
[16:43:10.396] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 59.3298 for pixel 18/10 mean/min/max = 45.9464/32.5338/59.3589
[16:43:10.396] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 56.4024 for pixel 0/0 mean/min/max = 44.3911/32.3111/56.4712
[16:43:10.396] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:43:10.529] <TB1> INFO: Expecting 1029120 events.
[16:43:33.029] <TB1> INFO: 1029120 events read in total (21780ms).
[16:43:33.034] <TB1> INFO: Expecting 1029120 events.
[16:43:54.872] <TB1> INFO: 1029120 events read in total (21294ms).
[16:43:54.883] <TB1> INFO: Expecting 1029120 events.
[16:44:16.721] <TB1> INFO: 1029120 events read in total (21299ms).
[16:44:16.730] <TB1> INFO: Expecting 1029120 events.
[16:44:38.595] <TB1> INFO: 1029120 events read in total (21321ms).
[16:44:38.614] <TB1> INFO: Expecting 1029120 events.
[16:45:00.307] <TB1> INFO: 1029120 events read in total (21165ms).
[16:45:00.324] <TB1> INFO: Expecting 1029120 events.
[16:45:23.370] <TB1> INFO: 1029120 events read in total (22511ms).
[16:45:23.386] <TB1> INFO: Expecting 1029120 events.
[16:45:45.244] <TB1> INFO: 1029120 events read in total (21322ms).
[16:45:45.262] <TB1> INFO: Expecting 1029120 events.
[16:46:06.920] <TB1> INFO: 1029120 events read in total (21127ms).
[16:46:06.948] <TB1> INFO: Expecting 1029120 events.
[16:46:28.572] <TB1> INFO: 1029120 events read in total (21096ms).
[16:46:28.593] <TB1> INFO: Expecting 1029120 events.
[16:46:50.295] <TB1> INFO: 1029120 events read in total (21168ms).
[16:46:50.319] <TB1> INFO: Expecting 1029120 events.
[16:47:13.139] <TB1> INFO: 1029120 events read in total (22290ms).
[16:47:13.166] <TB1> INFO: Expecting 1029120 events.
[16:47:34.934] <TB1> INFO: 1029120 events read in total (21240ms).
[16:47:34.969] <TB1> INFO: Expecting 1029120 events.
[16:47:56.541] <TB1> INFO: 1029120 events read in total (21043ms).
[16:47:56.575] <TB1> INFO: Expecting 1029120 events.
[16:48:18.304] <TB1> INFO: 1029120 events read in total (21200ms).
[16:48:18.344] <TB1> INFO: Expecting 1029120 events.
[16:48:40.211] <TB1> INFO: 1029120 events read in total (21338ms).
[16:48:40.247] <TB1> INFO: Expecting 1029120 events.
[16:49:02.550] <TB1> INFO: 1029120 events read in total (21775ms).
[16:49:02.591] <TB1> INFO: Test took 352195ms.
[16:49:03.623] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 20 dacrange: 0 .. 199 (-1/-1) hits flags = 16 (plus default)
[16:49:03.634] <TB1> INFO: dacScan split into 1 runs with ntrig = 20
[16:49:03.639] <TB1> INFO: run 1 of 1
[16:49:03.962] <TB1> INFO: Expecting 16640000 events.
[16:49:26.727] <TB1> INFO: 725540 events read in total (22043ms).
[16:49:48.777] <TB1> INFO: 1447720 events read in total (44093ms).
[16:50:10.774] <TB1> INFO: 2170520 events read in total (66090ms).
[16:50:32.743] <TB1> INFO: 2893420 events read in total (88059ms).
[16:50:55.047] <TB1> INFO: 3615060 events read in total (110363ms).
[16:51:17.058] <TB1> INFO: 4337740 events read in total (132374ms).
[16:51:38.977] <TB1> INFO: 5059780 events read in total (154293ms).
[16:52:01.074] <TB1> INFO: 5783260 events read in total (176390ms).
[16:52:23.060] <TB1> INFO: 6506100 events read in total (198376ms).
[16:52:45.019] <TB1> INFO: 7228620 events read in total (220335ms).
[16:53:07.135] <TB1> INFO: 7951760 events read in total (242451ms).
[16:53:29.131] <TB1> INFO: 8671940 events read in total (264447ms).
[16:53:51.224] <TB1> INFO: 9389740 events read in total (286540ms).
[16:54:13.561] <TB1> INFO: 10106420 events read in total (308877ms).
[16:54:35.347] <TB1> INFO: 10821580 events read in total (330663ms).
[16:54:57.193] <TB1> INFO: 11536740 events read in total (352509ms).
[16:55:19.265] <TB1> INFO: 12251240 events read in total (374581ms).
[16:55:41.262] <TB1> INFO: 12964660 events read in total (396578ms).
[16:56:04.079] <TB1> INFO: 13677780 events read in total (419395ms).
[16:56:25.895] <TB1> INFO: 14390060 events read in total (441211ms).
[16:56:47.785] <TB1> INFO: 15103120 events read in total (463101ms).
[16:57:09.538] <TB1> INFO: 15815080 events read in total (484854ms).
[16:57:31.285] <TB1> INFO: 16528760 events read in total (506601ms).
[16:57:35.069] <TB1> INFO: 16640000 events read in total (510385ms).
[16:57:35.138] <TB1> INFO: Test took 511499ms.
[16:57:35.364] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:58:01.378] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 1.026662 .. 81.530548
[16:58:01.454] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 20 dacrange: 1 .. 91 (-1/-1) hits flags = 16 (plus default)
[16:58:01.465] <TB1> INFO: dacScan split into 1 runs with ntrig = 20
[16:58:01.465] <TB1> INFO: run 1 of 1
[16:58:01.769] <TB1> INFO: Expecting 7571200 events.
[16:58:25.610] <TB1> INFO: 814080 events read in total (23116ms).
[16:58:48.603] <TB1> INFO: 1630340 events read in total (46109ms).
[16:59:11.800] <TB1> INFO: 2447140 events read in total (69306ms).
[16:59:34.758] <TB1> INFO: 3262580 events read in total (92264ms).
[16:59:57.783] <TB1> INFO: 4078520 events read in total (115289ms).
[17:00:21.097] <TB1> INFO: 4893540 events read in total (138603ms).
[17:00:44.062] <TB1> INFO: 5706940 events read in total (161568ms).
[17:01:06.982] <TB1> INFO: 6517220 events read in total (184488ms).
[17:01:31.042] <TB1> INFO: 7327300 events read in total (208548ms).
[17:01:38.295] <TB1> INFO: 7571200 events read in total (215801ms).
[17:01:38.329] <TB1> INFO: Test took 216864ms.
[17:01:38.425] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:01:56.225] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 14.636980 .. 67.265191
[17:01:56.302] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 20 dacrange: 4 .. 77 (-1/-1) hits flags = 16 (plus default)
[17:01:56.316] <TB1> INFO: dacScan split into 1 runs with ntrig = 20
[17:01:56.316] <TB1> INFO: run 1 of 1
[17:01:56.620] <TB1> INFO: Expecting 6156800 events.
[17:02:20.687] <TB1> INFO: 838880 events read in total (23348ms).
[17:02:43.907] <TB1> INFO: 1677820 events read in total (46568ms).
[17:03:07.064] <TB1> INFO: 2516780 events read in total (69725ms).
[17:03:30.394] <TB1> INFO: 3354880 events read in total (93055ms).
[17:03:53.493] <TB1> INFO: 4192780 events read in total (116154ms).
[17:04:16.699] <TB1> INFO: 5030440 events read in total (139360ms).
[17:04:39.873] <TB1> INFO: 5866440 events read in total (162534ms).
[17:04:48.171] <TB1> INFO: 6156800 events read in total (170832ms).
[17:04:48.190] <TB1> INFO: Test took 171874ms.
[17:04:48.250] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:05:04.244] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 19.562263 .. 59.150091
[17:05:04.321] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 20 dacrange: 9 .. 69 (-1/-1) hits flags = 16 (plus default)
[17:05:04.329] <TB1> INFO: dacScan split into 1 runs with ntrig = 20
[17:05:04.329] <TB1> INFO: run 1 of 1
[17:05:04.637] <TB1> INFO: Expecting 5075200 events.
[17:05:29.056] <TB1> INFO: 843760 events read in total (23696ms).
[17:05:52.882] <TB1> INFO: 1687840 events read in total (47522ms).
[17:06:16.653] <TB1> INFO: 2531180 events read in total (71293ms).
[17:06:40.389] <TB1> INFO: 3374340 events read in total (95029ms).
[17:07:04.874] <TB1> INFO: 4217240 events read in total (119514ms).
[17:07:28.173] <TB1> INFO: 5059140 events read in total (142813ms).
[17:07:29.093] <TB1> INFO: 5075200 events read in total (143733ms).
[17:07:29.113] <TB1> INFO: Test took 144784ms.
[17:07:29.166] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:44.698] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 21.732096 .. 58.128402
[17:07:44.783] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 20 dacrange: 11 .. 68 (-1/-1) hits flags = 16 (plus default)
[17:07:44.795] <TB1> INFO: dacScan split into 1 runs with ntrig = 20
[17:07:44.799] <TB1> INFO: run 1 of 1
[17:07:45.118] <TB1> INFO: Expecting 4825600 events.
[17:08:09.006] <TB1> INFO: 837000 events read in total (23167ms).
[17:08:32.476] <TB1> INFO: 1674440 events read in total (46637ms).
[17:08:56.742] <TB1> INFO: 2511240 events read in total (70903ms).
[17:09:19.791] <TB1> INFO: 3348820 events read in total (93952ms).
[17:09:43.004] <TB1> INFO: 4185500 events read in total (117165ms).
[17:10:01.064] <TB1> INFO: 4825600 events read in total (135225ms).
[17:10:01.083] <TB1> INFO: Test took 136284ms.
[17:10:01.131] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:10:16.386] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[17:10:16.389] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 20 dacrange: 15 .. 55 (-1/-1) hits flags = 16 (plus default)
[17:10:16.398] <TB1> INFO: dacScan split into 1 runs with ntrig = 20
[17:10:16.398] <TB1> INFO: run 1 of 1
[17:10:16.711] <TB1> INFO: Expecting 3411200 events.
[17:10:42.274] <TB1> INFO: 878400 events read in total (24840ms).
[17:11:05.907] <TB1> INFO: 1756920 events read in total (48473ms).
[17:11:29.591] <TB1> INFO: 2634540 events read in total (72158ms).
[17:11:50.588] <TB1> INFO: 3411200 events read in total (93154ms).
[17:11:50.604] <TB1> INFO: Test took 94205ms.
[17:11:50.640] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:12:04.581] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C0.dat
[17:12:04.581] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C1.dat
[17:12:04.581] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C2.dat
[17:12:04.582] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C3.dat
[17:12:04.584] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C4.dat
[17:12:04.584] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C5.dat
[17:12:04.584] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C6.dat
[17:12:04.584] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C7.dat
[17:12:04.584] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C8.dat
[17:12:04.584] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C9.dat
[17:12:04.584] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C10.dat
[17:12:04.584] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C11.dat
[17:12:04.584] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C12.dat
[17:12:04.591] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C13.dat
[17:12:04.591] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C14.dat
[17:12:04.591] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C15.dat
[17:12:04.592] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//trimParameters35_C0.dat
[17:12:04.600] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//trimParameters35_C1.dat
[17:12:04.612] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//trimParameters35_C2.dat
[17:12:04.619] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//trimParameters35_C3.dat
[17:12:04.629] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//trimParameters35_C4.dat
[17:12:04.636] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//trimParameters35_C5.dat
[17:12:04.648] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//trimParameters35_C6.dat
[17:12:04.660] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//trimParameters35_C7.dat
[17:12:04.667] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//trimParameters35_C8.dat
[17:12:04.676] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//trimParameters35_C9.dat
[17:12:04.687] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//trimParameters35_C10.dat
[17:12:04.699] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//trimParameters35_C11.dat
[17:12:04.706] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//trimParameters35_C12.dat
[17:12:04.714] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//trimParameters35_C13.dat
[17:12:04.722] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//trimParameters35_C14.dat
[17:12:04.731] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//trimParameters35_C15.dat
[17:12:04.738] <TB1> INFO: PixTestTrim::trimTest() done
[17:12:04.738] <TB1> INFO: vtrim: 96 97 86 98 83 99 102 84 87 103 85 98 92 88 100 82
[17:12:04.738] <TB1> INFO: vthrcomp: 95 98 93 87 81 88 92 86 100 94 91 88 92 92 91 86
[17:12:04.738] <TB1> INFO: vcal mean: 34.98 34.97 34.96 34.95 34.94 34.99 34.98 34.95 34.92 35.01 34.96 34.95 34.99 34.94 34.96 34.95
[17:12:04.745] <TB1> INFO: vcal RMS: 0.71 0.70 0.66 0.72 0.69 0.72 0.71 0.64 0.71 0.75 0.66 0.71 0.80 0.68 1.04 0.68
[17:12:04.745] <TB1> INFO: bits mean: 9.23 9.47 8.99 9.47 9.12 9.14 8.48 9.69 9.15 8.45 9.22 9.38 8.43 8.76 9.25 9.54
[17:12:04.745] <TB1> INFO: bits RMS: 2.68 2.74 2.74 2.75 2.86 2.63 2.99 2.70 2.94 3.01 2.60 2.58 3.04 2.87 2.67 2.75
[17:12:04.754] <TB1> INFO: ----------------------------------------------------------------------
[17:12:04.756] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[17:12:04.756] <TB1> INFO: ----------------------------------------------------------------------
[17:12:04.760] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 16 (plus default)
[17:12:04.769] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[17:12:04.773] <TB1> INFO: run 1 of 1
[17:12:05.094] <TB1> INFO: Expecting 8320000 events.
[17:12:34.144] <TB1> INFO: 943450 events read in total (28334ms).
[17:13:01.292] <TB1> INFO: 1877350 events read in total (55482ms).
[17:13:28.602] <TB1> INFO: 2809380 events read in total (82792ms).
[17:13:55.804] <TB1> INFO: 3738500 events read in total (109994ms).
[17:14:23.916] <TB1> INFO: 4661530 events read in total (138106ms).
[17:14:50.943] <TB1> INFO: 5579830 events read in total (165133ms).
[17:15:17.895] <TB1> INFO: 6497930 events read in total (192085ms).
[17:15:45.000] <TB1> INFO: 7414840 events read in total (219190ms).
[17:16:12.659] <TB1> INFO: 8320000 events read in total (246849ms).
[17:16:12.702] <TB1> INFO: Test took 247930ms.
[17:16:12.830] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:40.080] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 162 (-1/-1) hits flags = 16 (plus default)
[17:16:40.090] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[17:16:40.091] <TB1> INFO: run 1 of 1
[17:16:40.401] <TB1> INFO: Expecting 6780800 events.
[17:17:09.029] <TB1> INFO: 992520 events read in total (27908ms).
[17:17:36.993] <TB1> INFO: 1975400 events read in total (55872ms).
[17:18:05.387] <TB1> INFO: 2953770 events read in total (84266ms).
[17:18:33.193] <TB1> INFO: 3924180 events read in total (112072ms).
[17:19:00.961] <TB1> INFO: 4888230 events read in total (139840ms).
[17:19:28.692] <TB1> INFO: 5850510 events read in total (167571ms).
[17:19:55.511] <TB1> INFO: 6780800 events read in total (194390ms).
[17:19:55.545] <TB1> INFO: Test took 195454ms.
[17:19:55.630] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:20.491] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 152 (-1/-1) hits flags = 16 (plus default)
[17:20:20.501] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[17:20:20.508] <TB1> INFO: run 1 of 1
[17:20:20.819] <TB1> INFO: Expecting 6364800 events.
[17:20:50.133] <TB1> INFO: 1025480 events read in total (28596ms).
[17:21:18.467] <TB1> INFO: 2039240 events read in total (56930ms).
[17:21:46.824] <TB1> INFO: 3048630 events read in total (85287ms).
[17:22:15.043] <TB1> INFO: 4044820 events read in total (113506ms).
[17:22:43.639] <TB1> INFO: 5038130 events read in total (142102ms).
[17:23:11.982] <TB1> INFO: 6028440 events read in total (170445ms).
[17:23:21.784] <TB1> INFO: 6364800 events read in total (180247ms).
[17:23:21.814] <TB1> INFO: Test took 181305ms.
[17:23:21.886] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:45.298] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 151 (-1/-1) hits flags = 16 (plus default)
[17:23:45.308] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[17:23:45.312] <TB1> INFO: run 1 of 1
[17:23:45.634] <TB1> INFO: Expecting 6323200 events.
[17:24:14.814] <TB1> INFO: 1028580 events read in total (28460ms).
[17:24:44.638] <TB1> INFO: 2045620 events read in total (58285ms).
[17:25:13.160] <TB1> INFO: 3058200 events read in total (86807ms).
[17:25:41.102] <TB1> INFO: 4056780 events read in total (114748ms).
[17:26:09.230] <TB1> INFO: 5053340 events read in total (142876ms).
[17:26:37.290] <TB1> INFO: 6047160 events read in total (170936ms).
[17:26:45.346] <TB1> INFO: 6323200 events read in total (178992ms).
[17:26:45.372] <TB1> INFO: Test took 180060ms.
[17:26:45.436] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:27:07.241] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 151 (-1/-1) hits flags = 16 (plus default)
[17:27:07.254] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[17:27:07.257] <TB1> INFO: run 1 of 1
[17:27:07.573] <TB1> INFO: Expecting 6323200 events.
[17:27:36.910] <TB1> INFO: 1028580 events read in total (28611ms).
[17:28:05.298] <TB1> INFO: 2045350 events read in total (57000ms).
[17:28:33.370] <TB1> INFO: 3057540 events read in total (85072ms).
[17:29:01.536] <TB1> INFO: 4055360 events read in total (113237ms).
[17:29:30.134] <TB1> INFO: 5051800 events read in total (141835ms).
[17:29:58.005] <TB1> INFO: 6045560 events read in total (169706ms).
[17:30:06.145] <TB1> INFO: 6323200 events read in total (177846ms).
[17:30:06.171] <TB1> INFO: Test took 178914ms.
[17:30:06.239] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:30:28.508] <TB1> INFO: PixTestTrim::trimBitTest() done
[17:30:28.510] <TB1> INFO: PixTestTrim::doTest() done, duration: 3589 seconds
[17:30:29.215] <TB1> INFO: ######################################################################
[17:30:29.216] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[17:30:29.216] <TB1> INFO: ######################################################################
[17:30:29.524] <TB1> INFO: Expecting 41600 events.
[17:30:33.799] <TB1> INFO: 41600 events read in total (3553ms).
[17:30:33.802] <TB1> INFO: Test took 4584ms.
[17:30:33.811] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:30:34.384] <TB1> INFO: Expecting 41600 events.
[17:30:38.753] <TB1> INFO: 41600 events read in total (3649ms).
[17:30:38.756] <TB1> INFO: Test took 4685ms.
[17:30:38.765] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:30:39.104] <TB1> INFO: Expecting 41600 events.
[17:30:43.420] <TB1> INFO: 41600 events read in total (3599ms).
[17:30:43.427] <TB1> INFO: Test took 4644ms.
[17:30:43.436] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:30:43.448] <TB1> INFO: The DUT currently contains the following objects:
[17:30:43.449] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:30:43.449] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:30:43.449] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:30:43.449] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[17:30:43.449] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:43.449] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:43.449] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:43.449] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:43.449] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:43.449] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:43.449] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:43.449] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:43.449] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:43.449] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:43.449] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:43.449] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:43.449] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:43.452] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:43.452] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:43.452] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:43.777] <TB1> INFO: Expecting 2560 events.
[17:30:44.843] <TB1> INFO: 2560 events read in total (347ms).
[17:30:44.844] <TB1> INFO: Test took 1392ms.
[17:30:44.844] <TB1> INFO: The DUT currently contains the following objects:
[17:30:44.844] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:30:44.844] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:30:44.844] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:30:44.844] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[17:30:44.844] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:44.844] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:44.844] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:44.844] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:44.844] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:44.844] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:44.846] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:44.852] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:44.852] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:44.852] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:44.852] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:44.852] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:44.852] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:44.852] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:44.852] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:44.852] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:45.260] <TB1> INFO: Expecting 2560 events.
[17:30:46.324] <TB1> INFO: 2560 events read in total (348ms).
[17:30:46.327] <TB1> INFO: Test took 1475ms.
[17:30:46.327] <TB1> INFO: The DUT currently contains the following objects:
[17:30:46.327] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:30:46.327] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:30:46.327] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:30:46.327] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[17:30:46.327] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:46.327] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:46.327] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:46.327] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:46.327] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:46.327] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:46.327] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:46.327] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:46.327] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:46.327] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:46.331] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:46.331] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:46.334] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:46.334] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:46.334] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:46.334] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:46.740] <TB1> INFO: Expecting 2560 events.
[17:30:47.806] <TB1> INFO: 2560 events read in total (346ms).
[17:30:47.806] <TB1> INFO: Test took 1472ms.
[17:30:47.806] <TB1> INFO: The DUT currently contains the following objects:
[17:30:47.806] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:30:47.806] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:30:47.806] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:30:47.806] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[17:30:47.806] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:47.806] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:47.806] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:47.806] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:47.806] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:47.806] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:47.807] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:47.807] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:47.807] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:47.807] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:47.807] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:47.807] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:47.807] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:47.807] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:47.807] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:47.807] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:48.222] <TB1> INFO: Expecting 2560 events.
[17:30:49.287] <TB1> INFO: 2560 events read in total (347ms).
[17:30:49.292] <TB1> INFO: Test took 1485ms.
[17:30:49.292] <TB1> INFO: The DUT currently contains the following objects:
[17:30:49.294] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:30:49.294] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:30:49.294] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:30:49.294] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[17:30:49.294] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:49.294] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:49.294] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:49.294] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:49.294] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:49.294] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:49.294] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:49.294] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:49.294] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:49.294] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:49.294] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:49.294] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:49.294] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:49.294] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:49.294] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:49.294] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:49.703] <TB1> INFO: Expecting 2560 events.
[17:30:50.767] <TB1> INFO: 2560 events read in total (347ms).
[17:30:50.769] <TB1> INFO: Test took 1475ms.
[17:30:50.770] <TB1> INFO: The DUT currently contains the following objects:
[17:30:50.772] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:30:50.772] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:30:50.772] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:30:50.772] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[17:30:50.772] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:50.772] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:50.772] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:50.772] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:50.772] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:50.772] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:50.772] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:50.772] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:50.772] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:50.772] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:50.772] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:50.772] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:50.775] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:50.776] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:50.776] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:50.776] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:51.184] <TB1> INFO: Expecting 2560 events.
[17:30:52.252] <TB1> INFO: 2560 events read in total (348ms).
[17:30:52.255] <TB1> INFO: Test took 1479ms.
[17:30:52.256] <TB1> INFO: The DUT currently contains the following objects:
[17:30:52.259] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:30:52.259] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:30:52.260] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:30:52.260] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[17:30:52.260] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:52.260] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:52.260] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:52.260] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:52.260] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:52.260] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:52.260] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:52.260] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:52.260] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:52.260] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:52.260] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:52.260] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:52.260] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:52.260] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:52.260] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:52.260] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:52.668] <TB1> INFO: Expecting 2560 events.
[17:30:53.735] <TB1> INFO: 2560 events read in total (347ms).
[17:30:53.735] <TB1> INFO: Test took 1475ms.
[17:30:53.736] <TB1> INFO: The DUT currently contains the following objects:
[17:30:53.742] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:30:53.742] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:30:53.742] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:30:53.742] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[17:30:53.742] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:53.742] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:53.743] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:53.743] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:53.743] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:53.743] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:53.743] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:53.743] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:53.743] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:53.743] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:53.743] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:53.743] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:53.745] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:53.745] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:53.745] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:53.745] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:54.151] <TB1> INFO: Expecting 2560 events.
[17:30:55.215] <TB1> INFO: 2560 events read in total (347ms).
[17:30:55.215] <TB1> INFO: Test took 1470ms.
[17:30:55.216] <TB1> INFO: The DUT currently contains the following objects:
[17:30:55.216] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:30:55.220] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:30:55.220] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:30:55.220] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[17:30:55.220] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:55.220] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:55.220] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:55.220] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:55.220] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:55.220] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:55.220] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:55.220] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:55.220] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:55.220] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:55.220] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:55.220] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:55.220] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:55.220] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:55.220] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:55.223] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:55.631] <TB1> INFO: Expecting 2560 events.
[17:30:56.696] <TB1> INFO: 2560 events read in total (346ms).
[17:30:56.696] <TB1> INFO: Test took 1473ms.
[17:30:56.697] <TB1> INFO: The DUT currently contains the following objects:
[17:30:56.697] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:30:56.697] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:30:56.697] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:30:56.697] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[17:30:56.697] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:56.697] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:56.697] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:56.697] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:56.697] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:56.697] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:56.703] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:56.703] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:56.703] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:56.703] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:56.703] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:56.703] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:56.703] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:56.703] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:56.703] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:56.703] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:57.112] <TB1> INFO: Expecting 2560 events.
[17:30:58.177] <TB1> INFO: 2560 events read in total (347ms).
[17:30:58.178] <TB1> INFO: Test took 1475ms.
[17:30:58.179] <TB1> INFO: The DUT currently contains the following objects:
[17:30:58.183] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:30:58.183] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:30:58.183] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:30:58.183] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[17:30:58.183] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:58.183] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:58.183] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:58.183] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:58.183] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:58.183] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:58.183] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:58.183] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:58.183] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:58.183] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:58.183] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:58.183] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:58.183] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:58.183] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:58.183] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:58.183] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:58.593] <TB1> INFO: Expecting 2560 events.
[17:30:59.659] <TB1> INFO: 2560 events read in total (347ms).
[17:30:59.659] <TB1> INFO: Test took 1476ms.
[17:30:59.659] <TB1> INFO: The DUT currently contains the following objects:
[17:30:59.661] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:30:59.661] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:30:59.661] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:30:59.661] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[17:30:59.661] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:59.661] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:59.661] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:59.661] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:59.661] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:59.661] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:59.661] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:59.661] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:59.661] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:59.661] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:59.661] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:59.661] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:59.664] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:59.664] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:59.664] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:30:59.664] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:00.075] <TB1> INFO: Expecting 2560 events.
[17:31:01.143] <TB1> INFO: 2560 events read in total (347ms).
[17:31:01.144] <TB1> INFO: Test took 1480ms.
[17:31:01.144] <TB1> INFO: The DUT currently contains the following objects:
[17:31:01.149] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:31:01.149] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:31:01.149] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:31:01.149] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[17:31:01.149] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:01.149] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:01.149] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:01.149] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:01.149] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:01.149] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:01.149] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:01.149] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:01.149] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:01.149] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:01.149] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:01.151] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:01.152] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:01.152] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:01.152] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:01.152] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:01.560] <TB1> INFO: Expecting 2560 events.
[17:31:02.627] <TB1> INFO: 2560 events read in total (347ms).
[17:31:02.630] <TB1> INFO: Test took 1478ms.
[17:31:02.630] <TB1> INFO: The DUT currently contains the following objects:
[17:31:02.630] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:31:02.630] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:31:02.630] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:31:02.630] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[17:31:02.630] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:02.631] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:02.631] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:02.631] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:02.631] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:02.631] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:02.631] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:02.631] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:02.631] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:02.631] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:02.631] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:02.632] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:02.636] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:02.636] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:02.636] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:02.636] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:03.044] <TB1> INFO: Expecting 2560 events.
[17:31:04.109] <TB1> INFO: 2560 events read in total (348ms).
[17:31:04.110] <TB1> INFO: Test took 1474ms.
[17:31:04.110] <TB1> INFO: The DUT currently contains the following objects:
[17:31:04.112] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:31:04.112] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:31:04.112] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:31:04.112] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[17:31:04.112] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:04.112] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:04.112] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:04.112] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:04.112] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:04.112] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:04.112] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:04.112] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:04.112] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:04.112] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:04.112] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:04.112] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:04.112] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:04.117] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:04.117] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:04.118] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:04.525] <TB1> INFO: Expecting 2560 events.
[17:31:05.592] <TB1> INFO: 2560 events read in total (349ms).
[17:31:05.592] <TB1> INFO: Test took 1474ms.
[17:31:05.592] <TB1> INFO: The DUT currently contains the following objects:
[17:31:05.592] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:31:05.592] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:31:05.592] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:31:05.592] <TB1> INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[17:31:05.592] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:05.592] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:05.592] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:05.592] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:05.592] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:05.592] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:05.592] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:05.592] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:05.592] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:05.592] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:05.592] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:05.592] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:05.592] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:05.593] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:05.593] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:05.593] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[17:31:06.008] <TB1> INFO: Expecting 2560 events.
[17:31:07.073] <TB1> INFO: 2560 events read in total (348ms).
[17:31:07.080] <TB1> INFO: Test took 1487ms.
[17:31:07.085] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:31:07.491] <TB1> INFO: Expecting 655360 events.
[17:31:22.830] <TB1> INFO: 655360 events read in total (14613ms).
[17:31:22.841] <TB1> INFO: Expecting 655360 events.
[17:31:38.961] <TB1> INFO: 655360 events read in total (15590ms).
[17:31:38.973] <TB1> INFO: Expecting 655360 events.
[17:31:54.105] <TB1> INFO: 655360 events read in total (14602ms).
[17:31:54.121] <TB1> INFO: Expecting 655360 events.
[17:32:09.288] <TB1> INFO: 655360 events read in total (14633ms).
[17:32:09.311] <TB1> INFO: Expecting 655360 events.
[17:32:24.431] <TB1> INFO: 655360 events read in total (14591ms).
[17:32:24.461] <TB1> INFO: Expecting 655360 events.
[17:32:39.650] <TB1> INFO: 655360 events read in total (14656ms).
[17:32:39.679] <TB1> INFO: Expecting 655360 events.
[17:32:54.931] <TB1> INFO: 655360 events read in total (14724ms).
[17:32:54.965] <TB1> INFO: Expecting 655360 events.
[17:33:10.198] <TB1> INFO: 655360 events read in total (14705ms).
[17:33:10.235] <TB1> INFO: Expecting 655360 events.
[17:33:25.346] <TB1> INFO: 655360 events read in total (14583ms).
[17:33:25.388] <TB1> INFO: Expecting 655360 events.
[17:33:40.948] <TB1> INFO: 655360 events read in total (15032ms).
[17:33:40.988] <TB1> INFO: Expecting 655360 events.
[17:33:56.417] <TB1> INFO: 655360 events read in total (14901ms).
[17:33:56.468] <TB1> INFO: Expecting 655360 events.
[17:34:11.789] <TB1> INFO: 655360 events read in total (14792ms).
[17:34:11.843] <TB1> INFO: Expecting 655360 events.
[17:34:27.011] <TB1> INFO: 655360 events read in total (14640ms).
[17:34:27.071] <TB1> INFO: Expecting 655360 events.
[17:34:42.299] <TB1> INFO: 655360 events read in total (14696ms).
[17:34:42.381] <TB1> INFO: Expecting 655360 events.
[17:34:57.621] <TB1> INFO: 655360 events read in total (14707ms).
[17:34:57.684] <TB1> INFO: Expecting 655360 events.
[17:35:13.123] <TB1> INFO: 655360 events read in total (14911ms).
[17:35:13.195] <TB1> INFO: Test took 246110ms.
[17:35:13.293] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:13.600] <TB1> INFO: Expecting 655360 events.
[17:35:29.198] <TB1> INFO: 655360 events read in total (14874ms).
[17:35:29.213] <TB1> INFO: Expecting 655360 events.
[17:35:44.675] <TB1> INFO: 655360 events read in total (14931ms).
[17:35:44.689] <TB1> INFO: Expecting 655360 events.
[17:36:00.084] <TB1> INFO: 655360 events read in total (14855ms).
[17:36:00.100] <TB1> INFO: Expecting 655360 events.
[17:36:15.442] <TB1> INFO: 655360 events read in total (14813ms).
[17:36:15.468] <TB1> INFO: Expecting 655360 events.
[17:36:30.757] <TB1> INFO: 655360 events read in total (14755ms).
[17:36:30.795] <TB1> INFO: Expecting 655360 events.
[17:36:46.142] <TB1> INFO: 655360 events read in total (14818ms).
[17:36:46.175] <TB1> INFO: Expecting 655360 events.
[17:37:01.652] <TB1> INFO: 655360 events read in total (14947ms).
[17:37:01.693] <TB1> INFO: Expecting 655360 events.
[17:37:16.931] <TB1> INFO: 655360 events read in total (14710ms).
[17:37:16.969] <TB1> INFO: Expecting 655360 events.
[17:37:32.069] <TB1> INFO: 655360 events read in total (14572ms).
[17:37:32.114] <TB1> INFO: Expecting 655360 events.
[17:37:47.293] <TB1> INFO: 655360 events read in total (14651ms).
[17:37:47.339] <TB1> INFO: Expecting 655360 events.
[17:38:02.438] <TB1> INFO: 655360 events read in total (14570ms).
[17:38:02.489] <TB1> INFO: Expecting 655360 events.
[17:38:18.775] <TB1> INFO: 655360 events read in total (15757ms).
[17:38:18.821] <TB1> INFO: Expecting 655360 events.
[17:38:34.046] <TB1> INFO: 655360 events read in total (14696ms).
[17:38:34.099] <TB1> INFO: Expecting 655360 events.
[17:38:49.392] <TB1> INFO: 655360 events read in total (14758ms).
[17:38:49.452] <TB1> INFO: Expecting 655360 events.
[17:39:04.618] <TB1> INFO: 655360 events read in total (14637ms).
[17:39:04.688] <TB1> INFO: Expecting 655360 events.
[17:39:19.986] <TB1> INFO: 655360 events read in total (14768ms).
[17:39:20.056] <TB1> INFO: Test took 246763ms.
[17:39:20.275] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:39:20.283] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:39:20.290] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:39:20.300] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:39:20.308] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:39:20.318] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:39:20.325] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:39:20.334] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:39:20.342] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:39:20.352] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:39:20.363] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:39:20.374] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:39:20.387] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:39:20.400] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:39:20.410] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:39:20.418] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:39:20.426] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[17:39:20.434] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[17:39:20.443] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[17:39:20.458] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[17:39:20.467] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[17:39:20.475] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[17:39:20.532] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C0.dat
[17:39:20.532] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C1.dat
[17:39:20.532] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C2.dat
[17:39:20.532] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C3.dat
[17:39:20.532] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C4.dat
[17:39:20.532] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C5.dat
[17:39:20.532] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C6.dat
[17:39:20.532] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C7.dat
[17:39:20.532] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C8.dat
[17:39:20.532] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C9.dat
[17:39:20.532] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C10.dat
[17:39:20.533] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C11.dat
[17:39:20.533] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C12.dat
[17:39:20.533] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C13.dat
[17:39:20.533] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C14.dat
[17:39:20.533] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//dacParameters35_C15.dat
[17:39:20.847] <TB1> INFO: Expecting 41600 events.
[17:39:25.163] <TB1> INFO: 41600 events read in total (3596ms).
[17:39:25.163] <TB1> INFO: Test took 4626ms.
[17:39:25.728] <TB1> INFO: Expecting 41600 events.
[17:39:30.031] <TB1> INFO: 41600 events read in total (3583ms).
[17:39:30.033] <TB1> INFO: Test took 4627ms.
[17:39:30.611] <TB1> INFO: Expecting 41600 events.
[17:39:34.936] <TB1> INFO: 41600 events read in total (3605ms).
[17:39:34.938] <TB1> INFO: Test took 4660ms.
[17:39:35.184] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:39:35.317] <TB1> INFO: Expecting 2560 events.
[17:39:36.380] <TB1> INFO: 2560 events read in total (347ms).
[17:39:36.386] <TB1> INFO: Test took 1202ms.
[17:39:36.388] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:39:36.798] <TB1> INFO: Expecting 2560 events.
[17:39:37.865] <TB1> INFO: 2560 events read in total (349ms).
[17:39:37.865] <TB1> INFO: Test took 1477ms.
[17:39:37.868] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:39:38.281] <TB1> INFO: Expecting 2560 events.
[17:39:39.348] <TB1> INFO: 2560 events read in total (347ms).
[17:39:39.348] <TB1> INFO: Test took 1480ms.
[17:39:39.357] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:39:39.765] <TB1> INFO: Expecting 2560 events.
[17:39:40.831] <TB1> INFO: 2560 events read in total (349ms).
[17:39:40.832] <TB1> INFO: Test took 1475ms.
[17:39:40.835] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:39:41.249] <TB1> INFO: Expecting 2560 events.
[17:39:42.317] <TB1> INFO: 2560 events read in total (348ms).
[17:39:42.321] <TB1> INFO: Test took 1486ms.
[17:39:42.323] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:39:42.735] <TB1> INFO: Expecting 2560 events.
[17:39:43.802] <TB1> INFO: 2560 events read in total (347ms).
[17:39:43.802] <TB1> INFO: Test took 1479ms.
[17:39:43.805] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:39:44.220] <TB1> INFO: Expecting 2560 events.
[17:39:45.289] <TB1> INFO: 2560 events read in total (349ms).
[17:39:45.290] <TB1> INFO: Test took 1485ms.
[17:39:45.292] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:39:45.706] <TB1> INFO: Expecting 2560 events.
[17:39:46.775] <TB1> INFO: 2560 events read in total (349ms).
[17:39:46.776] <TB1> INFO: Test took 1484ms.
[17:39:46.779] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:39:47.192] <TB1> INFO: Expecting 2560 events.
[17:39:48.262] <TB1> INFO: 2560 events read in total (351ms).
[17:39:48.262] <TB1> INFO: Test took 1483ms.
[17:39:48.265] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:39:48.679] <TB1> INFO: Expecting 2560 events.
[17:39:49.743] <TB1> INFO: 2560 events read in total (348ms).
[17:39:49.751] <TB1> INFO: Test took 1486ms.
[17:39:49.754] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:39:50.161] <TB1> INFO: Expecting 2560 events.
[17:39:51.227] <TB1> INFO: 2560 events read in total (347ms).
[17:39:51.229] <TB1> INFO: Test took 1475ms.
[17:39:51.231] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:39:51.644] <TB1> INFO: Expecting 2560 events.
[17:39:52.710] <TB1> INFO: 2560 events read in total (349ms).
[17:39:52.712] <TB1> INFO: Test took 1481ms.
[17:39:52.715] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:39:53.127] <TB1> INFO: Expecting 2560 events.
[17:39:54.197] <TB1> INFO: 2560 events read in total (349ms).
[17:39:54.200] <TB1> INFO: Test took 1485ms.
[17:39:54.202] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:39:54.614] <TB1> INFO: Expecting 2560 events.
[17:39:55.681] <TB1> INFO: 2560 events read in total (347ms).
[17:39:55.682] <TB1> INFO: Test took 1480ms.
[17:39:55.687] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:39:56.098] <TB1> INFO: Expecting 2560 events.
[17:39:57.164] <TB1> INFO: 2560 events read in total (348ms).
[17:39:57.165] <TB1> INFO: Test took 1478ms.
[17:39:57.168] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:39:57.581] <TB1> INFO: Expecting 2560 events.
[17:39:58.647] <TB1> INFO: 2560 events read in total (347ms).
[17:39:58.653] <TB1> INFO: Test took 1485ms.
[17:39:58.656] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:39:59.064] <TB1> INFO: Expecting 2560 events.
[17:40:00.128] <TB1> INFO: 2560 events read in total (347ms).
[17:40:00.131] <TB1> INFO: Test took 1476ms.
[17:40:00.133] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:40:00.545] <TB1> INFO: Expecting 2560 events.
[17:40:01.610] <TB1> INFO: 2560 events read in total (347ms).
[17:40:01.613] <TB1> INFO: Test took 1480ms.
[17:40:01.615] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:40:02.027] <TB1> INFO: Expecting 2560 events.
[17:40:03.095] <TB1> INFO: 2560 events read in total (350ms).
[17:40:03.098] <TB1> INFO: Test took 1483ms.
[17:40:03.103] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:40:03.512] <TB1> INFO: Expecting 2560 events.
[17:40:04.578] <TB1> INFO: 2560 events read in total (348ms).
[17:40:04.581] <TB1> INFO: Test took 1478ms.
[17:40:04.583] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:40:04.995] <TB1> INFO: Expecting 2560 events.
[17:40:06.058] <TB1> INFO: 2560 events read in total (347ms).
[17:40:06.061] <TB1> INFO: Test took 1478ms.
[17:40:06.064] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:40:06.476] <TB1> INFO: Expecting 2560 events.
[17:40:07.545] <TB1> INFO: 2560 events read in total (347ms).
[17:40:07.549] <TB1> INFO: Test took 1485ms.
[17:40:07.552] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:40:07.962] <TB1> INFO: Expecting 2560 events.
[17:40:09.027] <TB1> INFO: 2560 events read in total (348ms).
[17:40:09.035] <TB1> INFO: Test took 1483ms.
[17:40:09.037] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:40:09.444] <TB1> INFO: Expecting 2560 events.
[17:40:10.509] <TB1> INFO: 2560 events read in total (348ms).
[17:40:10.512] <TB1> INFO: Test took 1475ms.
[17:40:10.514] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:40:10.926] <TB1> INFO: Expecting 2560 events.
[17:40:11.990] <TB1> INFO: 2560 events read in total (347ms).
[17:40:11.991] <TB1> INFO: Test took 1477ms.
[17:40:11.994] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:40:12.407] <TB1> INFO: Expecting 2560 events.
[17:40:13.471] <TB1> INFO: 2560 events read in total (347ms).
[17:40:13.472] <TB1> INFO: Test took 1478ms.
[17:40:13.475] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:40:13.888] <TB1> INFO: Expecting 2560 events.
[17:40:14.956] <TB1> INFO: 2560 events read in total (347ms).
[17:40:14.957] <TB1> INFO: Test took 1482ms.
[17:40:14.960] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:40:15.373] <TB1> INFO: Expecting 2560 events.
[17:40:16.442] <TB1> INFO: 2560 events read in total (350ms).
[17:40:16.444] <TB1> INFO: Test took 1484ms.
[17:40:16.447] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:40:16.860] <TB1> INFO: Expecting 2560 events.
[17:40:17.926] <TB1> INFO: 2560 events read in total (349ms).
[17:40:17.929] <TB1> INFO: Test took 1483ms.
[17:40:17.931] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:40:18.343] <TB1> INFO: Expecting 2560 events.
[17:40:19.409] <TB1> INFO: 2560 events read in total (347ms).
[17:40:19.410] <TB1> INFO: Test took 1479ms.
[17:40:19.412] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:40:19.826] <TB1> INFO: Expecting 2560 events.
[17:40:20.892] <TB1> INFO: 2560 events read in total (348ms).
[17:40:20.895] <TB1> INFO: Test took 1483ms.
[17:40:20.898] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:40:21.309] <TB1> INFO: Expecting 2560 events.
[17:40:22.373] <TB1> INFO: 2560 events read in total (347ms).
[17:40:22.379] <TB1> INFO: Test took 1481ms.
[17:40:23.035] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 593 seconds
[17:40:23.038] <TB1> INFO: PH scale (per ROC): 74 72 73 78 71 76 77 70 70 81 70 79 74 78 69 74
[17:40:23.039] <TB1> INFO: PH offset (per ROC): 186 173 184 171 171 179 170 171 179 179 174 165 186 179 175 176
[17:40:23.231] <TB1> INFO: ######################################################################
[17:40:23.236] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[17:40:23.236] <TB1> INFO: ######################################################################
[17:40:23.248] <TB1> INFO: scanning low vcal = 10
[17:40:23.573] <TB1> INFO: Expecting 41600 events.
[17:40:27.206] <TB1> INFO: 41600 events read in total (2913ms).
[17:40:27.207] <TB1> INFO: Test took 3947ms.
[17:40:27.209] <TB1> INFO: scanning low vcal = 20
[17:40:27.623] <TB1> INFO: Expecting 41600 events.
[17:40:31.250] <TB1> INFO: 41600 events read in total (2910ms).
[17:40:31.251] <TB1> INFO: Test took 4042ms.
[17:40:31.254] <TB1> INFO: scanning low vcal = 30
[17:40:31.667] <TB1> INFO: Expecting 41600 events.
[17:40:35.318] <TB1> INFO: 41600 events read in total (2934ms).
[17:40:35.319] <TB1> INFO: Test took 4065ms.
[17:40:35.321] <TB1> INFO: scanning low vcal = 40
[17:40:35.729] <TB1> INFO: Expecting 41600 events.
[17:40:39.775] <TB1> INFO: 41600 events read in total (3329ms).
[17:40:39.776] <TB1> INFO: Test took 4455ms.
[17:40:39.782] <TB1> INFO: scanning low vcal = 50
[17:40:40.135] <TB1> INFO: Expecting 41600 events.
[17:40:44.185] <TB1> INFO: 41600 events read in total (3333ms).
[17:40:44.185] <TB1> INFO: Test took 4400ms.
[17:40:44.189] <TB1> INFO: scanning low vcal = 60
[17:40:44.540] <TB1> INFO: Expecting 41600 events.
[17:40:48.593] <TB1> INFO: 41600 events read in total (3335ms).
[17:40:48.596] <TB1> INFO: Test took 4407ms.
[17:40:48.601] <TB1> INFO: scanning low vcal = 70
[17:40:48.953] <TB1> INFO: Expecting 41600 events.
[17:40:53.057] <TB1> INFO: 41600 events read in total (3385ms).
[17:40:53.060] <TB1> INFO: Test took 4459ms.
[17:40:53.065] <TB1> INFO: scanning low vcal = 80
[17:40:53.408] <TB1> INFO: Expecting 41600 events.
[17:40:57.464] <TB1> INFO: 41600 events read in total (3338ms).
[17:40:57.468] <TB1> INFO: Test took 4399ms.
[17:40:57.475] <TB1> INFO: scanning low vcal = 90
[17:40:57.816] <TB1> INFO: Expecting 41600 events.
[17:41:01.985] <TB1> INFO: 41600 events read in total (3449ms).
[17:41:01.987] <TB1> INFO: Test took 4512ms.
[17:41:01.995] <TB1> INFO: scanning low vcal = 100
[17:41:02.337] <TB1> INFO: Expecting 41600 events.
[17:41:06.441] <TB1> INFO: 41600 events read in total (3387ms).
[17:41:06.446] <TB1> INFO: Test took 4450ms.
[17:41:06.452] <TB1> INFO: scanning low vcal = 110
[17:41:06.803] <TB1> INFO: Expecting 41600 events.
[17:41:10.877] <TB1> INFO: 41600 events read in total (3351ms).
[17:41:10.882] <TB1> INFO: Test took 4426ms.
[17:41:10.887] <TB1> INFO: scanning low vcal = 120
[17:41:11.229] <TB1> INFO: Expecting 41600 events.
[17:41:15.290] <TB1> INFO: 41600 events read in total (3338ms).
[17:41:15.300] <TB1> INFO: Test took 4413ms.
[17:41:15.304] <TB1> INFO: scanning low vcal = 130
[17:41:15.646] <TB1> INFO: Expecting 41600 events.
[17:41:19.704] <TB1> INFO: 41600 events read in total (3341ms).
[17:41:19.705] <TB1> INFO: Test took 4395ms.
[17:41:19.711] <TB1> INFO: scanning low vcal = 140
[17:41:20.060] <TB1> INFO: Expecting 41600 events.
[17:41:24.125] <TB1> INFO: 41600 events read in total (3346ms).
[17:41:24.129] <TB1> INFO: Test took 4416ms.
[17:41:24.137] <TB1> INFO: scanning low vcal = 150
[17:41:24.478] <TB1> INFO: Expecting 41600 events.
[17:41:28.598] <TB1> INFO: 41600 events read in total (3401ms).
[17:41:28.603] <TB1> INFO: Test took 4465ms.
[17:41:28.606] <TB1> INFO: scanning low vcal = 160
[17:41:28.937] <TB1> INFO: Expecting 41600 events.
[17:41:32.992] <TB1> INFO: 41600 events read in total (3334ms).
[17:41:32.998] <TB1> INFO: Test took 4390ms.
[17:41:33.011] <TB1> INFO: scanning low vcal = 170
[17:41:33.338] <TB1> INFO: Expecting 41600 events.
[17:41:37.404] <TB1> INFO: 41600 events read in total (3346ms).
[17:41:37.409] <TB1> INFO: Test took 4398ms.
[17:41:37.419] <TB1> INFO: scanning low vcal = 180
[17:41:37.757] <TB1> INFO: Expecting 41600 events.
[17:41:41.802] <TB1> INFO: 41600 events read in total (3328ms).
[17:41:41.806] <TB1> INFO: Test took 4387ms.
[17:41:41.812] <TB1> INFO: scanning low vcal = 190
[17:41:42.157] <TB1> INFO: Expecting 41600 events.
[17:41:46.232] <TB1> INFO: 41600 events read in total (3358ms).
[17:41:46.232] <TB1> INFO: Test took 4416ms.
[17:41:46.236] <TB1> INFO: scanning low vcal = 200
[17:41:46.594] <TB1> INFO: Expecting 41600 events.
[17:41:50.701] <TB1> INFO: 41600 events read in total (3389ms).
[17:41:50.703] <TB1> INFO: Test took 4467ms.
[17:41:50.708] <TB1> INFO: scanning low vcal = 210
[17:41:51.036] <TB1> INFO: Expecting 41600 events.
[17:41:55.107] <TB1> INFO: 41600 events read in total (3354ms).
[17:41:55.112] <TB1> INFO: Test took 4404ms.
[17:41:55.117] <TB1> INFO: scanning low vcal = 220
[17:41:55.459] <TB1> INFO: Expecting 41600 events.
[17:41:59.532] <TB1> INFO: 41600 events read in total (3351ms).
[17:41:59.533] <TB1> INFO: Test took 4414ms.
[17:41:59.540] <TB1> INFO: scanning low vcal = 230
[17:41:59.882] <TB1> INFO: Expecting 41600 events.
[17:42:03.933] <TB1> INFO: 41600 events read in total (3332ms).
[17:42:03.935] <TB1> INFO: Test took 4394ms.
[17:42:03.941] <TB1> INFO: scanning low vcal = 240
[17:42:04.285] <TB1> INFO: Expecting 41600 events.
[17:42:08.337] <TB1> INFO: 41600 events read in total (3332ms).
[17:42:08.339] <TB1> INFO: Test took 4398ms.
[17:42:08.342] <TB1> INFO: scanning low vcal = 250
[17:42:08.688] <TB1> INFO: Expecting 41600 events.
[17:42:12.735] <TB1> INFO: 41600 events read in total (3326ms).
[17:42:12.739] <TB1> INFO: Test took 4396ms.
[17:42:12.745] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[17:42:13.096] <TB1> INFO: Expecting 41600 events.
[17:42:17.149] <TB1> INFO: 41600 events read in total (3336ms).
[17:42:17.150] <TB1> INFO: Test took 4404ms.
[17:42:17.153] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[17:42:17.502] <TB1> INFO: Expecting 41600 events.
[17:42:21.555] <TB1> INFO: 41600 events read in total (3334ms).
[17:42:21.556] <TB1> INFO: Test took 4403ms.
[17:42:21.560] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[17:42:21.910] <TB1> INFO: Expecting 41600 events.
[17:42:25.977] <TB1> INFO: 41600 events read in total (3350ms).
[17:42:25.979] <TB1> INFO: Test took 4418ms.
[17:42:25.983] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[17:42:26.331] <TB1> INFO: Expecting 41600 events.
[17:42:30.579] <TB1> INFO: 41600 events read in total (3529ms).
[17:42:30.583] <TB1> INFO: Test took 4593ms.
[17:42:30.587] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[17:42:30.908] <TB1> INFO: Expecting 41600 events.
[17:42:34.972] <TB1> INFO: 41600 events read in total (3346ms).
[17:42:34.977] <TB1> INFO: Test took 4390ms.
[17:42:35.514] <TB1> INFO: PixTestGainPedestal::measure() done
[17:43:08.876] <TB1> INFO: PixTestGainPedestal::fit() done
[17:43:08.882] <TB1> INFO: non-linearity mean: 0.958 0.954 0.960 0.956 0.958 0.961 0.967 0.956 0.959 0.967 0.962 0.960 0.957 0.965 0.955 0.964
[17:43:08.882] <TB1> INFO: non-linearity RMS: 0.007 0.007 0.007 0.007 0.006 0.006 0.005 0.006 0.006 0.006 0.006 0.006 0.006 0.005 0.007 0.005
[17:43:08.882] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[17:43:08.901] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[17:43:08.924] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[17:43:08.944] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[17:43:08.966] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[17:43:08.991] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[17:43:09.015] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[17:43:09.034] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[17:43:09.055] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[17:43:09.086] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[17:43:09.110] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[17:43:09.130] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[17:43:09.151] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[17:43:09.176] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[17:43:09.200] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[17:43:09.221] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[17:43:09.246] <TB1> INFO: PixTestGainPedestal::doTest() done, duration: 166 seconds
[17:43:09.254] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C15.dat
[17:43:09.296] <TB1> INFO: PixTestReadback::doTest() start.
[17:43:09.298] <TB1> INFO: PixTestReadback::RES sent once
[17:43:22.074] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C0.dat
[17:43:22.076] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C1.dat
[17:43:22.076] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C2.dat
[17:43:22.076] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C3.dat
[17:43:22.076] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C4.dat
[17:43:22.077] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C5.dat
[17:43:22.077] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C6.dat
[17:43:22.078] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C7.dat
[17:43:22.079] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C8.dat
[17:43:22.079] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C9.dat
[17:43:22.079] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C10.dat
[17:43:22.079] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C11.dat
[17:43:22.079] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C12.dat
[17:43:22.079] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C13.dat
[17:43:22.079] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C14.dat
[17:43:22.079] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C15.dat
[17:43:22.126] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[17:43:22.130] <TB1> INFO: PixTestReadback::RES sent once
[17:43:33.410] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C0.dat
[17:43:33.417] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C1.dat
[17:43:33.417] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C2.dat
[17:43:33.417] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C3.dat
[17:43:33.418] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C4.dat
[17:43:33.421] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C5.dat
[17:43:33.421] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C6.dat
[17:43:33.421] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C7.dat
[17:43:33.428] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C8.dat
[17:43:33.428] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C9.dat
[17:43:33.428] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C10.dat
[17:43:33.428] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C11.dat
[17:43:33.431] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C12.dat
[17:43:33.431] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C13.dat
[17:43:33.431] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C14.dat
[17:43:33.431] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C15.dat
[17:43:33.482] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[17:43:33.483] <TB1> INFO: PixTestReadback::RES sent once
[17:43:42.153] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[17:43:42.154] <TB1> INFO: Vbg will be calibrated using Vd calibration
[17:43:42.154] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 155.8calibrated Vbg = 1.19672 :::*/*/*/*/
[17:43:42.154] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 154calibrated Vbg = 1.19048 :::*/*/*/*/
[17:43:42.154] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 154.5calibrated Vbg = 1.19096 :::*/*/*/*/
[17:43:42.154] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 159calibrated Vbg = 1.20018 :::*/*/*/*/
[17:43:42.154] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 158.9calibrated Vbg = 1.20772 :::*/*/*/*/
[17:43:42.154] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 152.8calibrated Vbg = 1.21026 :::*/*/*/*/
[17:43:42.154] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 156.6calibrated Vbg = 1.21287 :::*/*/*/*/
[17:43:42.154] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 150.5calibrated Vbg = 1.20883 :::*/*/*/*/
[17:43:42.154] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 155.4calibrated Vbg = 1.21103 :::*/*/*/*/
[17:43:42.154] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 153.2calibrated Vbg = 1.21114 :::*/*/*/*/
[17:43:42.154] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 144calibrated Vbg = 1.20184 :::*/*/*/*/
[17:43:42.154] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 147.8calibrated Vbg = 1.19322 :::*/*/*/*/
[17:43:42.154] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 151.7calibrated Vbg = 1.19162 :::*/*/*/*/
[17:43:42.154] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 155calibrated Vbg = 1.19318 :::*/*/*/*/
[17:43:42.154] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 156.4calibrated Vbg = 1.19828 :::*/*/*/*/
[17:43:42.154] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 152.7calibrated Vbg = 1.19462 :::*/*/*/*/
[17:43:42.157] <TB1> INFO: PixTestReadback::RES sent once
[17:46:37.572] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C0.dat
[17:46:37.572] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C1.dat
[17:46:37.572] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C2.dat
[17:46:37.572] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C3.dat
[17:46:37.572] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C4.dat
[17:46:37.572] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C5.dat
[17:46:37.572] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C6.dat
[17:46:37.572] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C7.dat
[17:46:37.572] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C8.dat
[17:46:37.573] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C9.dat
[17:46:37.573] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C10.dat
[17:46:37.573] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C11.dat
[17:46:37.573] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C12.dat
[17:46:37.573] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C13.dat
[17:46:37.573] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C14.dat
[17:46:37.573] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2088_FullQualification_2015-09-01_10h30m_1441096224//004_FulltestPxar_p17//readbackCal_C15.dat
[17:46:37.618] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[17:46:37.619] <TB1> INFO: PixTestReadback::doTest() done
[17:46:37.667] <TB1> INFO: enter test to run
[17:46:37.667] <TB1> INFO: test: exit no parameter change
[17:46:38.240] <TB1> QUIET: Connection to board 153 closed.
[17:46:38.319] <TB1> INFO: pXar: this is the end, my friend