[09:03:53.660] <TB3> INFO: *** Welcome to pxar ***
[09:03:53.660] <TB3> INFO: *** Today: 2016/09/06
[09:03:53.767] <TB3> INFO: *** Version: fe94
[09:03:53.767] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters_C15.dat
[09:03:53.768] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//tbmParameters_C0b.dat
[09:03:53.769] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//defaultMaskFile.dat
[09:03:53.769] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//trimParameters_C15.dat
[09:03:53.851] <TB3> INFO: clk: 4
[09:03:53.851] <TB3> INFO: ctr: 4
[09:03:53.851] <TB3> INFO: sda: 19
[09:03:53.851] <TB3> INFO: tin: 9
[09:03:53.851] <TB3> INFO: level: 15
[09:03:53.852] <TB3> INFO: triggerdelay: 0
[09:03:53.852] <TB3> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[09:03:53.852] <TB3> INFO: Log level: INFO
[09:03:53.858] <TB3> INFO: Found DTB DTB_WZ4I6J
[09:03:53.867] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[09:03:53.869] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[09:03:53.870] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[09:03:55.415] <TB3> INFO: DUT info:
[09:03:55.415] <TB3> INFO: The DUT currently contains the following objects:
[09:03:55.415] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:03:55.415] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:03:55.415] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:03:55.415] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:03:55.415] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:55.415] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:55.415] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:55.415] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:55.415] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:55.415] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:55.415] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:55.415] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:55.415] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:55.415] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:55.415] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:55.415] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:55.415] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:55.415] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:55.416] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:55.416] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:55.816] <TB3> INFO: enter 'restricted' command line mode
[09:03:55.816] <TB3> INFO: enter test to run
[09:03:55.817] <TB3> INFO: test: pretest no parameter change
[09:03:55.817] <TB3> INFO: running: pretest
[09:03:55.823] <TB3> INFO: ----------------------------------------------------------------------
[09:03:55.823] <TB3> INFO: PixTestPretest::programROC()
[09:03:55.823] <TB3> INFO: ----------------------------------------------------------------------
[09:04:13.836] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[09:04:13.836] <TB3> INFO: IA differences per ROC: 20.1 19.3 19.3 19.3 20.9 18.5 19.3 19.3 16.1 20.1 19.3 19.3 19.3 20.1 19.3 17.7
[09:04:13.940] <TB3> INFO: enter test to run
[09:04:13.940] <TB3> INFO: test: pretest no parameter change
[09:04:13.940] <TB3> INFO: running: pretest
[09:04:13.941] <TB3> INFO: ----------------------------------------------------------------------
[09:04:13.941] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[09:04:13.941] <TB3> INFO: ----------------------------------------------------------------------
[09:04:35.207] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 376.2 mA = 23.5125 mA/ROC
[09:04:35.207] <TB3> INFO: i(loss) [mA/ROC]: 19.3 18.5 18.5 18.5 18.5 18.5 17.7 18.5 19.3 18.5 18.5 17.7 17.7 18.5 17.7 18.5
[09:04:35.242] <TB3> INFO: enter test to run
[09:04:35.242] <TB3> INFO: test: pretest no parameter change
[09:04:35.242] <TB3> INFO: running: pretest
[09:04:35.242] <TB3> INFO: ----------------------------------------------------------------------
[09:04:35.242] <TB3> INFO: PixTestPretest::findTiming()
[09:04:35.242] <TB3> INFO: ----------------------------------------------------------------------
[09:04:35.242] <TB3> INFO: PixTestCmd::init()
[09:04:36.186] <TB3> WARNING: Not unmasking DUT, not setting Calibrate bits!
[09:06:04.231] <TB3> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[09:06:04.231] <TB3> INFO: (success/tries = 100/100), width = 4
[09:06:04.232] <TB3> INFO: enter test to run
[09:06:04.232] <TB3> INFO: test: pretest no parameter change
[09:06:04.232] <TB3> INFO: running: pretest
[09:06:04.233] <TB3> INFO: ----------------------------------------------------------------------
[09:06:04.233] <TB3> INFO: PixTestPretest::findWorkingPixel()
[09:06:04.233] <TB3> INFO: ----------------------------------------------------------------------
[09:06:04.323] <TB3> INFO: Expecting 231680 events.
[09:06:10.102] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L497> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[09:06:10.183] <TB3> ERROR: <datapipe.cc/CheckEventID:L459> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[09:06:14.428] <TB3> INFO: 231680 events read in total (9548ms).
[09:06:14.432] <TB3> INFO: Test took 10196ms.
[09:06:14.661] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[09:06:14.719] <TB3> INFO: enter test to run
[09:06:14.719] <TB3> INFO: test: pretest no parameter change
[09:06:14.719] <TB3> INFO: running: pretest
[09:06:14.720] <TB3> INFO: ----------------------------------------------------------------------
[09:06:14.720] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[09:06:14.720] <TB3> INFO: ----------------------------------------------------------------------
[09:06:14.811] <TB3> INFO: Expecting 231680 events.
[09:06:24.985] <TB3> INFO: 231680 events read in total (9617ms).
[09:06:24.990] <TB3> INFO: Test took 10266ms.
[09:06:25.215] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[09:06:25.215] <TB3> INFO: CalDel: 143 157 153 143 156 151 161 143 123 126 171 142 146 138 145 144
[09:06:25.215] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[09:06:25.248] <TB3> INFO: enter test to run
[09:06:25.248] <TB3> INFO: test: pretest no parameter change
[09:06:25.248] <TB3> INFO: running: pretest
[09:06:25.248] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters_C0.dat
[09:06:25.248] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters_C1.dat
[09:06:25.248] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters_C2.dat
[09:06:25.249] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters_C3.dat
[09:06:25.249] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters_C4.dat
[09:06:25.249] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters_C5.dat
[09:06:25.249] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters_C6.dat
[09:06:25.249] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters_C7.dat
[09:06:25.249] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters_C8.dat
[09:06:25.249] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters_C9.dat
[09:06:25.249] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters_C10.dat
[09:06:25.249] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters_C11.dat
[09:06:25.250] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters_C12.dat
[09:06:25.250] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters_C13.dat
[09:06:25.250] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters_C14.dat
[09:06:25.250] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters_C15.dat
[09:06:25.250] <TB3> INFO: enter test to run
[09:06:25.250] <TB3> INFO: test: fulltest no parameter change
[09:06:25.250] <TB3> INFO: running: fulltest
[09:06:25.250] <TB3> INFO: ######################################################################
[09:06:25.250] <TB3> INFO: PixTestFullTest::doTest()
[09:06:25.250] <TB3> INFO: ######################################################################
[09:06:25.251] <TB3> INFO: ######################################################################
[09:06:25.251] <TB3> INFO: PixTestAlive::doTest()
[09:06:25.251] <TB3> INFO: ######################################################################
[09:06:25.253] <TB3> INFO: ----------------------------------------------------------------------
[09:06:25.253] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:06:25.253] <TB3> INFO: ----------------------------------------------------------------------
[09:06:25.517] <TB3> INFO: Expecting 41600 events.
[09:06:29.283] <TB3> INFO: 41600 events read in total (3209ms).
[09:06:29.283] <TB3> INFO: Test took 4029ms.
[09:06:29.513] <TB3> INFO: PixTestAlive::aliveTest() done
[09:06:29.513] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:06:29.515] <TB3> INFO: ----------------------------------------------------------------------
[09:06:29.515] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:06:29.515] <TB3> INFO: ----------------------------------------------------------------------
[09:06:29.776] <TB3> INFO: Expecting 41600 events.
[09:06:32.750] <TB3> INFO: 41600 events read in total (2418ms).
[09:06:32.750] <TB3> INFO: Test took 3234ms.
[09:06:32.751] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:06:32.994] <TB3> INFO: PixTestAlive::maskTest() done
[09:06:32.994] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:06:32.995] <TB3> INFO: ----------------------------------------------------------------------
[09:06:32.995] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:06:32.995] <TB3> INFO: ----------------------------------------------------------------------
[09:06:33.270] <TB3> INFO: Expecting 41600 events.
[09:06:37.115] <TB3> INFO: 41600 events read in total (3289ms).
[09:06:37.115] <TB3> INFO: Test took 4118ms.
[09:06:37.342] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[09:06:37.342] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:06:37.342] <TB3> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[09:06:37.342] <TB3> INFO: Decoding statistics:
[09:06:37.342] <TB3> INFO: General information:
[09:06:37.342] <TB3> INFO: 16bit words read: 0
[09:06:37.342] <TB3> INFO: valid events total: 0
[09:06:37.342] <TB3> INFO: empty events: 0
[09:06:37.342] <TB3> INFO: valid events with pixels: 0
[09:06:37.342] <TB3> INFO: valid pixel hits: 0
[09:06:37.342] <TB3> INFO: Event errors: 0
[09:06:37.342] <TB3> INFO: start marker: 0
[09:06:37.342] <TB3> INFO: stop marker: 0
[09:06:37.342] <TB3> INFO: overflow: 0
[09:06:37.342] <TB3> INFO: invalid 5bit words: 0
[09:06:37.342] <TB3> INFO: invalid XOR eye diagram: 0
[09:06:37.342] <TB3> INFO: frame (failed synchr.): 0
[09:06:37.342] <TB3> INFO: idle data (no TBM trl): 0
[09:06:37.342] <TB3> INFO: no data (only TBM hdr): 0
[09:06:37.342] <TB3> INFO: TBM errors: 0
[09:06:37.342] <TB3> INFO: flawed TBM headers: 0
[09:06:37.342] <TB3> INFO: flawed TBM trailers: 0
[09:06:37.342] <TB3> INFO: event ID mismatches: 0
[09:06:37.342] <TB3> INFO: ROC errors: 0
[09:06:37.342] <TB3> INFO: missing ROC header(s): 0
[09:06:37.342] <TB3> INFO: misplaced readback start: 0
[09:06:37.342] <TB3> INFO: Pixel decoding errors: 0
[09:06:37.342] <TB3> INFO: pixel data incomplete: 0
[09:06:37.342] <TB3> INFO: pixel address: 0
[09:06:37.343] <TB3> INFO: pulse height fill bit: 0
[09:06:37.343] <TB3> INFO: buffer corruption: 0
[09:06:37.352] <TB3> INFO: ######################################################################
[09:06:37.352] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[09:06:37.352] <TB3> INFO: ######################################################################
[09:06:37.355] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[09:06:37.367] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[09:06:37.367] <TB3> INFO: run 1 of 1
[09:06:37.650] <TB3> INFO: Expecting 3120000 events.
[09:07:18.839] <TB3> INFO: 870210 events read in total (40632ms).
[09:07:59.388] <TB3> INFO: 1725315 events read in total (81181ms).
[09:08:39.895] <TB3> INFO: 2593995 events read in total (121688ms).
[09:09:04.675] <TB3> INFO: 3120000 events read in total (146468ms).
[09:09:04.716] <TB3> INFO: Test took 147349ms.
[09:09:28.706] <TB3> INFO: PixTestBBMap::doTest() done, duration: 171 seconds
[09:09:28.706] <TB3> INFO: number of dead bumps (per ROC): 2 1 0 2 0 2 3 4 1 2 1 1 2 1 0 6
[09:09:28.706] <TB3> INFO: separation cut (per ROC): 89 79 82 71 90 83 79 76 75 68 78 79 80 91 68 70
[09:09:28.706] <TB3> INFO: Decoding statistics:
[09:09:28.706] <TB3> INFO: General information:
[09:09:28.706] <TB3> INFO: 16bit words read: 0
[09:09:28.706] <TB3> INFO: valid events total: 0
[09:09:28.706] <TB3> INFO: empty events: 0
[09:09:28.706] <TB3> INFO: valid events with pixels: 0
[09:09:28.706] <TB3> INFO: valid pixel hits: 0
[09:09:28.706] <TB3> INFO: Event errors: 0
[09:09:28.706] <TB3> INFO: start marker: 0
[09:09:28.706] <TB3> INFO: stop marker: 0
[09:09:28.706] <TB3> INFO: overflow: 0
[09:09:28.706] <TB3> INFO: invalid 5bit words: 0
[09:09:28.706] <TB3> INFO: invalid XOR eye diagram: 0
[09:09:28.706] <TB3> INFO: frame (failed synchr.): 0
[09:09:28.706] <TB3> INFO: idle data (no TBM trl): 0
[09:09:28.706] <TB3> INFO: no data (only TBM hdr): 0
[09:09:28.706] <TB3> INFO: TBM errors: 0
[09:09:28.706] <TB3> INFO: flawed TBM headers: 0
[09:09:28.706] <TB3> INFO: flawed TBM trailers: 0
[09:09:28.706] <TB3> INFO: event ID mismatches: 0
[09:09:28.706] <TB3> INFO: ROC errors: 0
[09:09:28.706] <TB3> INFO: missing ROC header(s): 0
[09:09:28.706] <TB3> INFO: misplaced readback start: 0
[09:09:28.706] <TB3> INFO: Pixel decoding errors: 0
[09:09:28.706] <TB3> INFO: pixel data incomplete: 0
[09:09:28.706] <TB3> INFO: pixel address: 0
[09:09:28.706] <TB3> INFO: pulse height fill bit: 0
[09:09:28.706] <TB3> INFO: buffer corruption: 0
[09:09:28.783] <TB3> INFO: ######################################################################
[09:09:28.783] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:09:28.783] <TB3> INFO: ######################################################################
[09:09:28.783] <TB3> INFO: ----------------------------------------------------------------------
[09:09:28.783] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:09:28.783] <TB3> INFO: ----------------------------------------------------------------------
[09:09:28.783] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:09:28.792] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:09:28.792] <TB3> INFO: run 1 of 1
[09:09:29.056] <TB3> INFO: Expecting 26208000 events.
[09:10:02.168] <TB3> INFO: 850000 events read in total (32556ms).
[09:10:34.187] <TB3> INFO: 1688600 events read in total (64575ms).
[09:11:06.409] <TB3> INFO: 2525600 events read in total (96797ms).
[09:11:38.747] <TB3> INFO: 3363650 events read in total (129135ms).
[09:12:11.138] <TB3> INFO: 4199950 events read in total (161526ms).
[09:12:43.859] <TB3> INFO: 5033550 events read in total (194247ms).
[09:13:17.654] <TB3> INFO: 5870650 events read in total (228042ms).
[09:13:50.368] <TB3> INFO: 6707100 events read in total (260756ms).
[09:14:22.630] <TB3> INFO: 7539750 events read in total (293018ms).
[09:14:55.063] <TB3> INFO: 8375900 events read in total (325451ms).
[09:15:27.425] <TB3> INFO: 9211150 events read in total (357813ms).
[09:15:59.464] <TB3> INFO: 10043850 events read in total (389852ms).
[09:16:31.744] <TB3> INFO: 10877550 events read in total (422132ms).
[09:17:03.871] <TB3> INFO: 11711350 events read in total (454259ms).
[09:17:36.202] <TB3> INFO: 12543500 events read in total (486590ms).
[09:18:08.287] <TB3> INFO: 13371750 events read in total (518675ms).
[09:18:40.553] <TB3> INFO: 14196800 events read in total (550941ms).
[09:19:12.725] <TB3> INFO: 15018050 events read in total (583113ms).
[09:19:44.770] <TB3> INFO: 15841850 events read in total (615158ms).
[09:20:16.975] <TB3> INFO: 16661900 events read in total (647363ms).
[09:20:49.312] <TB3> INFO: 17483200 events read in total (679700ms).
[09:21:21.569] <TB3> INFO: 18304400 events read in total (711957ms).
[09:21:53.945] <TB3> INFO: 19123500 events read in total (744333ms).
[09:22:26.351] <TB3> INFO: 19941950 events read in total (776739ms).
[09:22:58.903] <TB3> INFO: 20761600 events read in total (809291ms).
[09:23:31.154] <TB3> INFO: 21577150 events read in total (841542ms).
[09:24:03.772] <TB3> INFO: 22393750 events read in total (874160ms).
[09:24:36.338] <TB3> INFO: 23212450 events read in total (906726ms).
[09:25:08.548] <TB3> INFO: 24029000 events read in total (938936ms).
[09:25:40.633] <TB3> INFO: 24845550 events read in total (971021ms).
[09:26:13.449] <TB3> INFO: 25661050 events read in total (1003837ms).
[09:26:34.837] <TB3> INFO: 26208000 events read in total (1025225ms).
[09:26:34.874] <TB3> INFO: Test took 1026082ms.
[09:26:35.163] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:26:36.610] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:26:38.099] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:26:39.571] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:26:41.073] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:26:42.515] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:26:43.979] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:26:45.448] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:26:46.853] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:26:48.242] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:26:49.640] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:26:51.052] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:26:52.452] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:26:53.833] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:26:55.227] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:26:56.881] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[09:26:58.464] <TB3> INFO: PixTestScurves::scurves() done
[09:26:58.464] <TB3> INFO: Vcal mean: 87.54 77.02 79.46 73.08 87.76 81.28 77.65 78.44 80.81 68.95 71.08 77.96 83.67 82.67 75.31 70.19
[09:26:58.464] <TB3> INFO: Vcal RMS: 4.98 4.56 3.85 4.70 5.28 4.65 4.40 4.25 4.05 4.03 4.94 5.31 4.71 4.29 4.50 4.33
[09:26:58.464] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1049 seconds
[09:26:58.464] <TB3> INFO: Decoding statistics:
[09:26:58.464] <TB3> INFO: General information:
[09:26:58.464] <TB3> INFO: 16bit words read: 0
[09:26:58.464] <TB3> INFO: valid events total: 0
[09:26:58.464] <TB3> INFO: empty events: 0
[09:26:58.464] <TB3> INFO: valid events with pixels: 0
[09:26:58.464] <TB3> INFO: valid pixel hits: 0
[09:26:58.464] <TB3> INFO: Event errors: 0
[09:26:58.464] <TB3> INFO: start marker: 0
[09:26:58.464] <TB3> INFO: stop marker: 0
[09:26:58.464] <TB3> INFO: overflow: 0
[09:26:58.464] <TB3> INFO: invalid 5bit words: 0
[09:26:58.464] <TB3> INFO: invalid XOR eye diagram: 0
[09:26:58.464] <TB3> INFO: frame (failed synchr.): 0
[09:26:58.464] <TB3> INFO: idle data (no TBM trl): 0
[09:26:58.464] <TB3> INFO: no data (only TBM hdr): 0
[09:26:58.464] <TB3> INFO: TBM errors: 0
[09:26:58.464] <TB3> INFO: flawed TBM headers: 0
[09:26:58.464] <TB3> INFO: flawed TBM trailers: 0
[09:26:58.464] <TB3> INFO: event ID mismatches: 0
[09:26:58.464] <TB3> INFO: ROC errors: 0
[09:26:58.464] <TB3> INFO: missing ROC header(s): 0
[09:26:58.464] <TB3> INFO: misplaced readback start: 0
[09:26:58.464] <TB3> INFO: Pixel decoding errors: 0
[09:26:58.464] <TB3> INFO: pixel data incomplete: 0
[09:26:58.464] <TB3> INFO: pixel address: 0
[09:26:58.464] <TB3> INFO: pulse height fill bit: 0
[09:26:58.464] <TB3> INFO: buffer corruption: 0
[09:26:58.544] <TB3> INFO: ######################################################################
[09:26:58.544] <TB3> INFO: PixTestTrim::doTest()
[09:26:58.544] <TB3> INFO: ######################################################################
[09:26:58.545] <TB3> INFO: ----------------------------------------------------------------------
[09:26:58.545] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[09:26:58.545] <TB3> INFO: ----------------------------------------------------------------------
[09:26:58.650] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[09:26:58.650] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:26:58.659] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:26:58.659] <TB3> INFO: run 1 of 1
[09:26:58.927] <TB3> INFO: Expecting 6281600 events.
[09:27:38.851] <TB3> INFO: 1074120 events read in total (39367ms).
[09:28:17.680] <TB3> INFO: 2140360 events read in total (78196ms).
[09:28:56.524] <TB3> INFO: 3198860 events read in total (117040ms).
[09:29:35.135] <TB3> INFO: 4265390 events read in total (155651ms).
[09:30:14.282] <TB3> INFO: 5335350 events read in total (194798ms).
[09:30:49.757] <TB3> INFO: 6281600 events read in total (230273ms).
[09:30:49.792] <TB3> INFO: Test took 231132ms.
[09:31:08.217] <TB3> INFO: ROC 0 VthrComp = 92
[09:31:08.217] <TB3> INFO: ROC 1 VthrComp = 79
[09:31:08.218] <TB3> INFO: ROC 2 VthrComp = 85
[09:31:08.218] <TB3> INFO: ROC 3 VthrComp = 78
[09:31:08.218] <TB3> INFO: ROC 4 VthrComp = 91
[09:31:08.218] <TB3> INFO: ROC 5 VthrComp = 81
[09:31:08.218] <TB3> INFO: ROC 6 VthrComp = 84
[09:31:08.218] <TB3> INFO: ROC 7 VthrComp = 81
[09:31:08.218] <TB3> INFO: ROC 8 VthrComp = 87
[09:31:08.218] <TB3> INFO: ROC 9 VthrComp = 78
[09:31:08.218] <TB3> INFO: ROC 10 VthrComp = 76
[09:31:08.218] <TB3> INFO: ROC 11 VthrComp = 83
[09:31:08.219] <TB3> INFO: ROC 12 VthrComp = 88
[09:31:08.219] <TB3> INFO: ROC 13 VthrComp = 91
[09:31:08.219] <TB3> INFO: ROC 14 VthrComp = 78
[09:31:08.219] <TB3> INFO: ROC 15 VthrComp = 77
[09:31:08.219] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[09:31:08.219] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:31:08.231] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:31:08.231] <TB3> INFO: run 1 of 1
[09:31:08.500] <TB3> INFO: Expecting 6281600 events.
[09:31:43.813] <TB3> INFO: 757790 events read in total (34757ms).
[09:32:18.380] <TB3> INFO: 1513620 events read in total (69324ms).
[09:32:53.442] <TB3> INFO: 2269500 events read in total (104386ms).
[09:33:27.807] <TB3> INFO: 3025620 events read in total (138751ms).
[09:34:02.610] <TB3> INFO: 3775980 events read in total (173554ms).
[09:34:37.099] <TB3> INFO: 4522390 events read in total (208043ms).
[09:35:11.647] <TB3> INFO: 5266340 events read in total (242591ms).
[09:35:46.300] <TB3> INFO: 6009670 events read in total (277244ms).
[09:35:59.143] <TB3> INFO: 6281600 events read in total (290087ms).
[09:35:59.185] <TB3> INFO: Test took 290954ms.
[09:36:22.969] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 58.6496 for pixel 16/36 mean/min/max = 45.7228/32.6932/58.7523
[09:36:22.969] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 61.0066 for pixel 2/43 mean/min/max = 47.3786/33.7075/61.0497
[09:36:22.969] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 56.2525 for pixel 22/3 mean/min/max = 44.3225/32.3037/56.3413
[09:36:22.969] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 59.0624 for pixel 0/55 mean/min/max = 46.7246/34.3499/59.0992
[09:36:22.970] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 59.1699 for pixel 20/71 mean/min/max = 45.7911/32.2423/59.34
[09:36:22.970] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 60.9734 for pixel 0/1 mean/min/max = 46.9214/32.6849/61.1578
[09:36:22.970] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 56.8212 for pixel 23/73 mean/min/max = 44.3381/31.4868/57.1894
[09:36:22.970] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 57.2384 for pixel 5/79 mean/min/max = 44.9579/32.5489/57.367
[09:36:22.971] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 56.3196 for pixel 0/5 mean/min/max = 44.6073/32.4696/56.7449
[09:36:22.971] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 54.8643 for pixel 51/38 mean/min/max = 45.3707/35.6787/55.0627
[09:36:22.971] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 59.4628 for pixel 2/8 mean/min/max = 47.1372/34.4078/59.8666
[09:36:22.971] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 59.9668 for pixel 6/17 mean/min/max = 45.4588/30.95/59.9675
[09:36:22.972] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 57.9631 for pixel 18/15 mean/min/max = 45.2948/32.5054/58.0842
[09:36:22.972] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 56.7509 for pixel 0/15 mean/min/max = 44.9101/32.8249/56.9952
[09:36:22.972] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 59.7323 for pixel 0/19 mean/min/max = 47.208/34.6622/59.7539
[09:36:22.972] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 55.9841 for pixel 0/44 mean/min/max = 45.4722/34.5325/56.4119
[09:36:22.973] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:36:23.061] <TB3> INFO: Expecting 514560 events.
[09:36:36.157] <TB3> INFO: 514560 events read in total (12540ms).
[09:36:36.163] <TB3> INFO: Expecting 514560 events.
[09:36:49.013] <TB3> INFO: 514560 events read in total (12442ms).
[09:36:49.021] <TB3> INFO: Expecting 514560 events.
[09:37:02.004] <TB3> INFO: 514560 events read in total (12565ms).
[09:37:02.013] <TB3> INFO: Expecting 514560 events.
[09:37:14.854] <TB3> INFO: 514560 events read in total (12426ms).
[09:37:14.867] <TB3> INFO: Expecting 514560 events.
[09:37:27.688] <TB3> INFO: 514560 events read in total (12403ms).
[09:37:27.700] <TB3> INFO: Expecting 514560 events.
[09:37:40.493] <TB3> INFO: 514560 events read in total (12377ms).
[09:37:40.506] <TB3> INFO: Expecting 514560 events.
[09:37:53.510] <TB3> INFO: 514560 events read in total (12587ms).
[09:37:53.529] <TB3> INFO: Expecting 514560 events.
[09:38:06.296] <TB3> INFO: 514560 events read in total (12365ms).
[09:38:06.314] <TB3> INFO: Expecting 514560 events.
[09:38:19.264] <TB3> INFO: 514560 events read in total (12545ms).
[09:38:19.283] <TB3> INFO: Expecting 514560 events.
[09:38:32.145] <TB3> INFO: 514560 events read in total (12450ms).
[09:38:32.167] <TB3> INFO: Expecting 514560 events.
[09:38:45.153] <TB3> INFO: 514560 events read in total (12580ms).
[09:38:45.178] <TB3> INFO: Expecting 514560 events.
[09:38:58.145] <TB3> INFO: 514560 events read in total (12565ms).
[09:38:58.171] <TB3> INFO: Expecting 514560 events.
[09:39:11.077] <TB3> INFO: 514560 events read in total (12504ms).
[09:39:11.106] <TB3> INFO: Expecting 514560 events.
[09:39:23.851] <TB3> INFO: 514560 events read in total (12343ms).
[09:39:23.884] <TB3> INFO: Expecting 514560 events.
[09:39:36.690] <TB3> INFO: 514560 events read in total (12405ms).
[09:39:36.724] <TB3> INFO: Expecting 514560 events.
[09:39:49.526] <TB3> INFO: 514560 events read in total (12401ms).
[09:39:49.566] <TB3> INFO: Test took 206593ms.
[09:39:50.610] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:39:50.619] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:39:50.619] <TB3> INFO: run 1 of 1
[09:39:50.883] <TB3> INFO: Expecting 6281600 events.
[09:40:25.949] <TB3> INFO: 745920 events read in total (34510ms).
[09:41:00.420] <TB3> INFO: 1490020 events read in total (68981ms).
[09:41:34.585] <TB3> INFO: 2233210 events read in total (103146ms).
[09:42:08.787] <TB3> INFO: 2976090 events read in total (137348ms).
[09:42:43.945] <TB3> INFO: 3712800 events read in total (172506ms).
[09:43:18.542] <TB3> INFO: 4445040 events read in total (207103ms).
[09:43:53.032] <TB3> INFO: 5174630 events read in total (241593ms).
[09:44:28.057] <TB3> INFO: 5903410 events read in total (276618ms).
[09:44:46.742] <TB3> INFO: 6281600 events read in total (295303ms).
[09:44:46.786] <TB3> INFO: Test took 296167ms.
[09:45:10.632] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.099621 .. 255.000000
[09:45:10.710] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[09:45:10.719] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:45:10.719] <TB3> INFO: run 1 of 1
[09:45:10.981] <TB3> INFO: Expecting 10649600 events.
[09:45:45.262] <TB3> INFO: 712400 events read in total (33725ms).
[09:46:19.291] <TB3> INFO: 1424970 events read in total (67754ms).
[09:46:53.510] <TB3> INFO: 2137380 events read in total (101973ms).
[09:47:28.163] <TB3> INFO: 2850180 events read in total (136626ms).
[09:48:02.418] <TB3> INFO: 3562820 events read in total (170881ms).
[09:48:36.657] <TB3> INFO: 4275820 events read in total (205120ms).
[09:49:11.357] <TB3> INFO: 4988470 events read in total (239820ms).
[09:49:45.361] <TB3> INFO: 5701730 events read in total (273824ms).
[09:50:19.358] <TB3> INFO: 6414260 events read in total (307821ms).
[09:50:53.453] <TB3> INFO: 7126210 events read in total (341917ms).
[09:51:27.513] <TB3> INFO: 7837850 events read in total (375976ms).
[09:52:01.938] <TB3> INFO: 8548540 events read in total (410401ms).
[09:52:36.168] <TB3> INFO: 9259120 events read in total (444631ms).
[09:53:09.722] <TB3> INFO: 9969960 events read in total (478185ms).
[09:53:42.839] <TB3> INFO: 10649600 events read in total (511302ms).
[09:53:42.957] <TB3> INFO: Test took 512238ms.
[09:54:11.514] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 16.581440 .. 43.647626
[09:54:11.603] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 6 .. 53 (-1/-1) hits flags = 528 (plus default)
[09:54:11.612] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:54:11.612] <TB3> INFO: run 1 of 1
[09:54:11.878] <TB3> INFO: Expecting 1996800 events.
[09:54:50.468] <TB3> INFO: 949470 events read in total (38033ms).
[09:55:28.848] <TB3> INFO: 1896380 events read in total (76413ms).
[09:55:33.182] <TB3> INFO: 1996800 events read in total (80747ms).
[09:55:33.204] <TB3> INFO: Test took 81593ms.
[09:55:46.932] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 22.258588 .. 41.372105
[09:55:47.017] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 12 .. 51 (-1/-1) hits flags = 528 (plus default)
[09:55:47.026] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:55:47.026] <TB3> INFO: run 1 of 1
[09:55:47.296] <TB3> INFO: Expecting 1664000 events.
[09:56:25.548] <TB3> INFO: 932770 events read in total (37696ms).
[09:56:55.744] <TB3> INFO: 1664000 events read in total (67892ms).
[09:56:55.760] <TB3> INFO: Test took 68734ms.
[09:57:08.929] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 8.194863 .. 41.372105
[09:57:09.007] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 8 .. 51 (-1/-1) hits flags = 528 (plus default)
[09:57:09.016] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:57:09.016] <TB3> INFO: run 1 of 1
[09:57:09.282] <TB3> INFO: Expecting 1830400 events.
[09:57:47.746] <TB3> INFO: 958750 events read in total (37908ms).
[09:58:22.814] <TB3> INFO: 1830400 events read in total (72976ms).
[09:58:22.825] <TB3> INFO: Test took 73809ms.
[09:58:36.706] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[09:58:36.706] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[09:58:36.715] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:58:36.715] <TB3> INFO: run 1 of 1
[09:58:36.986] <TB3> INFO: Expecting 1705600 events.
[09:59:14.609] <TB3> INFO: 879770 events read in total (37067ms).
[09:59:49.871] <TB3> INFO: 1705600 events read in total (72329ms).
[09:59:49.890] <TB3> INFO: Test took 73176ms.
[10:00:04.012] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C0.dat
[10:00:04.012] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C1.dat
[10:00:04.012] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C2.dat
[10:00:04.012] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C3.dat
[10:00:04.013] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C4.dat
[10:00:04.013] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C5.dat
[10:00:04.013] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C6.dat
[10:00:04.014] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C7.dat
[10:00:04.014] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C8.dat
[10:00:04.014] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C9.dat
[10:00:04.014] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C10.dat
[10:00:04.015] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C11.dat
[10:00:04.015] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C12.dat
[10:00:04.015] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C13.dat
[10:00:04.016] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C14.dat
[10:00:04.016] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C15.dat
[10:00:04.016] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//trimParameters35_C0.dat
[10:00:04.024] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//trimParameters35_C1.dat
[10:00:04.031] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//trimParameters35_C2.dat
[10:00:04.039] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//trimParameters35_C3.dat
[10:00:04.046] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//trimParameters35_C4.dat
[10:00:04.055] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//trimParameters35_C5.dat
[10:00:04.063] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//trimParameters35_C6.dat
[10:00:04.070] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//trimParameters35_C7.dat
[10:00:04.076] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//trimParameters35_C8.dat
[10:00:04.083] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//trimParameters35_C9.dat
[10:00:04.090] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//trimParameters35_C10.dat
[10:00:04.096] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//trimParameters35_C11.dat
[10:00:04.104] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//trimParameters35_C12.dat
[10:00:04.112] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//trimParameters35_C13.dat
[10:00:04.119] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//trimParameters35_C14.dat
[10:00:04.126] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//trimParameters35_C15.dat
[10:00:04.132] <TB3> INFO: PixTestTrim::trimTest() done
[10:00:04.132] <TB3> INFO: vtrim: 107 109 99 95 92 93 101 89 86 86 111 106 102 96 108 90
[10:00:04.132] <TB3> INFO: vthrcomp: 92 79 85 78 91 81 84 81 87 78 76 83 88 91 78 77
[10:00:04.132] <TB3> INFO: vcal mean: 34.93 34.97 34.96 34.98 34.99 34.97 34.99 34.98 34.97 35.00 35.00 34.94 35.00 34.99 34.98 35.04
[10:00:04.132] <TB3> INFO: vcal RMS: 0.85 0.84 0.83 0.79 0.85 0.91 0.83 0.81 0.82 0.70 0.81 0.86 0.85 0.76 0.81 0.76
[10:00:04.132] <TB3> INFO: bits mean: 9.68 8.89 9.83 8.64 9.35 9.11 9.99 9.34 9.09 8.54 8.72 9.69 9.68 8.80 8.77 8.86
[10:00:04.132] <TB3> INFO: bits RMS: 2.50 2.60 2.55 2.64 2.74 2.75 2.62 2.72 2.88 2.51 2.55 2.72 2.56 2.91 2.51 2.59
[10:00:04.139] <TB3> INFO: ----------------------------------------------------------------------
[10:00:04.139] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[10:00:04.139] <TB3> INFO: ----------------------------------------------------------------------
[10:00:04.142] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[10:00:04.150] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:00:04.150] <TB3> INFO: run 1 of 1
[10:00:04.436] <TB3> INFO: Expecting 4160000 events.
[10:00:45.986] <TB3> INFO: 889380 events read in total (40994ms).
[10:01:26.651] <TB3> INFO: 1773660 events read in total (81659ms).
[10:02:07.302] <TB3> INFO: 2650000 events read in total (122310ms).
[10:02:47.915] <TB3> INFO: 3521270 events read in total (162923ms).
[10:03:17.872] <TB3> INFO: 4160000 events read in total (192880ms).
[10:03:17.933] <TB3> INFO: Test took 193783ms.
[10:03:47.803] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 528 (plus default)
[10:03:47.811] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:03:47.811] <TB3> INFO: run 1 of 1
[10:03:48.073] <TB3> INFO: Expecting 3120000 events.
[10:04:30.124] <TB3> INFO: 958180 events read in total (41495ms).
[10:05:11.823] <TB3> INFO: 1904125 events read in total (83194ms).
[10:05:54.007] <TB3> INFO: 2837860 events read in total (125378ms).
[10:06:06.993] <TB3> INFO: 3120000 events read in total (138364ms).
[10:06:07.022] <TB3> INFO: Test took 139210ms.
[10:06:31.818] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 138 (-1/-1) hits flags = 528 (plus default)
[10:06:31.826] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:06:31.826] <TB3> INFO: run 1 of 1
[10:06:32.096] <TB3> INFO: Expecting 2891200 events.
[10:07:15.379] <TB3> INFO: 991890 events read in total (42727ms).
[10:07:57.717] <TB3> INFO: 1967010 events read in total (85065ms).
[10:08:37.964] <TB3> INFO: 2891200 events read in total (125312ms).
[10:08:37.989] <TB3> INFO: Test took 126162ms.
[10:09:02.042] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 138 (-1/-1) hits flags = 528 (plus default)
[10:09:02.052] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:09:02.052] <TB3> INFO: run 1 of 1
[10:09:02.319] <TB3> INFO: Expecting 2891200 events.
[10:09:45.581] <TB3> INFO: 990800 events read in total (42706ms).
[10:10:27.843] <TB3> INFO: 1965015 events read in total (84968ms).
[10:11:08.074] <TB3> INFO: 2891200 events read in total (125199ms).
[10:11:08.106] <TB3> INFO: Test took 126054ms.
[10:11:31.787] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 139 (-1/-1) hits flags = 528 (plus default)
[10:11:31.796] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:11:31.799] <TB3> INFO: run 1 of 1
[10:11:32.067] <TB3> INFO: Expecting 2912000 events.
[10:12:15.310] <TB3> INFO: 987110 events read in total (42686ms).
[10:12:58.356] <TB3> INFO: 1958235 events read in total (85732ms).
[10:13:40.542] <TB3> INFO: 2912000 events read in total (127919ms).
[10:13:40.572] <TB3> INFO: Test took 128774ms.
[10:14:05.405] <TB3> INFO: PixTestTrim::trimBitTest() done
[10:14:05.406] <TB3> INFO: PixTestTrim::doTest() done, duration: 2826 seconds
[10:14:05.417] <TB3> INFO: Decoding statistics:
[10:14:05.417] <TB3> INFO: General information:
[10:14:05.417] <TB3> INFO: 16bit words read: 0
[10:14:05.417] <TB3> INFO: valid events total: 0
[10:14:05.417] <TB3> INFO: empty events: 0
[10:14:05.417] <TB3> INFO: valid events with pixels: 0
[10:14:05.417] <TB3> INFO: valid pixel hits: 0
[10:14:05.417] <TB3> INFO: Event errors: 0
[10:14:05.417] <TB3> INFO: start marker: 0
[10:14:05.417] <TB3> INFO: stop marker: 0
[10:14:05.417] <TB3> INFO: overflow: 0
[10:14:05.417] <TB3> INFO: invalid 5bit words: 0
[10:14:05.417] <TB3> INFO: invalid XOR eye diagram: 0
[10:14:05.417] <TB3> INFO: frame (failed synchr.): 0
[10:14:05.417] <TB3> INFO: idle data (no TBM trl): 0
[10:14:05.417] <TB3> INFO: no data (only TBM hdr): 0
[10:14:05.417] <TB3> INFO: TBM errors: 0
[10:14:05.417] <TB3> INFO: flawed TBM headers: 0
[10:14:05.417] <TB3> INFO: flawed TBM trailers: 0
[10:14:05.417] <TB3> INFO: event ID mismatches: 0
[10:14:05.417] <TB3> INFO: ROC errors: 0
[10:14:05.417] <TB3> INFO: missing ROC header(s): 0
[10:14:05.417] <TB3> INFO: misplaced readback start: 0
[10:14:05.417] <TB3> INFO: Pixel decoding errors: 0
[10:14:05.417] <TB3> INFO: pixel data incomplete: 0
[10:14:05.417] <TB3> INFO: pixel address: 0
[10:14:05.417] <TB3> INFO: pulse height fill bit: 0
[10:14:05.417] <TB3> INFO: buffer corruption: 0
[10:14:06.090] <TB3> INFO: ######################################################################
[10:14:06.090] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[10:14:06.090] <TB3> INFO: ######################################################################
[10:14:06.350] <TB3> INFO: Expecting 41600 events.
[10:14:10.119] <TB3> INFO: 41600 events read in total (3213ms).
[10:14:10.119] <TB3> INFO: Test took 4028ms.
[10:14:10.590] <TB3> INFO: Expecting 41600 events.
[10:14:14.354] <TB3> INFO: 41600 events read in total (3208ms).
[10:14:14.355] <TB3> INFO: Test took 4035ms.
[10:14:14.636] <TB3> INFO: Expecting 41600 events.
[10:14:18.413] <TB3> INFO: 41600 events read in total (3221ms).
[10:14:18.413] <TB3> INFO: Test took 4037ms.
[10:14:18.668] <TB3> INFO: Expecting 2560 events.
[10:14:19.523] <TB3> INFO: 2560 events read in total (299ms).
[10:14:19.523] <TB3> INFO: Test took 1097ms.
[10:14:19.828] <TB3> INFO: Expecting 2560 events.
[10:14:20.681] <TB3> INFO: 2560 events read in total (297ms).
[10:14:20.682] <TB3> INFO: Test took 1158ms.
[10:14:20.986] <TB3> INFO: Expecting 2560 events.
[10:14:21.840] <TB3> INFO: 2560 events read in total (297ms).
[10:14:21.840] <TB3> INFO: Test took 1158ms.
[10:14:22.145] <TB3> INFO: Expecting 2560 events.
[10:14:22.997] <TB3> INFO: 2560 events read in total (296ms).
[10:14:22.998] <TB3> INFO: Test took 1157ms.
[10:14:23.303] <TB3> INFO: Expecting 2560 events.
[10:14:24.157] <TB3> INFO: 2560 events read in total (298ms).
[10:14:24.157] <TB3> INFO: Test took 1159ms.
[10:14:24.463] <TB3> INFO: Expecting 2560 events.
[10:14:25.321] <TB3> INFO: 2560 events read in total (302ms).
[10:14:25.321] <TB3> INFO: Test took 1163ms.
[10:14:25.627] <TB3> INFO: Expecting 2560 events.
[10:14:26.481] <TB3> INFO: 2560 events read in total (298ms).
[10:14:26.481] <TB3> INFO: Test took 1159ms.
[10:14:26.786] <TB3> INFO: Expecting 2560 events.
[10:14:27.638] <TB3> INFO: 2560 events read in total (296ms).
[10:14:27.638] <TB3> INFO: Test took 1157ms.
[10:14:27.943] <TB3> INFO: Expecting 2560 events.
[10:14:28.796] <TB3> INFO: 2560 events read in total (296ms).
[10:14:28.796] <TB3> INFO: Test took 1156ms.
[10:14:29.101] <TB3> INFO: Expecting 2560 events.
[10:14:29.955] <TB3> INFO: 2560 events read in total (297ms).
[10:14:29.955] <TB3> INFO: Test took 1158ms.
[10:14:30.260] <TB3> INFO: Expecting 2560 events.
[10:14:31.112] <TB3> INFO: 2560 events read in total (296ms).
[10:14:31.112] <TB3> INFO: Test took 1156ms.
[10:14:31.417] <TB3> INFO: Expecting 2560 events.
[10:14:32.270] <TB3> INFO: 2560 events read in total (297ms).
[10:14:32.270] <TB3> INFO: Test took 1158ms.
[10:14:32.575] <TB3> INFO: Expecting 2560 events.
[10:14:33.429] <TB3> INFO: 2560 events read in total (298ms).
[10:14:33.429] <TB3> INFO: Test took 1158ms.
[10:14:33.734] <TB3> INFO: Expecting 2560 events.
[10:14:34.588] <TB3> INFO: 2560 events read in total (298ms).
[10:14:34.588] <TB3> INFO: Test took 1158ms.
[10:14:34.893] <TB3> INFO: Expecting 2560 events.
[10:14:35.748] <TB3> INFO: 2560 events read in total (299ms).
[10:14:35.748] <TB3> INFO: Test took 1160ms.
[10:14:36.053] <TB3> INFO: Expecting 2560 events.
[10:14:36.907] <TB3> INFO: 2560 events read in total (298ms).
[10:14:36.907] <TB3> INFO: Test took 1158ms.
[10:14:36.910] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:14:37.213] <TB3> INFO: Expecting 655360 events.
[10:14:54.110] <TB3> INFO: 655360 events read in total (16341ms).
[10:14:54.119] <TB3> INFO: Expecting 655360 events.
[10:15:10.609] <TB3> INFO: 655360 events read in total (16088ms).
[10:15:10.621] <TB3> INFO: Expecting 655360 events.
[10:15:27.073] <TB3> INFO: 655360 events read in total (16050ms).
[10:15:27.089] <TB3> INFO: Expecting 655360 events.
[10:15:43.883] <TB3> INFO: 655360 events read in total (16392ms).
[10:15:43.906] <TB3> INFO: Expecting 655360 events.
[10:16:00.818] <TB3> INFO: 655360 events read in total (16510ms).
[10:16:00.841] <TB3> INFO: Expecting 655360 events.
[10:16:17.511] <TB3> INFO: 655360 events read in total (16269ms).
[10:16:17.537] <TB3> INFO: Expecting 655360 events.
[10:16:34.251] <TB3> INFO: 655360 events read in total (16312ms).
[10:16:34.281] <TB3> INFO: Expecting 655360 events.
[10:16:51.043] <TB3> INFO: 655360 events read in total (16361ms).
[10:16:51.081] <TB3> INFO: Expecting 655360 events.
[10:17:07.821] <TB3> INFO: 655360 events read in total (16338ms).
[10:17:07.858] <TB3> INFO: Expecting 655360 events.
[10:17:24.608] <TB3> INFO: 655360 events read in total (16348ms).
[10:17:24.647] <TB3> INFO: Expecting 655360 events.
[10:17:41.462] <TB3> INFO: 655360 events read in total (16414ms).
[10:17:41.506] <TB3> INFO: Expecting 655360 events.
[10:17:58.294] <TB3> INFO: 655360 events read in total (16386ms).
[10:17:58.349] <TB3> INFO: Expecting 655360 events.
[10:18:15.179] <TB3> INFO: 655360 events read in total (16428ms).
[10:18:15.236] <TB3> INFO: Expecting 655360 events.
[10:18:32.035] <TB3> INFO: 655360 events read in total (16398ms).
[10:18:32.088] <TB3> INFO: Expecting 655360 events.
[10:18:48.912] <TB3> INFO: 655360 events read in total (16422ms).
[10:18:48.969] <TB3> INFO: Expecting 655360 events.
[10:19:05.598] <TB3> INFO: 655360 events read in total (16227ms).
[10:19:05.671] <TB3> INFO: Test took 268761ms.
[10:19:05.752] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:19:05.982] <TB3> INFO: Expecting 655360 events.
[10:19:22.737] <TB3> INFO: 655360 events read in total (16199ms).
[10:19:22.745] <TB3> INFO: Expecting 655360 events.
[10:19:39.229] <TB3> INFO: 655360 events read in total (16082ms).
[10:19:39.243] <TB3> INFO: Expecting 655360 events.
[10:19:56.063] <TB3> INFO: 655360 events read in total (16419ms).
[10:19:56.082] <TB3> INFO: Expecting 655360 events.
[10:20:12.791] <TB3> INFO: 655360 events read in total (16307ms).
[10:20:12.810] <TB3> INFO: Expecting 655360 events.
[10:20:29.581] <TB3> INFO: 655360 events read in total (16369ms).
[10:20:29.606] <TB3> INFO: Expecting 655360 events.
[10:20:46.484] <TB3> INFO: 655360 events read in total (16477ms).
[10:20:46.511] <TB3> INFO: Expecting 655360 events.
[10:21:03.034] <TB3> INFO: 655360 events read in total (16121ms).
[10:21:03.063] <TB3> INFO: Expecting 655360 events.
[10:21:19.603] <TB3> INFO: 655360 events read in total (16139ms).
[10:21:19.639] <TB3> INFO: Expecting 655360 events.
[10:21:36.414] <TB3> INFO: 655360 events read in total (16373ms).
[10:21:36.450] <TB3> INFO: Expecting 655360 events.
[10:21:53.510] <TB3> INFO: 655360 events read in total (16659ms).
[10:21:53.551] <TB3> INFO: Expecting 655360 events.
[10:22:10.498] <TB3> INFO: 655360 events read in total (16545ms).
[10:22:10.540] <TB3> INFO: Expecting 655360 events.
[10:22:27.474] <TB3> INFO: 655360 events read in total (16533ms).
[10:22:27.523] <TB3> INFO: Expecting 655360 events.
[10:22:44.379] <TB3> INFO: 655360 events read in total (16454ms).
[10:22:44.433] <TB3> INFO: Expecting 655360 events.
[10:23:01.087] <TB3> INFO: 655360 events read in total (16252ms).
[10:23:01.140] <TB3> INFO: Expecting 655360 events.
[10:23:17.883] <TB3> INFO: 655360 events read in total (16341ms).
[10:23:17.944] <TB3> INFO: Expecting 655360 events.
[10:23:34.758] <TB3> INFO: 655360 events read in total (16412ms).
[10:23:34.822] <TB3> INFO: Test took 269070ms.
[10:23:35.010] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:23:35.017] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:23:35.024] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:23:35.031] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:23:35.038] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:23:35.045] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:23:35.052] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:23:35.059] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:23:35.066] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:23:35.073] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:23:35.080] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:23:35.087] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:23:35.093] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:23:35.102] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:23:35.111] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:23:35.119] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[10:23:35.160] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C0.dat
[10:23:35.161] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C1.dat
[10:23:35.161] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C2.dat
[10:23:35.161] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C3.dat
[10:23:35.161] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C4.dat
[10:23:35.162] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C5.dat
[10:23:35.162] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C6.dat
[10:23:35.162] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C7.dat
[10:23:35.162] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C8.dat
[10:23:35.162] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C9.dat
[10:23:35.162] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C10.dat
[10:23:35.163] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C11.dat
[10:23:35.163] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C12.dat
[10:23:35.163] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C13.dat
[10:23:35.163] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C14.dat
[10:23:35.163] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//dacParameters35_C15.dat
[10:23:35.444] <TB3> INFO: Expecting 41600 events.
[10:23:38.939] <TB3> INFO: 41600 events read in total (2939ms).
[10:23:38.939] <TB3> INFO: Test took 3773ms.
[10:23:39.395] <TB3> INFO: Expecting 41600 events.
[10:23:42.903] <TB3> INFO: 41600 events read in total (2952ms).
[10:23:42.904] <TB3> INFO: Test took 3772ms.
[10:23:43.353] <TB3> INFO: Expecting 41600 events.
[10:23:46.819] <TB3> INFO: 41600 events read in total (2910ms).
[10:23:46.820] <TB3> INFO: Test took 3739ms.
[10:23:47.003] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:23:47.091] <TB3> INFO: Expecting 2560 events.
[10:23:47.948] <TB3> INFO: 2560 events read in total (301ms).
[10:23:47.949] <TB3> INFO: Test took 946ms.
[10:23:47.951] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:23:48.254] <TB3> INFO: Expecting 2560 events.
[10:23:49.109] <TB3> INFO: 2560 events read in total (299ms).
[10:23:49.109] <TB3> INFO: Test took 1158ms.
[10:23:49.111] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:23:49.415] <TB3> INFO: Expecting 2560 events.
[10:23:50.269] <TB3> INFO: 2560 events read in total (298ms).
[10:23:50.270] <TB3> INFO: Test took 1159ms.
[10:23:50.272] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:23:50.575] <TB3> INFO: Expecting 2560 events.
[10:23:51.429] <TB3> INFO: 2560 events read in total (298ms).
[10:23:51.429] <TB3> INFO: Test took 1157ms.
[10:23:51.432] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:23:51.735] <TB3> INFO: Expecting 2560 events.
[10:23:52.593] <TB3> INFO: 2560 events read in total (302ms).
[10:23:52.594] <TB3> INFO: Test took 1162ms.
[10:23:52.596] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:23:52.900] <TB3> INFO: Expecting 2560 events.
[10:23:53.753] <TB3> INFO: 2560 events read in total (297ms).
[10:23:53.753] <TB3> INFO: Test took 1157ms.
[10:23:53.756] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:23:54.059] <TB3> INFO: Expecting 2560 events.
[10:23:54.917] <TB3> INFO: 2560 events read in total (302ms).
[10:23:54.918] <TB3> INFO: Test took 1162ms.
[10:23:54.920] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:23:55.224] <TB3> INFO: Expecting 2560 events.
[10:23:56.082] <TB3> INFO: 2560 events read in total (303ms).
[10:23:56.083] <TB3> INFO: Test took 1163ms.
[10:23:56.086] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:23:56.389] <TB3> INFO: Expecting 2560 events.
[10:23:57.247] <TB3> INFO: 2560 events read in total (302ms).
[10:23:57.247] <TB3> INFO: Test took 1161ms.
[10:23:57.249] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:23:57.553] <TB3> INFO: Expecting 2560 events.
[10:23:58.406] <TB3> INFO: 2560 events read in total (297ms).
[10:23:58.407] <TB3> INFO: Test took 1158ms.
[10:23:58.409] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:23:58.712] <TB3> INFO: Expecting 2560 events.
[10:23:59.565] <TB3> INFO: 2560 events read in total (296ms).
[10:23:59.565] <TB3> INFO: Test took 1156ms.
[10:23:59.568] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:23:59.871] <TB3> INFO: Expecting 2560 events.
[10:24:00.725] <TB3> INFO: 2560 events read in total (297ms).
[10:24:00.725] <TB3> INFO: Test took 1157ms.
[10:24:00.728] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:01.030] <TB3> INFO: Expecting 2560 events.
[10:24:01.888] <TB3> INFO: 2560 events read in total (302ms).
[10:24:01.888] <TB3> INFO: Test took 1160ms.
[10:24:01.890] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:02.194] <TB3> INFO: Expecting 2560 events.
[10:24:03.048] <TB3> INFO: 2560 events read in total (298ms).
[10:24:03.049] <TB3> INFO: Test took 1159ms.
[10:24:03.050] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:03.354] <TB3> INFO: Expecting 2560 events.
[10:24:04.208] <TB3> INFO: 2560 events read in total (297ms).
[10:24:04.208] <TB3> INFO: Test took 1158ms.
[10:24:04.211] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:04.514] <TB3> INFO: Expecting 2560 events.
[10:24:05.366] <TB3> INFO: 2560 events read in total (296ms).
[10:24:05.366] <TB3> INFO: Test took 1155ms.
[10:24:05.369] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:05.672] <TB3> INFO: Expecting 2560 events.
[10:24:06.525] <TB3> INFO: 2560 events read in total (297ms).
[10:24:06.525] <TB3> INFO: Test took 1156ms.
[10:24:06.526] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:06.830] <TB3> INFO: Expecting 2560 events.
[10:24:07.684] <TB3> INFO: 2560 events read in total (297ms).
[10:24:07.684] <TB3> INFO: Test took 1158ms.
[10:24:07.686] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:07.990] <TB3> INFO: Expecting 2560 events.
[10:24:08.844] <TB3> INFO: 2560 events read in total (298ms).
[10:24:08.844] <TB3> INFO: Test took 1158ms.
[10:24:08.847] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:09.149] <TB3> INFO: Expecting 2560 events.
[10:24:10.007] <TB3> INFO: 2560 events read in total (301ms).
[10:24:10.007] <TB3> INFO: Test took 1160ms.
[10:24:10.009] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:10.313] <TB3> INFO: Expecting 2560 events.
[10:24:11.170] <TB3> INFO: 2560 events read in total (301ms).
[10:24:11.170] <TB3> INFO: Test took 1161ms.
[10:24:11.173] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:11.476] <TB3> INFO: Expecting 2560 events.
[10:24:12.334] <TB3> INFO: 2560 events read in total (301ms).
[10:24:12.334] <TB3> INFO: Test took 1161ms.
[10:24:12.336] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:12.640] <TB3> INFO: Expecting 2560 events.
[10:24:13.497] <TB3> INFO: 2560 events read in total (301ms).
[10:24:13.498] <TB3> INFO: Test took 1162ms.
[10:24:13.500] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:13.804] <TB3> INFO: Expecting 2560 events.
[10:24:14.661] <TB3> INFO: 2560 events read in total (301ms).
[10:24:14.661] <TB3> INFO: Test took 1161ms.
[10:24:14.664] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:14.967] <TB3> INFO: Expecting 2560 events.
[10:24:15.820] <TB3> INFO: 2560 events read in total (297ms).
[10:24:15.820] <TB3> INFO: Test took 1156ms.
[10:24:15.822] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:16.126] <TB3> INFO: Expecting 2560 events.
[10:24:16.986] <TB3> INFO: 2560 events read in total (303ms).
[10:24:16.986] <TB3> INFO: Test took 1164ms.
[10:24:16.988] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:17.291] <TB3> INFO: Expecting 2560 events.
[10:24:18.147] <TB3> INFO: 2560 events read in total (300ms).
[10:24:18.147] <TB3> INFO: Test took 1159ms.
[10:24:18.149] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:18.454] <TB3> INFO: Expecting 2560 events.
[10:24:19.307] <TB3> INFO: 2560 events read in total (297ms).
[10:24:19.307] <TB3> INFO: Test took 1158ms.
[10:24:19.309] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:19.613] <TB3> INFO: Expecting 2560 events.
[10:24:20.472] <TB3> INFO: 2560 events read in total (303ms).
[10:24:20.472] <TB3> INFO: Test took 1163ms.
[10:24:20.474] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:20.778] <TB3> INFO: Expecting 2560 events.
[10:24:21.636] <TB3> INFO: 2560 events read in total (302ms).
[10:24:21.636] <TB3> INFO: Test took 1162ms.
[10:24:21.638] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:21.942] <TB3> INFO: Expecting 2560 events.
[10:24:22.800] <TB3> INFO: 2560 events read in total (302ms).
[10:24:22.800] <TB3> INFO: Test took 1162ms.
[10:24:22.802] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:24:23.106] <TB3> INFO: Expecting 2560 events.
[10:24:23.959] <TB3> INFO: 2560 events read in total (297ms).
[10:24:23.959] <TB3> INFO: Test took 1157ms.
[10:24:24.417] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 618 seconds
[10:24:24.417] <TB3> INFO: PH scale (per ROC): 97 83 88 82 91 79 80 94 83 106 83 92 83 89 90 95
[10:24:24.417] <TB3> INFO: PH offset (per ROC): 154 153 147 143 151 145 140 149 158 137 157 154 157 142 169 139
[10:24:24.421] <TB3> INFO: Decoding statistics:
[10:24:24.421] <TB3> INFO: General information:
[10:24:24.421] <TB3> INFO: 16bit words read: 91940
[10:24:24.421] <TB3> INFO: valid events total: 10240
[10:24:24.421] <TB3> INFO: empty events: 7735
[10:24:24.421] <TB3> INFO: valid events with pixels: 2505
[10:24:24.421] <TB3> INFO: valid pixel hits: 2505
[10:24:24.421] <TB3> INFO: Event errors: 0
[10:24:24.421] <TB3> INFO: start marker: 0
[10:24:24.421] <TB3> INFO: stop marker: 0
[10:24:24.421] <TB3> INFO: overflow: 0
[10:24:24.421] <TB3> INFO: invalid 5bit words: 0
[10:24:24.421] <TB3> INFO: invalid XOR eye diagram: 0
[10:24:24.421] <TB3> INFO: frame (failed synchr.): 0
[10:24:24.421] <TB3> INFO: idle data (no TBM trl): 0
[10:24:24.421] <TB3> INFO: no data (only TBM hdr): 0
[10:24:24.421] <TB3> INFO: TBM errors: 0
[10:24:24.421] <TB3> INFO: flawed TBM headers: 0
[10:24:24.421] <TB3> INFO: flawed TBM trailers: 0
[10:24:24.421] <TB3> INFO: event ID mismatches: 0
[10:24:24.421] <TB3> INFO: ROC errors: 0
[10:24:24.421] <TB3> INFO: missing ROC header(s): 0
[10:24:24.421] <TB3> INFO: misplaced readback start: 0
[10:24:24.421] <TB3> INFO: Pixel decoding errors: 0
[10:24:24.421] <TB3> INFO: pixel data incomplete: 0
[10:24:24.421] <TB3> INFO: pixel address: 0
[10:24:24.421] <TB3> INFO: pulse height fill bit: 0
[10:24:24.421] <TB3> INFO: buffer corruption: 0
[10:24:24.591] <TB3> INFO: ######################################################################
[10:24:24.591] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[10:24:24.591] <TB3> INFO: ######################################################################
[10:24:24.600] <TB3> INFO: scanning low vcal = 10
[10:24:24.862] <TB3> INFO: Expecting 41600 events.
[10:24:28.416] <TB3> INFO: 41600 events read in total (2998ms).
[10:24:28.416] <TB3> INFO: Test took 3815ms.
[10:24:28.418] <TB3> INFO: scanning low vcal = 20
[10:24:28.721] <TB3> INFO: Expecting 41600 events.
[10:24:32.275] <TB3> INFO: 41600 events read in total (2998ms).
[10:24:32.275] <TB3> INFO: Test took 3857ms.
[10:24:32.277] <TB3> INFO: scanning low vcal = 30
[10:24:32.583] <TB3> INFO: Expecting 41600 events.
[10:24:36.133] <TB3> INFO: 41600 events read in total (2994ms).
[10:24:36.133] <TB3> INFO: Test took 3856ms.
[10:24:36.135] <TB3> INFO: scanning low vcal = 40
[10:24:36.435] <TB3> INFO: Expecting 41600 events.
[10:24:40.593] <TB3> INFO: 41600 events read in total (3602ms).
[10:24:40.594] <TB3> INFO: Test took 4459ms.
[10:24:40.604] <TB3> INFO: scanning low vcal = 50
[10:24:40.874] <TB3> INFO: Expecting 41600 events.
[10:24:45.155] <TB3> INFO: 41600 events read in total (3725ms).
[10:24:45.156] <TB3> INFO: Test took 4552ms.
[10:24:45.159] <TB3> INFO: scanning low vcal = 60
[10:24:45.432] <TB3> INFO: Expecting 41600 events.
[10:24:49.655] <TB3> INFO: 41600 events read in total (3667ms).
[10:24:49.656] <TB3> INFO: Test took 4497ms.
[10:24:49.659] <TB3> INFO: scanning low vcal = 70
[10:24:49.921] <TB3> INFO: Expecting 41600 events.
[10:24:54.093] <TB3> INFO: 41600 events read in total (3616ms).
[10:24:54.094] <TB3> INFO: Test took 4435ms.
[10:24:54.097] <TB3> INFO: scanning low vcal = 80
[10:24:54.356] <TB3> INFO: Expecting 41600 events.
[10:24:58.531] <TB3> INFO: 41600 events read in total (3619ms).
[10:24:58.532] <TB3> INFO: Test took 4435ms.
[10:24:58.544] <TB3> INFO: scanning low vcal = 90
[10:24:58.816] <TB3> INFO: Expecting 41600 events.
[10:25:02.999] <TB3> INFO: 41600 events read in total (3627ms).
[10:25:02.999] <TB3> INFO: Test took 4455ms.
[10:25:03.003] <TB3> INFO: scanning low vcal = 100
[10:25:03.259] <TB3> INFO: Expecting 41600 events.
[10:25:07.446] <TB3> INFO: 41600 events read in total (3631ms).
[10:25:07.446] <TB3> INFO: Test took 4443ms.
[10:25:07.449] <TB3> INFO: scanning low vcal = 110
[10:25:07.737] <TB3> INFO: Expecting 41600 events.
[10:25:11.923] <TB3> INFO: 41600 events read in total (3630ms).
[10:25:11.923] <TB3> INFO: Test took 4473ms.
[10:25:11.927] <TB3> INFO: scanning low vcal = 120
[10:25:12.209] <TB3> INFO: Expecting 41600 events.
[10:25:16.420] <TB3> INFO: 41600 events read in total (3654ms).
[10:25:16.420] <TB3> INFO: Test took 4493ms.
[10:25:16.423] <TB3> INFO: scanning low vcal = 130
[10:25:16.681] <TB3> INFO: Expecting 41600 events.
[10:25:20.899] <TB3> INFO: 41600 events read in total (3662ms).
[10:25:20.899] <TB3> INFO: Test took 4476ms.
[10:25:20.902] <TB3> INFO: scanning low vcal = 140
[10:25:21.161] <TB3> INFO: Expecting 41600 events.
[10:25:25.341] <TB3> INFO: 41600 events read in total (3623ms).
[10:25:25.342] <TB3> INFO: Test took 4440ms.
[10:25:25.344] <TB3> INFO: scanning low vcal = 150
[10:25:25.603] <TB3> INFO: Expecting 41600 events.
[10:25:29.777] <TB3> INFO: 41600 events read in total (3618ms).
[10:25:29.778] <TB3> INFO: Test took 4434ms.
[10:25:29.780] <TB3> INFO: scanning low vcal = 160
[10:25:30.039] <TB3> INFO: Expecting 41600 events.
[10:25:34.254] <TB3> INFO: 41600 events read in total (3658ms).
[10:25:34.255] <TB3> INFO: Test took 4474ms.
[10:25:34.258] <TB3> INFO: scanning low vcal = 170
[10:25:34.521] <TB3> INFO: Expecting 41600 events.
[10:25:38.699] <TB3> INFO: 41600 events read in total (3622ms).
[10:25:38.700] <TB3> INFO: Test took 4442ms.
[10:25:38.703] <TB3> INFO: scanning low vcal = 180
[10:25:38.962] <TB3> INFO: Expecting 41600 events.
[10:25:43.140] <TB3> INFO: 41600 events read in total (3622ms).
[10:25:43.140] <TB3> INFO: Test took 4437ms.
[10:25:43.143] <TB3> INFO: scanning low vcal = 190
[10:25:43.405] <TB3> INFO: Expecting 41600 events.
[10:25:47.587] <TB3> INFO: 41600 events read in total (3626ms).
[10:25:47.588] <TB3> INFO: Test took 4445ms.
[10:25:47.591] <TB3> INFO: scanning low vcal = 200
[10:25:47.850] <TB3> INFO: Expecting 41600 events.
[10:25:52.042] <TB3> INFO: 41600 events read in total (3636ms).
[10:25:52.042] <TB3> INFO: Test took 4451ms.
[10:25:52.045] <TB3> INFO: scanning low vcal = 210
[10:25:52.308] <TB3> INFO: Expecting 41600 events.
[10:25:56.542] <TB3> INFO: 41600 events read in total (3678ms).
[10:25:56.543] <TB3> INFO: Test took 4498ms.
[10:25:56.546] <TB3> INFO: scanning low vcal = 220
[10:25:56.833] <TB3> INFO: Expecting 41600 events.
[10:26:01.064] <TB3> INFO: 41600 events read in total (3674ms).
[10:26:01.064] <TB3> INFO: Test took 4518ms.
[10:26:01.067] <TB3> INFO: scanning low vcal = 230
[10:26:01.345] <TB3> INFO: Expecting 41600 events.
[10:26:05.506] <TB3> INFO: 41600 events read in total (3605ms).
[10:26:05.507] <TB3> INFO: Test took 4440ms.
[10:26:05.509] <TB3> INFO: scanning low vcal = 240
[10:26:05.768] <TB3> INFO: Expecting 41600 events.
[10:26:09.950] <TB3> INFO: 41600 events read in total (3626ms).
[10:26:09.950] <TB3> INFO: Test took 4441ms.
[10:26:09.953] <TB3> INFO: scanning low vcal = 250
[10:26:10.215] <TB3> INFO: Expecting 41600 events.
[10:26:14.410] <TB3> INFO: 41600 events read in total (3639ms).
[10:26:14.410] <TB3> INFO: Test took 4457ms.
[10:26:14.414] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[10:26:14.674] <TB3> INFO: Expecting 41600 events.
[10:26:18.848] <TB3> INFO: 41600 events read in total (3618ms).
[10:26:18.848] <TB3> INFO: Test took 4433ms.
[10:26:18.851] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[10:26:19.112] <TB3> INFO: Expecting 41600 events.
[10:26:23.275] <TB3> INFO: 41600 events read in total (3606ms).
[10:26:23.276] <TB3> INFO: Test took 4425ms.
[10:26:23.279] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[10:26:23.538] <TB3> INFO: Expecting 41600 events.
[10:26:27.724] <TB3> INFO: 41600 events read in total (3630ms).
[10:26:27.724] <TB3> INFO: Test took 4445ms.
[10:26:27.728] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[10:26:27.986] <TB3> INFO: Expecting 41600 events.
[10:26:32.182] <TB3> INFO: 41600 events read in total (3640ms).
[10:26:32.183] <TB3> INFO: Test took 4455ms.
[10:26:32.185] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[10:26:32.442] <TB3> INFO: Expecting 41600 events.
[10:26:36.617] <TB3> INFO: 41600 events read in total (3619ms).
[10:26:36.618] <TB3> INFO: Test took 4433ms.
[10:26:37.043] <TB3> INFO: PixTestGainPedestal::measure() done
[10:27:08.625] <TB3> INFO: PixTestGainPedestal::fit() done
[10:27:08.625] <TB3> INFO: non-linearity mean: 0.957 0.957 0.953 0.954 0.962 0.957 0.953 0.958 0.951 0.950 0.949 0.957 0.952 0.955 0.953 0.950
[10:27:08.625] <TB3> INFO: non-linearity RMS: 0.005 0.006 0.006 0.006 0.005 0.007 0.006 0.005 0.006 0.006 0.006 0.006 0.007 0.005 0.005 0.006
[10:27:08.625] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[10:27:08.643] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[10:27:08.661] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[10:27:08.679] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[10:27:08.697] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[10:27:08.715] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[10:27:08.733] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[10:27:08.751] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[10:27:08.769] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[10:27:08.787] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[10:27:08.805] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[10:27:08.823] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[10:27:08.841] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[10:27:08.858] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[10:27:08.876] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[10:27:08.894] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[10:27:08.912] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 164 seconds
[10:27:08.912] <TB3> INFO: Decoding statistics:
[10:27:08.912] <TB3> INFO: General information:
[10:27:08.912] <TB3> INFO: 16bit words read: 2662400
[10:27:08.912] <TB3> INFO: valid events total: 166400
[10:27:08.912] <TB3> INFO: empty events: 0
[10:27:08.912] <TB3> INFO: valid events with pixels: 166400
[10:27:08.912] <TB3> INFO: valid pixel hits: 665590
[10:27:08.912] <TB3> INFO: Event errors: 0
[10:27:08.912] <TB3> INFO: start marker: 0
[10:27:08.912] <TB3> INFO: stop marker: 0
[10:27:08.912] <TB3> INFO: overflow: 0
[10:27:08.912] <TB3> INFO: invalid 5bit words: 0
[10:27:08.912] <TB3> INFO: invalid XOR eye diagram: 0
[10:27:08.912] <TB3> INFO: frame (failed synchr.): 0
[10:27:08.912] <TB3> INFO: idle data (no TBM trl): 0
[10:27:08.912] <TB3> INFO: no data (only TBM hdr): 0
[10:27:08.912] <TB3> INFO: TBM errors: 0
[10:27:08.912] <TB3> INFO: flawed TBM headers: 0
[10:27:08.912] <TB3> INFO: flawed TBM trailers: 0
[10:27:08.912] <TB3> INFO: event ID mismatches: 0
[10:27:08.912] <TB3> INFO: ROC errors: 0
[10:27:08.912] <TB3> INFO: missing ROC header(s): 0
[10:27:08.912] <TB3> INFO: misplaced readback start: 0
[10:27:08.912] <TB3> INFO: Pixel decoding errors: 0
[10:27:08.912] <TB3> INFO: pixel data incomplete: 0
[10:27:08.912] <TB3> INFO: pixel address: 0
[10:27:08.912] <TB3> INFO: pulse height fill bit: 0
[10:27:08.912] <TB3> INFO: buffer corruption: 0
[10:27:08.919] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C15.dat
[10:27:08.921] <TB3> INFO: ######################################################################
[10:27:08.921] <TB3> INFO: PixTestReadback::doTest()
[10:27:08.921] <TB3> INFO: ######################################################################
[10:27:08.921] <TB3> INFO: ----------------------------------------------------------------------
[10:27:08.921] <TB3> INFO: PixTestReadback::CalibrateVd()
[10:27:08.921] <TB3> INFO: ----------------------------------------------------------------------
[10:27:18.385] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C0.dat
[10:27:18.386] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C1.dat
[10:27:18.386] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C2.dat
[10:27:18.386] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C3.dat
[10:27:18.386] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C4.dat
[10:27:18.386] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C5.dat
[10:27:18.386] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C6.dat
[10:27:18.387] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C7.dat
[10:27:18.387] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C8.dat
[10:27:18.387] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C9.dat
[10:27:18.387] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C10.dat
[10:27:18.387] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C11.dat
[10:27:18.387] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C12.dat
[10:27:18.388] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C13.dat
[10:27:18.388] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C14.dat
[10:27:18.388] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C15.dat
[10:27:18.417] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[10:27:18.417] <TB3> INFO: ----------------------------------------------------------------------
[10:27:18.417] <TB3> INFO: PixTestReadback::CalibrateVa()
[10:27:18.417] <TB3> INFO: ----------------------------------------------------------------------
[10:27:27.910] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C0.dat
[10:27:27.910] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C1.dat
[10:27:27.910] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C2.dat
[10:27:27.911] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C3.dat
[10:27:27.911] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C4.dat
[10:27:27.911] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C5.dat
[10:27:27.911] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C6.dat
[10:27:27.911] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C7.dat
[10:27:27.911] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C8.dat
[10:27:27.911] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C9.dat
[10:27:27.911] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C10.dat
[10:27:27.911] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C11.dat
[10:27:27.911] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C12.dat
[10:27:27.911] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C13.dat
[10:27:27.911] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C14.dat
[10:27:27.911] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C15.dat
[10:27:27.939] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[10:27:27.939] <TB3> INFO: ----------------------------------------------------------------------
[10:27:27.939] <TB3> INFO: PixTestReadback::readbackVbg()
[10:27:27.939] <TB3> INFO: ----------------------------------------------------------------------
[10:27:35.235] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[10:27:35.235] <TB3> INFO: ----------------------------------------------------------------------
[10:27:35.235] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[10:27:35.235] <TB3> INFO: ----------------------------------------------------------------------
[10:27:35.235] <TB3> INFO: Vbg will be calibrated using Vd calibration
[10:27:35.235] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 151.6calibrated Vbg = 1.21084 :::*/*/*/*/
[10:27:35.235] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 152.8calibrated Vbg = 1.21068 :::*/*/*/*/
[10:27:35.235] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 150.3calibrated Vbg = 1.20439 :::*/*/*/*/
[10:27:35.235] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 163.6calibrated Vbg = 1.21642 :::*/*/*/*/
[10:27:35.235] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 149.7calibrated Vbg = 1.22478 :::*/*/*/*/
[10:27:35.235] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 148calibrated Vbg = 1.22255 :::*/*/*/*/
[10:27:35.235] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 147.3calibrated Vbg = 1.22144 :::*/*/*/*/
[10:27:35.235] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 157.3calibrated Vbg = 1.22197 :::*/*/*/*/
[10:27:35.235] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 159.6calibrated Vbg = 1.22336 :::*/*/*/*/
[10:27:35.235] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 155.4calibrated Vbg = 1.2227 :::*/*/*/*/
[10:27:35.235] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 162.8calibrated Vbg = 1.22389 :::*/*/*/*/
[10:27:35.235] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 146.6calibrated Vbg = 1.21764 :::*/*/*/*/
[10:27:35.235] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 152.6calibrated Vbg = 1.21503 :::*/*/*/*/
[10:27:35.235] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 145.4calibrated Vbg = 1.22013 :::*/*/*/*/
[10:27:35.235] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 155.4calibrated Vbg = 1.21992 :::*/*/*/*/
[10:27:35.235] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 158.5calibrated Vbg = 1.21503 :::*/*/*/*/
[10:27:35.238] <TB3> INFO: ----------------------------------------------------------------------
[10:27:35.238] <TB3> INFO: PixTestReadback::CalibrateIa()
[10:27:35.238] <TB3> INFO: ----------------------------------------------------------------------
[10:30:10.562] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C0.dat
[10:30:10.562] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C1.dat
[10:30:10.562] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C2.dat
[10:30:10.562] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C3.dat
[10:30:10.562] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C4.dat
[10:30:10.562] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C5.dat
[10:30:10.562] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C6.dat
[10:30:10.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C7.dat
[10:30:10.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C8.dat
[10:30:10.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C9.dat
[10:30:10.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C10.dat
[10:30:10.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C11.dat
[10:30:10.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C12.dat
[10:30:10.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C13.dat
[10:30:10.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C14.dat
[10:30:10.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//000_FulltestPxar_m20//readbackCal_C15.dat
[10:30:10.591] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[10:30:10.592] <TB3> INFO: PixTestReadback::doTest() done
[10:30:10.592] <TB3> INFO: Decoding statistics:
[10:30:10.592] <TB3> INFO: General information:
[10:30:10.592] <TB3> INFO: 16bit words read: 1024
[10:30:10.592] <TB3> INFO: valid events total: 128
[10:30:10.592] <TB3> INFO: empty events: 128
[10:30:10.592] <TB3> INFO: valid events with pixels: 0
[10:30:10.592] <TB3> INFO: valid pixel hits: 0
[10:30:10.592] <TB3> INFO: Event errors: 0
[10:30:10.592] <TB3> INFO: start marker: 0
[10:30:10.592] <TB3> INFO: stop marker: 0
[10:30:10.592] <TB3> INFO: overflow: 0
[10:30:10.592] <TB3> INFO: invalid 5bit words: 0
[10:30:10.592] <TB3> INFO: invalid XOR eye diagram: 0
[10:30:10.592] <TB3> INFO: frame (failed synchr.): 0
[10:30:10.592] <TB3> INFO: idle data (no TBM trl): 0
[10:30:10.592] <TB3> INFO: no data (only TBM hdr): 0
[10:30:10.592] <TB3> INFO: TBM errors: 0
[10:30:10.592] <TB3> INFO: flawed TBM headers: 0
[10:30:10.592] <TB3> INFO: flawed TBM trailers: 0
[10:30:10.592] <TB3> INFO: event ID mismatches: 0
[10:30:10.592] <TB3> INFO: ROC errors: 0
[10:30:10.592] <TB3> INFO: missing ROC header(s): 0
[10:30:10.592] <TB3> INFO: misplaced readback start: 0
[10:30:10.592] <TB3> INFO: Pixel decoding errors: 0
[10:30:10.592] <TB3> INFO: pixel data incomplete: 0
[10:30:10.592] <TB3> INFO: pixel address: 0
[10:30:10.592] <TB3> INFO: pulse height fill bit: 0
[10:30:10.592] <TB3> INFO: buffer corruption: 0
[10:30:10.610] <TB3> INFO: Decoding statistics:
[10:30:10.610] <TB3> INFO: General information:
[10:30:10.610] <TB3> INFO: 16bit words read: 2755364
[10:30:10.610] <TB3> INFO: valid events total: 176768
[10:30:10.610] <TB3> INFO: empty events: 7863
[10:30:10.610] <TB3> INFO: valid events with pixels: 168905
[10:30:10.610] <TB3> INFO: valid pixel hits: 668095
[10:30:10.610] <TB3> INFO: Event errors: 0
[10:30:10.610] <TB3> INFO: start marker: 0
[10:30:10.610] <TB3> INFO: stop marker: 0
[10:30:10.610] <TB3> INFO: overflow: 0
[10:30:10.610] <TB3> INFO: invalid 5bit words: 0
[10:30:10.610] <TB3> INFO: invalid XOR eye diagram: 0
[10:30:10.610] <TB3> INFO: frame (failed synchr.): 0
[10:30:10.610] <TB3> INFO: idle data (no TBM trl): 0
[10:30:10.610] <TB3> INFO: no data (only TBM hdr): 0
[10:30:10.610] <TB3> INFO: TBM errors: 0
[10:30:10.610] <TB3> INFO: flawed TBM headers: 0
[10:30:10.610] <TB3> INFO: flawed TBM trailers: 0
[10:30:10.610] <TB3> INFO: event ID mismatches: 0
[10:30:10.610] <TB3> INFO: ROC errors: 0
[10:30:10.610] <TB3> INFO: missing ROC header(s): 0
[10:30:10.610] <TB3> INFO: misplaced readback start: 0
[10:30:10.610] <TB3> INFO: Pixel decoding errors: 0
[10:30:10.610] <TB3> INFO: pixel data incomplete: 0
[10:30:10.610] <TB3> INFO: pixel address: 0
[10:30:10.611] <TB3> INFO: pulse height fill bit: 0
[10:30:10.611] <TB3> INFO: buffer corruption: 0
[10:30:10.611] <TB3> INFO: enter test to run
[10:30:10.611] <TB3> INFO: test: exit no parameter change
[10:30:10.775] <TB3> QUIET: Connection to board 170 closed.
[10:30:10.854] <TB3> INFO: pXar: this is the end, my friend