[08:09:08.931] <TB2> INFO: *** Welcome to pxar ***
[08:09:08.931] <TB2> INFO: *** Today: 2016/09/08
[08:09:09.044] <TB2> INFO: *** Version: fe94
[08:09:09.044] <TB2> INFO: readRocDacs: /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C15.dat
[08:09:09.046] <TB2> INFO: readTbmDacs: /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//tbmParameters_C0b.dat
[08:09:09.046] <TB2> INFO: readMaskFile: /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//defaultMaskFile.dat
[08:09:09.046] <TB2> INFO: readTrimFile: /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters_C15.dat
[08:09:09.117] <TB2> INFO: clk: 4
[08:09:09.117] <TB2> INFO: ctr: 4
[08:09:09.117] <TB2> INFO: sda: 19
[08:09:09.117] <TB2> INFO: tin: 9
[08:09:09.117] <TB2> INFO: level: 15
[08:09:09.117] <TB2> INFO: triggerdelay: 0
[08:09:09.117] <TB2> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[08:09:09.117] <TB2> INFO: Log level: INFO
[08:09:09.124] <TB2> INFO: Found DTB DTB_WXC55Z
[08:09:09.131] <TB2> QUIET: Connection to board DTB_WXC55Z opened.
[08:09:09.133] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 156
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WXC55Z
MAC address: 40D85511809C
Hostname: pixelDTB156
Comment:
------------------------------------------------------
[08:09:09.135] <TB2> INFO: RPC call hashes of host and DTB match: 484264910
[08:09:10.677] <TB2> INFO: DUT info:
[08:09:10.677] <TB2> INFO: The DUT currently contains the following objects:
[08:09:10.677] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:09:10.677] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:09:10.677] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:09:10.677] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:09:10.677] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:10.678] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:10.678] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:10.678] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:10.678] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:10.678] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:10.678] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:10.678] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:10.678] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:10.678] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:10.678] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:10.678] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:10.678] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:10.678] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:10.678] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:10.678] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:11.079] <TB2> INFO: enter 'restricted' command line mode
[08:09:11.079] <TB2> INFO: enter test to run
[08:09:11.079] <TB2> INFO: test: pretest no parameter change
[08:09:11.079] <TB2> INFO: running: pretest
[08:09:11.084] <TB2> INFO: ----------------------------------------------------------------------
[08:09:11.084] <TB2> INFO: PixTestPretest::programROC()
[08:09:11.084] <TB2> INFO: ----------------------------------------------------------------------
[08:09:29.098] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[08:09:29.098] <TB2> INFO: IA differences per ROC: 20.9 20.9 20.9 20.9 19.3 20.1 20.9 17.7 19.3 19.3 17.7 20.1 20.9 19.3 19.3 20.1
[08:09:29.199] <TB2> INFO: enter test to run
[08:09:29.199] <TB2> INFO: test: pretest no parameter change
[08:09:29.199] <TB2> INFO: running: pretest
[08:09:29.200] <TB2> INFO: ----------------------------------------------------------------------
[08:09:29.200] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[08:09:29.200] <TB2> INFO: ----------------------------------------------------------------------
[08:09:35.687] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 385.9 mA = 24.1188 mA/ROC
[08:09:35.687] <TB2> INFO: i(loss) [mA/ROC]: 19.3 19.3 18.5 19.3 19.3 18.5 19.3 18.5 19.3 18.5 19.3 18.5 18.5 18.5 17.7 19.3
[08:09:35.732] <TB2> INFO: enter test to run
[08:09:35.732] <TB2> INFO: test: pretest no parameter change
[08:09:35.732] <TB2> INFO: running: pretest
[08:09:35.732] <TB2> INFO: ----------------------------------------------------------------------
[08:09:35.732] <TB2> INFO: PixTestPretest::findTiming()
[08:09:35.732] <TB2> INFO: ----------------------------------------------------------------------
[08:09:35.732] <TB2> INFO: PixTestCmd::init()

[08:11:05.341] <TB2> INFO: TBM phases: 160MHz: 4, 400MHz: 6, TBM delays: ROC(0/1):3, header/trailer: 1, token: 0
[08:11:05.341] <TB2> INFO: (success/tries = 100/100), width = 4
[08:11:05.341] <TB2> INFO: enter test to run
[08:11:05.341] <TB2> INFO: test: pretest no parameter change
[08:11:05.342] <TB2> INFO: running: pretest
[08:11:05.343] <TB2> INFO: ----------------------------------------------------------------------
[08:11:05.343] <TB2> INFO: PixTestPretest::findWorkingPixel()
[08:11:05.343] <TB2> INFO: ----------------------------------------------------------------------
[08:11:05.437] <TB2> INFO: Expecting 231680 events.


[08:11:15.715] <TB2> INFO: 231680 events read in total (9721ms).
[08:11:15.719] <TB2> INFO: Test took 10371ms.
[08:11:15.942] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[08:11:15.997] <TB2> INFO: enter test to run
[08:11:15.997] <TB2> INFO: test: pretest no parameter change
[08:11:15.997] <TB2> INFO: running: pretest
[08:11:15.999] <TB2> INFO: ----------------------------------------------------------------------
[08:11:15.999] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[08:11:15.999] <TB2> INFO: ----------------------------------------------------------------------
[08:11:16.089] <TB2> INFO: Expecting 231680 events.
[08:11:26.243] <TB2> INFO: 231680 events read in total (9597ms).
[08:11:26.247] <TB2> INFO: Test took 10246ms.
[08:11:26.482] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[08:11:26.482] <TB2> INFO: CalDel: 179 184 169 150 177 183 174 149 135 142 146 146 128 152 177 164
[08:11:26.482] <TB2> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[08:11:26.515] <TB2> INFO: enter test to run
[08:11:26.515] <TB2> INFO: test: pretest no parameter change
[08:11:26.515] <TB2> INFO: running: pretest
[08:11:26.516] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C0.dat
[08:11:26.516] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C1.dat
[08:11:26.516] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C2.dat
[08:11:26.516] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C3.dat
[08:11:26.516] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C4.dat
[08:11:26.516] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C5.dat
[08:11:26.516] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C6.dat
[08:11:26.516] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C7.dat
[08:11:26.517] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C8.dat
[08:11:26.517] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C9.dat
[08:11:26.517] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C10.dat
[08:11:26.517] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C11.dat
[08:11:26.517] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C12.dat
[08:11:26.517] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C13.dat
[08:11:26.517] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C14.dat
[08:11:26.517] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C15.dat
[08:11:26.517] <TB2> INFO: enter test to run
[08:11:26.517] <TB2> INFO: test: fulltest no parameter change
[08:11:26.518] <TB2> INFO: running: fulltest
[08:11:26.518] <TB2> INFO: ######################################################################
[08:11:26.518] <TB2> INFO: PixTestFullTest::doTest()
[08:11:26.518] <TB2> INFO: ######################################################################
[08:11:26.519] <TB2> INFO: ######################################################################
[08:11:26.519] <TB2> INFO: PixTestAlive::doTest()
[08:11:26.519] <TB2> INFO: ######################################################################
[08:11:26.520] <TB2> INFO: ----------------------------------------------------------------------
[08:11:26.520] <TB2> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:11:26.520] <TB2> INFO: ----------------------------------------------------------------------
[08:11:26.784] <TB2> INFO: Expecting 41600 events.
[08:11:30.580] <TB2> INFO: 41600 events read in total (3239ms).
[08:11:30.580] <TB2> INFO: Test took 4059ms.
[08:11:30.809] <TB2> INFO: PixTestAlive::aliveTest() done
[08:11:30.809] <TB2> INFO: number of dead pixels (per ROC): 0 0 2 0 0 0 0 0 0 0 0 0 1 0 2 0
[08:11:30.811] <TB2> INFO: ----------------------------------------------------------------------
[08:11:30.811] <TB2> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:11:30.811] <TB2> INFO: ----------------------------------------------------------------------
[08:11:31.079] <TB2> INFO: Expecting 41600 events.
[08:11:34.043] <TB2> INFO: 41600 events read in total (2407ms).
[08:11:34.043] <TB2> INFO: Test took 3230ms.
[08:11:34.043] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:11:34.291] <TB2> INFO: PixTestAlive::maskTest() done
[08:11:34.291] <TB2> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:11:34.293] <TB2> INFO: ----------------------------------------------------------------------
[08:11:34.293] <TB2> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:11:34.293] <TB2> INFO: ----------------------------------------------------------------------
[08:11:34.559] <TB2> INFO: Expecting 41600 events.
[08:11:38.390] <TB2> INFO: 41600 events read in total (3274ms).
[08:11:38.391] <TB2> INFO: Test took 4097ms.
[08:11:38.614] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[08:11:38.614] <TB2> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:11:38.614] <TB2> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[08:11:38.614] <TB2> INFO: Decoding statistics:
[08:11:38.614] <TB2> INFO: General information:
[08:11:38.614] <TB2> INFO: 16bit words read: 0
[08:11:38.614] <TB2> INFO: valid events total: 0
[08:11:38.614] <TB2> INFO: empty events: 0
[08:11:38.614] <TB2> INFO: valid events with pixels: 0
[08:11:38.614] <TB2> INFO: valid pixel hits: 0
[08:11:38.614] <TB2> INFO: Event errors: 0
[08:11:38.614] <TB2> INFO: start marker: 0
[08:11:38.614] <TB2> INFO: stop marker: 0
[08:11:38.614] <TB2> INFO: overflow: 0
[08:11:38.614] <TB2> INFO: invalid 5bit words: 0
[08:11:38.614] <TB2> INFO: invalid XOR eye diagram: 0
[08:11:38.614] <TB2> INFO: frame (failed synchr.): 0
[08:11:38.614] <TB2> INFO: idle data (no TBM trl): 0
[08:11:38.614] <TB2> INFO: no data (only TBM hdr): 0
[08:11:38.614] <TB2> INFO: TBM errors: 0
[08:11:38.614] <TB2> INFO: flawed TBM headers: 0
[08:11:38.614] <TB2> INFO: flawed TBM trailers: 0
[08:11:38.614] <TB2> INFO: event ID mismatches: 0
[08:11:38.614] <TB2> INFO: ROC errors: 0
[08:11:38.614] <TB2> INFO: missing ROC header(s): 0
[08:11:38.614] <TB2> INFO: misplaced readback start: 0
[08:11:38.614] <TB2> INFO: Pixel decoding errors: 0
[08:11:38.614] <TB2> INFO: pixel data incomplete: 0
[08:11:38.614] <TB2> INFO: pixel address: 0
[08:11:38.614] <TB2> INFO: pulse height fill bit: 0
[08:11:38.614] <TB2> INFO: buffer corruption: 0
[08:11:38.623] <TB2> INFO: ######################################################################
[08:11:38.623] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[08:11:38.623] <TB2> INFO: ######################################################################
[08:11:38.626] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[08:11:38.637] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[08:11:38.637] <TB2> INFO: run 1 of 1
[08:11:38.926] <TB2> INFO: Expecting 3120000 events.
[08:12:19.637] <TB2> INFO: 845060 events read in total (40155ms).
[08:13:00.115] <TB2> INFO: 1677495 events read in total (80633ms).
[08:13:40.506] <TB2> INFO: 2520455 events read in total (121024ms).
[08:14:09.026] <TB2> INFO: 3120000 events read in total (149544ms).
[08:14:09.085] <TB2> INFO: Test took 150448ms.
[08:14:34.901] <TB2> INFO: PixTestBBMap::doTest() done, duration: 176 seconds
[08:14:34.901] <TB2> INFO: number of dead bumps (per ROC): 2 0 1 0 0 0 0 0 1 2 0 1 0 0 0 0
[08:14:34.901] <TB2> INFO: separation cut (per ROC): 83 73 83 75 70 76 84 71 91 72 70 72 74 102 70 75
[08:14:34.901] <TB2> INFO: Decoding statistics:
[08:14:34.901] <TB2> INFO: General information:
[08:14:34.901] <TB2> INFO: 16bit words read: 0
[08:14:34.901] <TB2> INFO: valid events total: 0
[08:14:34.901] <TB2> INFO: empty events: 0
[08:14:34.901] <TB2> INFO: valid events with pixels: 0
[08:14:34.901] <TB2> INFO: valid pixel hits: 0
[08:14:34.902] <TB2> INFO: Event errors: 0
[08:14:34.902] <TB2> INFO: start marker: 0
[08:14:34.902] <TB2> INFO: stop marker: 0
[08:14:34.902] <TB2> INFO: overflow: 0
[08:14:34.902] <TB2> INFO: invalid 5bit words: 0
[08:14:34.902] <TB2> INFO: invalid XOR eye diagram: 0
[08:14:34.902] <TB2> INFO: frame (failed synchr.): 0
[08:14:34.902] <TB2> INFO: idle data (no TBM trl): 0
[08:14:34.902] <TB2> INFO: no data (only TBM hdr): 0
[08:14:34.902] <TB2> INFO: TBM errors: 0
[08:14:34.902] <TB2> INFO: flawed TBM headers: 0
[08:14:34.902] <TB2> INFO: flawed TBM trailers: 0
[08:14:34.902] <TB2> INFO: event ID mismatches: 0
[08:14:34.902] <TB2> INFO: ROC errors: 0
[08:14:34.902] <TB2> INFO: missing ROC header(s): 0
[08:14:34.902] <TB2> INFO: misplaced readback start: 0
[08:14:34.902] <TB2> INFO: Pixel decoding errors: 0
[08:14:34.902] <TB2> INFO: pixel data incomplete: 0
[08:14:34.902] <TB2> INFO: pixel address: 0
[08:14:34.902] <TB2> INFO: pulse height fill bit: 0
[08:14:34.902] <TB2> INFO: buffer corruption: 0
[08:14:34.975] <TB2> INFO: ######################################################################
[08:14:34.975] <TB2> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:14:34.975] <TB2> INFO: ######################################################################
[08:14:34.975] <TB2> INFO: ----------------------------------------------------------------------
[08:14:34.975] <TB2> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:14:34.975] <TB2> INFO: ----------------------------------------------------------------------
[08:14:34.975] <TB2> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[08:14:34.983] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[08:14:34.983] <TB2> INFO: run 1 of 1
[08:14:35.250] <TB2> INFO: Expecting 26208000 events.
[08:15:08.712] <TB2> INFO: 883700 events read in total (32906ms).
[08:15:41.447] <TB2> INFO: 1753450 events read in total (65641ms).
[08:16:13.954] <TB2> INFO: 2623050 events read in total (98148ms).
[08:16:47.068] <TB2> INFO: 3492050 events read in total (131262ms).
[08:17:19.721] <TB2> INFO: 4359150 events read in total (163915ms).
[08:17:52.569] <TB2> INFO: 5227150 events read in total (196763ms).
[08:18:24.954] <TB2> INFO: 6093250 events read in total (229148ms).
[08:18:57.675] <TB2> INFO: 6959600 events read in total (261869ms).
[08:19:29.894] <TB2> INFO: 7827200 events read in total (294088ms).
[08:20:02.955] <TB2> INFO: 8693750 events read in total (327149ms).
[08:20:35.515] <TB2> INFO: 9560800 events read in total (359709ms).
[08:21:08.354] <TB2> INFO: 10424950 events read in total (392548ms).
[08:21:41.312] <TB2> INFO: 11290700 events read in total (425506ms).
[08:22:14.171] <TB2> INFO: 12156450 events read in total (458365ms).
[08:22:46.602] <TB2> INFO: 13017750 events read in total (490796ms).
[08:23:19.129] <TB2> INFO: 13873500 events read in total (523323ms).
[08:23:51.702] <TB2> INFO: 14728450 events read in total (555896ms).
[08:24:24.625] <TB2> INFO: 15583100 events read in total (588819ms).
[08:24:57.104] <TB2> INFO: 16434450 events read in total (621298ms).
[08:25:30.002] <TB2> INFO: 17286700 events read in total (654196ms).
[08:26:02.727] <TB2> INFO: 18137700 events read in total (686921ms).
[08:26:34.835] <TB2> INFO: 18990150 events read in total (719029ms).
[08:27:07.851] <TB2> INFO: 19841850 events read in total (752045ms).
[08:27:40.744] <TB2> INFO: 20692250 events read in total (784938ms).
[08:28:13.317] <TB2> INFO: 21541850 events read in total (817511ms).
[08:28:45.974] <TB2> INFO: 22391300 events read in total (850168ms).
[08:29:18.387] <TB2> INFO: 23242050 events read in total (882581ms).
[08:29:51.092] <TB2> INFO: 24090750 events read in total (915286ms).
[08:30:23.310] <TB2> INFO: 24943400 events read in total (947504ms).
[08:30:55.639] <TB2> INFO: 25797450 events read in total (979833ms).
[08:31:11.248] <TB2> INFO: 26208000 events read in total (995442ms).
[08:31:11.280] <TB2> INFO: Test took 996297ms.
[08:31:11.570] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:31:13.214] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:31:14.694] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:31:16.213] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:31:17.741] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:31:19.325] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:31:21.047] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:31:22.547] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:31:24.147] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:31:25.584] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:31:27.006] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:31:28.463] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:31:29.953] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:31:31.484] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:31:32.926] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:31:34.401] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:31:35.912] <TB2> INFO: PixTestScurves::scurves() done
[08:31:35.912] <TB2> INFO: Vcal mean: 92.25 84.79 81.40 80.02 87.88 94.76 91.48 83.54 96.45 84.98 79.75 76.44 76.43 99.16 84.25 74.41
[08:31:35.912] <TB2> INFO: Vcal RMS: 6.03 5.60 4.48 3.99 5.85 6.47 5.68 4.69 5.20 4.66 3.83 4.11 4.44 5.41 5.92 4.49
[08:31:35.912] <TB2> INFO: PixTestScurves::fullTest() done, duration: 1020 seconds
[08:31:35.912] <TB2> INFO: Decoding statistics:
[08:31:35.912] <TB2> INFO: General information:
[08:31:35.912] <TB2> INFO: 16bit words read: 0
[08:31:35.912] <TB2> INFO: valid events total: 0
[08:31:35.912] <TB2> INFO: empty events: 0
[08:31:35.912] <TB2> INFO: valid events with pixels: 0
[08:31:35.912] <TB2> INFO: valid pixel hits: 0
[08:31:35.912] <TB2> INFO: Event errors: 0
[08:31:35.912] <TB2> INFO: start marker: 0
[08:31:35.912] <TB2> INFO: stop marker: 0
[08:31:35.912] <TB2> INFO: overflow: 0
[08:31:35.912] <TB2> INFO: invalid 5bit words: 0
[08:31:35.912] <TB2> INFO: invalid XOR eye diagram: 0
[08:31:35.912] <TB2> INFO: frame (failed synchr.): 0
[08:31:35.912] <TB2> INFO: idle data (no TBM trl): 0
[08:31:35.912] <TB2> INFO: no data (only TBM hdr): 0
[08:31:35.912] <TB2> INFO: TBM errors: 0
[08:31:35.912] <TB2> INFO: flawed TBM headers: 0
[08:31:35.912] <TB2> INFO: flawed TBM trailers: 0
[08:31:35.912] <TB2> INFO: event ID mismatches: 0
[08:31:35.912] <TB2> INFO: ROC errors: 0
[08:31:35.912] <TB2> INFO: missing ROC header(s): 0
[08:31:35.912] <TB2> INFO: misplaced readback start: 0
[08:31:35.912] <TB2> INFO: Pixel decoding errors: 0
[08:31:35.912] <TB2> INFO: pixel data incomplete: 0
[08:31:35.912] <TB2> INFO: pixel address: 0
[08:31:35.912] <TB2> INFO: pulse height fill bit: 0
[08:31:35.912] <TB2> INFO: buffer corruption: 0
[08:31:35.992] <TB2> INFO: ######################################################################
[08:31:35.992] <TB2> INFO: PixTestTrim::doTest()
[08:31:35.992] <TB2> INFO: ######################################################################
[08:31:35.993] <TB2> INFO: ----------------------------------------------------------------------
[08:31:35.993] <TB2> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[08:31:35.993] <TB2> INFO: ----------------------------------------------------------------------
[08:31:36.072] <TB2> INFO: ---> VthrComp thr map (minimal VthrComp)
[08:31:36.072] <TB2> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[08:31:36.080] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:31:36.080] <TB2> INFO: run 1 of 1
[08:31:36.341] <TB2> INFO: Expecting 6281600 events.
[08:32:16.266] <TB2> INFO: 1073970 events read in total (39366ms).
[08:32:54.894] <TB2> INFO: 2141290 events read in total (77994ms).
[08:33:33.790] <TB2> INFO: 3201040 events read in total (116890ms).
[08:34:12.675] <TB2> INFO: 4266910 events read in total (155775ms).
[08:34:51.846] <TB2> INFO: 5337410 events read in total (194946ms).
[08:35:25.956] <TB2> INFO: 6281600 events read in total (229056ms).
[08:35:25.986] <TB2> INFO: Test took 229906ms.
[08:35:44.668] <TB2> INFO: ROC 0 VthrComp = 94
[08:35:44.668] <TB2> INFO: ROC 1 VthrComp = 87
[08:35:44.669] <TB2> INFO: ROC 2 VthrComp = 86
[08:35:44.669] <TB2> INFO: ROC 3 VthrComp = 89
[08:35:44.669] <TB2> INFO: ROC 4 VthrComp = 86
[08:35:44.669] <TB2> INFO: ROC 5 VthrComp = 92
[08:35:44.669] <TB2> INFO: ROC 6 VthrComp = 97
[08:35:44.669] <TB2> INFO: ROC 7 VthrComp = 86
[08:35:44.669] <TB2> INFO: ROC 8 VthrComp = 101
[08:35:44.669] <TB2> INFO: ROC 9 VthrComp = 88
[08:35:44.669] <TB2> INFO: ROC 10 VthrComp = 86
[08:35:44.669] <TB2> INFO: ROC 11 VthrComp = 85
[08:35:44.669] <TB2> INFO: ROC 12 VthrComp = 84
[08:35:44.670] <TB2> INFO: ROC 13 VthrComp = 103
[08:35:44.670] <TB2> INFO: ROC 14 VthrComp = 85
[08:35:44.670] <TB2> INFO: ROC 15 VthrComp = 81
[08:35:44.670] <TB2> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[08:35:44.670] <TB2> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[08:35:44.679] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:35:44.679] <TB2> INFO: run 1 of 1
[08:35:44.940] <TB2> INFO: Expecting 6281600 events.
[08:36:20.367] <TB2> INFO: 753870 events read in total (34871ms).
[08:36:55.528] <TB2> INFO: 1506160 events read in total (70032ms).
[08:37:30.478] <TB2> INFO: 2259470 events read in total (104982ms).
[08:38:05.540] <TB2> INFO: 3013110 events read in total (140044ms).
[08:38:40.653] <TB2> INFO: 3761810 events read in total (175157ms).
[08:39:15.807] <TB2> INFO: 4506910 events read in total (210311ms).
[08:39:50.854] <TB2> INFO: 5250630 events read in total (245358ms).
[08:40:25.935] <TB2> INFO: 5993950 events read in total (280439ms).
[08:40:39.716] <TB2> INFO: 6281600 events read in total (294220ms).
[08:40:39.757] <TB2> INFO: Test took 295077ms.
[08:41:03.291] <TB2> INFO: roc 0 with ID = 0 has maximal Vcal 61.5886 for pixel 16/8 mean/min/max = 46.5971/31.5869/61.6073
[08:41:03.291] <TB2> INFO: roc 1 with ID = 1 has maximal Vcal 61.1651 for pixel 17/3 mean/min/max = 45.9928/30.6521/61.3336
[08:41:03.292] <TB2> INFO: roc 2 with ID = 2 has maximal Vcal 56.9058 for pixel 25/10 mean/min/max = 44.8435/32.724/56.963
[08:41:03.292] <TB2> INFO: roc 3 with ID = 3 has maximal Vcal 55.889 for pixel 0/45 mean/min/max = 44.3508/32.5092/56.1924
[08:41:03.292] <TB2> INFO: roc 4 with ID = 4 has maximal Vcal 63.3908 for pixel 24/7 mean/min/max = 47.6534/31.7738/63.533
[08:41:03.292] <TB2> INFO: roc 5 with ID = 5 has maximal Vcal 66.9393 for pixel 24/3 mean/min/max = 49.3423/31.3061/67.3785
[08:41:03.293] <TB2> INFO: roc 6 with ID = 6 has maximal Vcal 60.4729 for pixel 0/16 mean/min/max = 45.837/31.1985/60.4754
[08:41:03.293] <TB2> INFO: roc 7 with ID = 7 has maximal Vcal 58.1703 for pixel 3/2 mean/min/max = 45.2432/32.1406/58.3459
[08:41:03.293] <TB2> INFO: roc 8 with ID = 8 has maximal Vcal 57.9327 for pixel 16/12 mean/min/max = 44.8905/31.7932/57.9879
[08:41:03.293] <TB2> INFO: roc 9 with ID = 9 has maximal Vcal 58.1921 for pixel 23/2 mean/min/max = 45.8759/33.3444/58.4075
[08:41:03.294] <TB2> INFO: roc 10 with ID = 10 has maximal Vcal 54.9207 for pixel 17/1 mean/min/max = 43.4203/31.7282/55.1125
[08:41:03.294] <TB2> INFO: roc 11 with ID = 11 has maximal Vcal 56.0503 for pixel 9/6 mean/min/max = 43.9892/31.5178/56.4606
[08:41:03.294] <TB2> INFO: roc 12 with ID = 12 has maximal Vcal 56.6136 for pixel 25/2 mean/min/max = 44.4888/32.3052/56.6724
[08:41:03.294] <TB2> INFO: roc 13 with ID = 13 has maximal Vcal 58.0479 for pixel 0/25 mean/min/max = 45.5793/32.6212/58.5374
[08:41:03.294] <TB2> INFO: roc 14 with ID = 14 has maximal Vcal 61.7806 for pixel 22/1 mean/min/max = 46.6077/31.3789/61.8365
[08:41:03.295] <TB2> INFO: roc 15 with ID = 15 has maximal Vcal 56.2748 for pixel 5/78 mean/min/max = 44.7884/32.8426/56.7342
[08:41:03.295] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:41:03.383] <TB2> INFO: Expecting 514560 events.
[08:41:16.198] <TB2> INFO: 514560 events read in total (12259ms).
[08:41:16.203] <TB2> INFO: Expecting 514560 events.
[08:41:28.866] <TB2> INFO: 514560 events read in total (12239ms).
[08:41:28.873] <TB2> INFO: Expecting 514560 events.
[08:41:41.694] <TB2> INFO: 514560 events read in total (12406ms).
[08:41:41.702] <TB2> INFO: Expecting 514560 events.
[08:41:54.548] <TB2> INFO: 514560 events read in total (12435ms).
[08:41:54.560] <TB2> INFO: Expecting 514560 events.
[08:42:07.402] <TB2> INFO: 514560 events read in total (12427ms).
[08:42:07.414] <TB2> INFO: Expecting 514560 events.
[08:42:20.052] <TB2> INFO: 514560 events read in total (12224ms).
[08:42:20.066] <TB2> INFO: Expecting 514560 events.
[08:42:32.743] <TB2> INFO: 514560 events read in total (12262ms).
[08:42:32.762] <TB2> INFO: Expecting 514560 events.
[08:42:45.505] <TB2> INFO: 514560 events read in total (12336ms).
[08:42:45.523] <TB2> INFO: Expecting 514560 events.
[08:42:58.206] <TB2> INFO: 514560 events read in total (12271ms).
[08:42:58.225] <TB2> INFO: Expecting 514560 events.
[08:43:11.004] <TB2> INFO: 514560 events read in total (12370ms).
[08:43:11.024] <TB2> INFO: Expecting 514560 events.
[08:43:23.718] <TB2> INFO: 514560 events read in total (12284ms).
[08:43:23.740] <TB2> INFO: Expecting 514560 events.
[08:43:36.501] <TB2> INFO: 514560 events read in total (12359ms).
[08:43:36.526] <TB2> INFO: Expecting 514560 events.
[08:43:49.411] <TB2> INFO: 514560 events read in total (12483ms).
[08:43:49.438] <TB2> INFO: Expecting 514560 events.
[08:44:02.269] <TB2> INFO: 514560 events read in total (12430ms).
[08:44:02.297] <TB2> INFO: Expecting 514560 events.
[08:44:15.099] <TB2> INFO: 514560 events read in total (12401ms).
[08:44:15.143] <TB2> INFO: Expecting 514560 events.
[08:44:28.020] <TB2> INFO: 514560 events read in total (12475ms).
[08:44:28.059] <TB2> INFO: Test took 204764ms.
[08:44:29.136] <TB2> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[08:44:29.144] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:44:29.144] <TB2> INFO: run 1 of 1
[08:44:29.429] <TB2> INFO: Expecting 6281600 events.
[08:45:05.002] <TB2> INFO: 741260 events read in total (35017ms).
[08:45:39.894] <TB2> INFO: 1481980 events read in total (69909ms).
[08:46:14.345] <TB2> INFO: 2223300 events read in total (104360ms).
[08:46:49.344] <TB2> INFO: 2964840 events read in total (139359ms).
[08:47:23.873] <TB2> INFO: 3700920 events read in total (173888ms).
[08:47:58.087] <TB2> INFO: 4433050 events read in total (208102ms).
[08:48:32.882] <TB2> INFO: 5163900 events read in total (242897ms).
[08:49:07.367] <TB2> INFO: 5894630 events read in total (277382ms).
[08:49:25.670] <TB2> INFO: 6281600 events read in total (295685ms).
[08:49:25.712] <TB2> INFO: Test took 296567ms.
[08:49:49.030] <TB2> INFO: ---> TrimStepCorr4 extremal thresholds: 0.180094 .. 255.000000
[08:49:49.105] <TB2> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[08:49:49.113] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:49:49.113] <TB2> INFO: run 1 of 1
[08:49:49.373] <TB2> INFO: Expecting 10649600 events.
[08:50:24.147] <TB2> INFO: 711950 events read in total (34218ms).
[08:50:58.623] <TB2> INFO: 1424130 events read in total (68694ms).
[08:51:32.832] <TB2> INFO: 2136390 events read in total (102903ms).
[08:52:06.876] <TB2> INFO: 2848630 events read in total (136947ms).
[08:52:41.034] <TB2> INFO: 3561090 events read in total (171105ms).
[08:53:15.026] <TB2> INFO: 4273510 events read in total (205097ms).
[08:53:49.234] <TB2> INFO: 4985860 events read in total (239305ms).
[08:54:23.451] <TB2> INFO: 5697860 events read in total (273522ms).
[08:54:57.245] <TB2> INFO: 6409480 events read in total (307316ms).
[08:55:31.497] <TB2> INFO: 7120500 events read in total (341568ms).
[08:56:05.812] <TB2> INFO: 7831490 events read in total (375883ms).
[08:56:39.924] <TB2> INFO: 8541810 events read in total (409995ms).
[08:57:13.746] <TB2> INFO: 9252540 events read in total (443817ms).
[08:57:47.972] <TB2> INFO: 9963080 events read in total (478043ms).
[08:58:20.774] <TB2> INFO: 10649600 events read in total (510845ms).
[08:58:20.891] <TB2> INFO: Test took 511778ms.
[08:58:49.533] <TB2> INFO: ---> TrimStepCorr2 extremal thresholds: 13.300861 .. 48.151536
[08:58:49.610] <TB2> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 3 .. 58 (-1/-1) hits flags = 528 (plus default)
[08:58:49.618] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:58:49.618] <TB2> INFO: run 1 of 1
[08:58:49.884] <TB2> INFO: Expecting 2329600 events.
[08:59:27.744] <TB2> INFO: 925810 events read in total (37304ms).
[09:00:04.879] <TB2> INFO: 1851150 events read in total (74439ms).
[09:00:24.349] <TB2> INFO: 2329600 events read in total (93910ms).
[09:00:24.366] <TB2> INFO: Test took 94748ms.
[09:00:38.503] <TB2> INFO: ---> TrimStepCorr1a extremal thresholds: 19.044345 .. 48.151536
[09:00:38.578] <TB2> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 9 .. 58 (-1/-1) hits flags = 528 (plus default)
[09:00:38.587] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[09:00:38.587] <TB2> INFO: run 1 of 1
[09:00:38.847] <TB2> INFO: Expecting 2080000 events.
[09:01:16.710] <TB2> INFO: 897790 events read in total (37307ms).
[09:01:54.111] <TB2> INFO: 1794750 events read in total (74709ms).
[09:02:06.096] <TB2> INFO: 2080000 events read in total (86694ms).
[09:02:06.117] <TB2> INFO: Test took 87530ms.
[09:02:20.074] <TB2> INFO: ---> TrimStepCorr1b extremal thresholds: 2.798316 .. 48.151536
[09:02:20.149] <TB2> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 2 .. 58 (-1/-1) hits flags = 528 (plus default)
[09:02:20.157] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[09:02:20.157] <TB2> INFO: run 1 of 1
[09:02:20.416] <TB2> INFO: Expecting 2371200 events.
[09:02:59.342] <TB2> INFO: 934410 events read in total (38370ms).
[09:03:37.533] <TB2> INFO: 1868850 events read in total (76561ms).
[09:03:58.054] <TB2> INFO: 2371200 events read in total (97082ms).
[09:03:58.075] <TB2> INFO: Test took 97918ms.
[09:04:12.566] <TB2> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[09:04:12.566] <TB2> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[09:04:12.574] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[09:04:12.574] <TB2> INFO: run 1 of 1
[09:04:12.834] <TB2> INFO: Expecting 1705600 events.
[09:04:50.728] <TB2> INFO: 878850 events read in total (37338ms).
[09:05:25.971] <TB2> INFO: 1705600 events read in total (72582ms).
[09:05:25.991] <TB2> INFO: Test took 73418ms.
[09:05:40.409] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:05:40.410] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:05:40.410] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:05:40.410] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:05:40.410] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:05:40.410] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:05:40.410] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:05:40.410] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:05:40.410] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:05:40.410] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:05:40.410] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:05:40.411] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:05:40.411] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:05:40.411] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:05:40.411] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:05:40.411] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:05:40.411] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C0.dat
[09:05:40.418] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C1.dat
[09:05:40.425] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C2.dat
[09:05:40.431] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C3.dat
[09:05:40.437] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C4.dat
[09:05:40.443] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C5.dat
[09:05:40.449] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C6.dat
[09:05:40.456] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C7.dat
[09:05:40.463] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C8.dat
[09:05:40.469] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C9.dat
[09:05:40.475] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C10.dat
[09:05:40.482] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C11.dat
[09:05:40.489] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C12.dat
[09:05:40.495] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C13.dat
[09:05:40.501] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C14.dat
[09:05:40.507] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C15.dat
[09:05:40.513] <TB2> INFO: PixTestTrim::trimTest() done
[09:05:40.513] <TB2> INFO: vtrim: 95 75 93 82 102 109 79 89 99 97 84 97 91 103 89 88
[09:05:40.513] <TB2> INFO: vthrcomp: 94 87 86 89 86 92 97 86 101 88 86 85 84 103 85 81
[09:05:40.513] <TB2> INFO: vcal mean: 34.96 34.94 34.97 34.94 35.00 34.99 34.95 35.00 34.95 35.02 34.96 34.94 34.98 34.93 34.94 34.98
[09:05:40.513] <TB2> INFO: vcal RMS: 0.91 0.85 0.98 0.83 0.91 1.15 0.90 0.95 0.83 0.82 0.82 0.80 0.94 0.82 1.17 0.74
[09:05:40.513] <TB2> INFO: bits mean: 9.63 9.29 9.67 10.06 9.35 9.20 9.34 9.47 9.78 9.11 10.23 10.09 9.31 8.74 9.52 9.11
[09:05:40.513] <TB2> INFO: bits RMS: 2.59 2.92 2.56 2.44 2.57 2.61 2.85 2.67 2.60 2.63 2.52 2.58 2.78 2.92 2.66 2.78
[09:05:40.520] <TB2> INFO: ----------------------------------------------------------------------
[09:05:40.520] <TB2> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[09:05:40.520] <TB2> INFO: ----------------------------------------------------------------------
[09:05:40.522] <TB2> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[09:05:40.530] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[09:05:40.530] <TB2> INFO: run 1 of 1
[09:05:40.793] <TB2> INFO: Expecting 4160000 events.
[09:06:22.677] <TB2> INFO: 913560 events read in total (41328ms).
[09:07:03.993] <TB2> INFO: 1821595 events read in total (82644ms).
[09:07:44.804] <TB2> INFO: 2721695 events read in total (123456ms).
[09:08:25.625] <TB2> INFO: 3618450 events read in total (164276ms).
[09:08:50.187] <TB2> INFO: 4160000 events read in total (188838ms).
[09:08:50.226] <TB2> INFO: Test took 189697ms.
[09:09:19.256] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 156 (-1/-1) hits flags = 528 (plus default)
[09:09:19.264] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[09:09:19.264] <TB2> INFO: run 1 of 1
[09:09:19.525] <TB2> INFO: Expecting 3265600 events.
[09:10:02.571] <TB2> INFO: 971485 events read in total (42490ms).
[09:10:44.672] <TB2> INFO: 1932455 events read in total (84591ms).
[09:11:27.149] <TB2> INFO: 2883270 events read in total (127068ms).
[09:11:43.997] <TB2> INFO: 3265600 events read in total (143916ms).
[09:11:44.031] <TB2> INFO: Test took 144767ms.
[09:12:08.488] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 146 (-1/-1) hits flags = 528 (plus default)
[09:12:08.497] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[09:12:08.497] <TB2> INFO: run 1 of 1
[09:12:08.760] <TB2> INFO: Expecting 3057600 events.
[09:12:52.636] <TB2> INFO: 1001065 events read in total (43320ms).
[09:13:35.850] <TB2> INFO: 1988680 events read in total (86534ms).
[09:14:19.034] <TB2> INFO: 2967590 events read in total (129718ms).
[09:14:23.376] <TB2> INFO: 3057600 events read in total (134060ms).
[09:14:23.412] <TB2> INFO: Test took 134915ms.
[09:14:49.141] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 147 (-1/-1) hits flags = 528 (plus default)
[09:14:49.149] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[09:14:49.149] <TB2> INFO: run 1 of 1
[09:14:49.437] <TB2> INFO: Expecting 3078400 events.
[09:15:32.870] <TB2> INFO: 995855 events read in total (42877ms).
[09:16:15.720] <TB2> INFO: 1978780 events read in total (85727ms).
[09:16:58.858] <TB2> INFO: 2953135 events read in total (128865ms).
[09:17:04.818] <TB2> INFO: 3078400 events read in total (134825ms).
[09:17:04.855] <TB2> INFO: Test took 135706ms.
[09:17:27.772] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 145 (-1/-1) hits flags = 528 (plus default)
[09:17:27.781] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[09:17:27.781] <TB2> INFO: run 1 of 1
[09:17:28.052] <TB2> INFO: Expecting 3036800 events.
[09:18:11.112] <TB2> INFO: 1002755 events read in total (42504ms).
[09:18:53.655] <TB2> INFO: 1991800 events read in total (85048ms).
[09:19:36.435] <TB2> INFO: 2972425 events read in total (127828ms).
[09:19:39.703] <TB2> INFO: 3036800 events read in total (131095ms).
[09:19:39.731] <TB2> INFO: Test took 131950ms.
[09:20:04.010] <TB2> INFO: PixTestTrim::trimBitTest() done
[09:20:04.011] <TB2> INFO: PixTestTrim::doTest() done, duration: 2908 seconds
[09:20:04.011] <TB2> INFO: Decoding statistics:
[09:20:04.011] <TB2> INFO: General information:
[09:20:04.011] <TB2> INFO: 16bit words read: 0
[09:20:04.011] <TB2> INFO: valid events total: 0
[09:20:04.011] <TB2> INFO: empty events: 0
[09:20:04.011] <TB2> INFO: valid events with pixels: 0
[09:20:04.011] <TB2> INFO: valid pixel hits: 0
[09:20:04.011] <TB2> INFO: Event errors: 0
[09:20:04.011] <TB2> INFO: start marker: 0
[09:20:04.011] <TB2> INFO: stop marker: 0
[09:20:04.011] <TB2> INFO: overflow: 0
[09:20:04.011] <TB2> INFO: invalid 5bit words: 0
[09:20:04.011] <TB2> INFO: invalid XOR eye diagram: 0
[09:20:04.011] <TB2> INFO: frame (failed synchr.): 0
[09:20:04.011] <TB2> INFO: idle data (no TBM trl): 0
[09:20:04.011] <TB2> INFO: no data (only TBM hdr): 0
[09:20:04.011] <TB2> INFO: TBM errors: 0
[09:20:04.011] <TB2> INFO: flawed TBM headers: 0
[09:20:04.011] <TB2> INFO: flawed TBM trailers: 0
[09:20:04.011] <TB2> INFO: event ID mismatches: 0
[09:20:04.011] <TB2> INFO: ROC errors: 0
[09:20:04.011] <TB2> INFO: missing ROC header(s): 0
[09:20:04.011] <TB2> INFO: misplaced readback start: 0
[09:20:04.011] <TB2> INFO: Pixel decoding errors: 0
[09:20:04.011] <TB2> INFO: pixel data incomplete: 0
[09:20:04.011] <TB2> INFO: pixel address: 0
[09:20:04.011] <TB2> INFO: pulse height fill bit: 0
[09:20:04.011] <TB2> INFO: buffer corruption: 0
[09:20:04.700] <TB2> INFO: ######################################################################
[09:20:04.700] <TB2> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[09:20:04.700] <TB2> INFO: ######################################################################
[09:20:04.993] <TB2> INFO: Expecting 41600 events.
[09:20:08.774] <TB2> INFO: 41600 events read in total (3225ms).
[09:20:08.774] <TB2> INFO: Test took 4073ms.
[09:20:09.270] <TB2> INFO: Expecting 41600 events.
[09:20:13.044] <TB2> INFO: 41600 events read in total (3218ms).
[09:20:13.045] <TB2> INFO: Test took 4069ms.
[09:20:13.355] <TB2> INFO: Expecting 41600 events.
[09:20:17.129] <TB2> INFO: 41600 events read in total (3218ms).
[09:20:17.130] <TB2> INFO: Test took 4064ms.
[09:20:17.384] <TB2> INFO: Expecting 2560 events.
[09:20:18.243] <TB2> INFO: 2560 events read in total (303ms).
[09:20:18.244] <TB2> INFO: Test took 1102ms.
[09:20:18.549] <TB2> INFO: Expecting 2560 events.
[09:20:19.404] <TB2> INFO: 2560 events read in total (299ms).
[09:20:19.404] <TB2> INFO: Test took 1159ms.
[09:20:19.709] <TB2> INFO: Expecting 2560 events.
[09:20:20.562] <TB2> INFO: 2560 events read in total (297ms).
[09:20:20.563] <TB2> INFO: Test took 1159ms.
[09:20:20.867] <TB2> INFO: Expecting 2560 events.
[09:20:21.724] <TB2> INFO: 2560 events read in total (301ms).
[09:20:21.724] <TB2> INFO: Test took 1161ms.
[09:20:22.030] <TB2> INFO: Expecting 2560 events.
[09:20:22.887] <TB2> INFO: 2560 events read in total (301ms).
[09:20:22.888] <TB2> INFO: Test took 1164ms.
[09:20:23.193] <TB2> INFO: Expecting 2560 events.
[09:20:24.049] <TB2> INFO: 2560 events read in total (300ms).
[09:20:24.050] <TB2> INFO: Test took 1162ms.
[09:20:24.355] <TB2> INFO: Expecting 2560 events.
[09:20:25.209] <TB2> INFO: 2560 events read in total (297ms).
[09:20:25.210] <TB2> INFO: Test took 1160ms.
[09:20:25.514] <TB2> INFO: Expecting 2560 events.
[09:20:26.369] <TB2> INFO: 2560 events read in total (299ms).
[09:20:26.370] <TB2> INFO: Test took 1160ms.
[09:20:26.675] <TB2> INFO: Expecting 2560 events.
[09:20:27.533] <TB2> INFO: 2560 events read in total (302ms).
[09:20:27.534] <TB2> INFO: Test took 1164ms.
[09:20:27.839] <TB2> INFO: Expecting 2560 events.
[09:20:28.694] <TB2> INFO: 2560 events read in total (299ms).
[09:20:28.694] <TB2> INFO: Test took 1160ms.
[09:20:28.999] <TB2> INFO: Expecting 2560 events.
[09:20:29.854] <TB2> INFO: 2560 events read in total (299ms).
[09:20:29.854] <TB2> INFO: Test took 1160ms.
[09:20:30.159] <TB2> INFO: Expecting 2560 events.
[09:20:31.018] <TB2> INFO: 2560 events read in total (302ms).
[09:20:31.018] <TB2> INFO: Test took 1163ms.
[09:20:31.323] <TB2> INFO: Expecting 2560 events.
[09:20:32.183] <TB2> INFO: 2560 events read in total (304ms).
[09:20:32.183] <TB2> INFO: Test took 1165ms.
[09:20:32.488] <TB2> INFO: Expecting 2560 events.
[09:20:33.346] <TB2> INFO: 2560 events read in total (302ms).
[09:20:33.346] <TB2> INFO: Test took 1163ms.
[09:20:33.651] <TB2> INFO: Expecting 2560 events.
[09:20:34.504] <TB2> INFO: 2560 events read in total (297ms).
[09:20:34.504] <TB2> INFO: Test took 1158ms.
[09:20:34.809] <TB2> INFO: Expecting 2560 events.
[09:20:35.666] <TB2> INFO: 2560 events read in total (301ms).
[09:20:35.666] <TB2> INFO: Test took 1161ms.
[09:20:35.670] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:20:35.973] <TB2> INFO: Expecting 655360 events.
[09:20:52.955] <TB2> INFO: 655360 events read in total (16426ms).
[09:20:52.964] <TB2> INFO: Expecting 655360 events.
[09:21:09.682] <TB2> INFO: 655360 events read in total (16317ms).
[09:21:09.695] <TB2> INFO: Expecting 655360 events.
[09:21:26.447] <TB2> INFO: 655360 events read in total (16351ms).
[09:21:26.463] <TB2> INFO: Expecting 655360 events.
[09:21:43.277] <TB2> INFO: 655360 events read in total (16412ms).
[09:21:43.299] <TB2> INFO: Expecting 655360 events.
[09:22:00.000] <TB2> INFO: 655360 events read in total (16299ms).
[09:22:00.021] <TB2> INFO: Expecting 655360 events.
[09:22:16.750] <TB2> INFO: 655360 events read in total (16327ms).
[09:22:16.775] <TB2> INFO: Expecting 655360 events.
[09:22:33.472] <TB2> INFO: 655360 events read in total (16295ms).
[09:22:33.501] <TB2> INFO: Expecting 655360 events.
[09:22:50.378] <TB2> INFO: 655360 events read in total (16475ms).
[09:22:50.412] <TB2> INFO: Expecting 655360 events.
[09:23:07.122] <TB2> INFO: 655360 events read in total (16309ms).
[09:23:07.158] <TB2> INFO: Expecting 655360 events.
[09:23:23.890] <TB2> INFO: 655360 events read in total (16330ms).
[09:23:23.934] <TB2> INFO: Expecting 655360 events.
[09:23:40.730] <TB2> INFO: 655360 events read in total (16394ms).
[09:23:40.774] <TB2> INFO: Expecting 655360 events.
[09:23:57.645] <TB2> INFO: 655360 events read in total (16470ms).
[09:23:57.693] <TB2> INFO: Expecting 655360 events.
[09:24:14.454] <TB2> INFO: 655360 events read in total (16359ms).
[09:24:14.504] <TB2> INFO: Expecting 655360 events.
[09:24:31.401] <TB2> INFO: 655360 events read in total (16496ms).
[09:24:31.456] <TB2> INFO: Expecting 655360 events.
[09:24:48.195] <TB2> INFO: 655360 events read in total (16337ms).
[09:24:48.252] <TB2> INFO: Expecting 655360 events.
[09:25:05.068] <TB2> INFO: 655360 events read in total (16415ms).
[09:25:05.130] <TB2> INFO: Test took 269460ms.
[09:25:05.209] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:25:05.440] <TB2> INFO: Expecting 655360 events.
[09:25:22.297] <TB2> INFO: 655360 events read in total (16300ms).
[09:25:22.305] <TB2> INFO: Expecting 655360 events.
[09:25:39.116] <TB2> INFO: 655360 events read in total (16409ms).
[09:25:39.129] <TB2> INFO: Expecting 655360 events.
[09:25:56.000] <TB2> INFO: 655360 events read in total (16469ms).
[09:25:56.016] <TB2> INFO: Expecting 655360 events.
[09:26:12.883] <TB2> INFO: 655360 events read in total (16465ms).
[09:26:12.906] <TB2> INFO: Expecting 655360 events.
[09:26:29.633] <TB2> INFO: 655360 events read in total (16325ms).
[09:26:29.658] <TB2> INFO: Expecting 655360 events.
[09:26:46.372] <TB2> INFO: 655360 events read in total (16312ms).
[09:26:46.401] <TB2> INFO: Expecting 655360 events.
[09:27:03.179] <TB2> INFO: 655360 events read in total (16376ms).
[09:27:03.216] <TB2> INFO: Expecting 655360 events.
[09:27:20.109] <TB2> INFO: 655360 events read in total (16492ms).
[09:27:20.146] <TB2> INFO: Expecting 655360 events.
[09:27:36.975] <TB2> INFO: 655360 events read in total (16427ms).
[09:27:37.015] <TB2> INFO: Expecting 655360 events.
[09:27:53.973] <TB2> INFO: 655360 events read in total (16556ms).
[09:27:54.017] <TB2> INFO: Expecting 655360 events.
[09:28:10.767] <TB2> INFO: 655360 events read in total (16349ms).
[09:28:10.810] <TB2> INFO: Expecting 655360 events.
[09:28:27.617] <TB2> INFO: 655360 events read in total (16405ms).
[09:28:27.664] <TB2> INFO: Expecting 655360 events.
[09:28:44.456] <TB2> INFO: 655360 events read in total (16390ms).
[09:28:44.506] <TB2> INFO: Expecting 655360 events.
[09:29:01.129] <TB2> INFO: 655360 events read in total (16221ms).
[09:29:01.187] <TB2> INFO: Expecting 655360 events.
[09:29:17.864] <TB2> INFO: 655360 events read in total (16275ms).
[09:29:17.925] <TB2> INFO: Expecting 655360 events.
[09:29:34.646] <TB2> INFO: 655360 events read in total (16320ms).
[09:29:34.714] <TB2> INFO: Test took 269505ms.
[09:29:34.917] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:34.924] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:34.931] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:34.938] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:34.946] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:34.953] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[09:29:34.960] <TB2> INFO: safety margin for low PH: adding 2, margin is now 22
[09:29:34.969] <TB2> INFO: safety margin for low PH: adding 3, margin is now 23
[09:29:34.975] <TB2> INFO: safety margin for low PH: adding 4, margin is now 24
[09:29:34.982] <TB2> INFO: safety margin for low PH: adding 5, margin is now 25
[09:29:34.990] <TB2> INFO: safety margin for low PH: adding 6, margin is now 26
[09:29:34.998] <TB2> INFO: safety margin for low PH: adding 7, margin is now 27
[09:29:35.006] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:35.014] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:35.022] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:35.029] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:35.038] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:35.046] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:35.053] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:35.061] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[09:29:35.069] <TB2> INFO: safety margin for low PH: adding 2, margin is now 22
[09:29:35.077] <TB2> INFO: safety margin for low PH: adding 3, margin is now 23
[09:29:35.085] <TB2> INFO: safety margin for low PH: adding 4, margin is now 24
[09:29:35.094] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:35.102] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:35.110] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:35.118] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:35.153] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:29:35.153] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:29:35.154] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:29:35.154] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:29:35.154] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:29:35.154] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:29:35.154] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:29:35.154] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:29:35.154] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:29:35.154] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:29:35.155] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:29:35.155] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:29:35.155] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:29:35.155] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:29:35.155] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:29:35.155] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:29:35.424] <TB2> INFO: Expecting 41600 events.
[09:29:38.895] <TB2> INFO: 41600 events read in total (2915ms).
[09:29:38.895] <TB2> INFO: Test took 3737ms.
[09:29:39.356] <TB2> INFO: Expecting 41600 events.
[09:29:42.823] <TB2> INFO: 41600 events read in total (2911ms).
[09:29:42.824] <TB2> INFO: Test took 3737ms.
[09:29:43.267] <TB2> INFO: Expecting 41600 events.
[09:29:46.778] <TB2> INFO: 41600 events read in total (2955ms).
[09:29:46.779] <TB2> INFO: Test took 3779ms.
[09:29:46.959] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:47.046] <TB2> INFO: Expecting 2560 events.
[09:29:47.904] <TB2> INFO: 2560 events read in total (302ms).
[09:29:47.904] <TB2> INFO: Test took 945ms.
[09:29:47.907] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:48.210] <TB2> INFO: Expecting 2560 events.
[09:29:49.068] <TB2> INFO: 2560 events read in total (302ms).
[09:29:49.068] <TB2> INFO: Test took 1162ms.
[09:29:49.070] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:49.373] <TB2> INFO: Expecting 2560 events.
[09:29:50.226] <TB2> INFO: 2560 events read in total (296ms).
[09:29:50.227] <TB2> INFO: Test took 1157ms.
[09:29:50.228] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:50.532] <TB2> INFO: Expecting 2560 events.
[09:29:51.388] <TB2> INFO: 2560 events read in total (300ms).
[09:29:51.388] <TB2> INFO: Test took 1160ms.
[09:29:51.390] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:51.694] <TB2> INFO: Expecting 2560 events.
[09:29:52.549] <TB2> INFO: 2560 events read in total (299ms).
[09:29:52.549] <TB2> INFO: Test took 1159ms.
[09:29:52.550] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:52.855] <TB2> INFO: Expecting 2560 events.
[09:29:53.713] <TB2> INFO: 2560 events read in total (301ms).
[09:29:53.714] <TB2> INFO: Test took 1164ms.
[09:29:53.715] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:54.020] <TB2> INFO: Expecting 2560 events.
[09:29:54.872] <TB2> INFO: 2560 events read in total (296ms).
[09:29:54.873] <TB2> INFO: Test took 1158ms.
[09:29:54.874] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:55.178] <TB2> INFO: Expecting 2560 events.
[09:29:56.036] <TB2> INFO: 2560 events read in total (301ms).
[09:29:56.036] <TB2> INFO: Test took 1162ms.
[09:29:56.038] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:56.341] <TB2> INFO: Expecting 2560 events.
[09:29:57.195] <TB2> INFO: 2560 events read in total (298ms).
[09:29:57.196] <TB2> INFO: Test took 1158ms.
[09:29:57.198] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:57.501] <TB2> INFO: Expecting 2560 events.
[09:29:58.357] <TB2> INFO: 2560 events read in total (300ms).
[09:29:58.357] <TB2> INFO: Test took 1159ms.
[09:29:58.359] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:58.662] <TB2> INFO: Expecting 2560 events.
[09:29:59.518] <TB2> INFO: 2560 events read in total (299ms).
[09:29:59.518] <TB2> INFO: Test took 1159ms.
[09:29:59.520] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:59.824] <TB2> INFO: Expecting 2560 events.
[09:30:00.678] <TB2> INFO: 2560 events read in total (298ms).
[09:30:00.678] <TB2> INFO: Test took 1158ms.
[09:30:00.680] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:00.985] <TB2> INFO: Expecting 2560 events.
[09:30:01.843] <TB2> INFO: 2560 events read in total (302ms).
[09:30:01.843] <TB2> INFO: Test took 1163ms.
[09:30:01.845] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:02.149] <TB2> INFO: Expecting 2560 events.
[09:30:03.007] <TB2> INFO: 2560 events read in total (302ms).
[09:30:03.007] <TB2> INFO: Test took 1162ms.
[09:30:03.009] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:03.313] <TB2> INFO: Expecting 2560 events.
[09:30:04.168] <TB2> INFO: 2560 events read in total (299ms).
[09:30:04.168] <TB2> INFO: Test took 1159ms.
[09:30:04.170] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:04.475] <TB2> INFO: Expecting 2560 events.
[09:30:05.333] <TB2> INFO: 2560 events read in total (302ms).
[09:30:05.334] <TB2> INFO: Test took 1164ms.
[09:30:05.336] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:05.639] <TB2> INFO: Expecting 2560 events.
[09:30:06.496] <TB2> INFO: 2560 events read in total (301ms).
[09:30:06.496] <TB2> INFO: Test took 1160ms.
[09:30:06.498] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:06.803] <TB2> INFO: Expecting 2560 events.
[09:30:07.658] <TB2> INFO: 2560 events read in total (299ms).
[09:30:07.659] <TB2> INFO: Test took 1161ms.
[09:30:07.661] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:07.964] <TB2> INFO: Expecting 2560 events.
[09:30:08.819] <TB2> INFO: 2560 events read in total (299ms).
[09:30:08.819] <TB2> INFO: Test took 1158ms.
[09:30:08.822] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:09.125] <TB2> INFO: Expecting 2560 events.
[09:30:09.984] <TB2> INFO: 2560 events read in total (303ms).
[09:30:09.984] <TB2> INFO: Test took 1162ms.
[09:30:09.986] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:10.289] <TB2> INFO: Expecting 2560 events.
[09:30:11.147] <TB2> INFO: 2560 events read in total (301ms).
[09:30:11.148] <TB2> INFO: Test took 1162ms.
[09:30:11.150] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:11.453] <TB2> INFO: Expecting 2560 events.
[09:30:12.308] <TB2> INFO: 2560 events read in total (299ms).
[09:30:12.309] <TB2> INFO: Test took 1159ms.
[09:30:12.312] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:12.614] <TB2> INFO: Expecting 2560 events.
[09:30:13.473] <TB2> INFO: 2560 events read in total (303ms).
[09:30:13.473] <TB2> INFO: Test took 1161ms.
[09:30:13.476] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:13.779] <TB2> INFO: Expecting 2560 events.
[09:30:14.637] <TB2> INFO: 2560 events read in total (302ms).
[09:30:14.638] <TB2> INFO: Test took 1163ms.
[09:30:14.640] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:14.943] <TB2> INFO: Expecting 2560 events.
[09:30:15.803] <TB2> INFO: 2560 events read in total (304ms).
[09:30:15.803] <TB2> INFO: Test took 1163ms.
[09:30:15.806] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:16.109] <TB2> INFO: Expecting 2560 events.
[09:30:16.961] <TB2> INFO: 2560 events read in total (296ms).
[09:30:16.962] <TB2> INFO: Test took 1156ms.
[09:30:16.963] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:17.268] <TB2> INFO: Expecting 2560 events.
[09:30:18.121] <TB2> INFO: 2560 events read in total (297ms).
[09:30:18.121] <TB2> INFO: Test took 1158ms.
[09:30:18.124] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:18.427] <TB2> INFO: Expecting 2560 events.
[09:30:19.285] <TB2> INFO: 2560 events read in total (302ms).
[09:30:19.286] <TB2> INFO: Test took 1162ms.
[09:30:19.289] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:19.590] <TB2> INFO: Expecting 2560 events.
[09:30:20.447] <TB2> INFO: 2560 events read in total (301ms).
[09:30:20.447] <TB2> INFO: Test took 1158ms.
[09:30:20.449] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:20.752] <TB2> INFO: Expecting 2560 events.
[09:30:21.606] <TB2> INFO: 2560 events read in total (298ms).
[09:30:21.606] <TB2> INFO: Test took 1157ms.
[09:30:21.609] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:21.911] <TB2> INFO: Expecting 2560 events.
[09:30:22.768] <TB2> INFO: 2560 events read in total (301ms).
[09:30:22.768] <TB2> INFO: Test took 1159ms.
[09:30:22.771] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:23.074] <TB2> INFO: Expecting 2560 events.
[09:30:23.933] <TB2> INFO: 2560 events read in total (303ms).
[09:30:23.933] <TB2> INFO: Test took 1162ms.
[09:30:24.396] <TB2> INFO: PixTestPhOptimization::doTest() done, duration: 619 seconds
[09:30:24.396] <TB2> INFO: PH scale (per ROC): 77 78 85 95 74 77 81 87 77 83 90 80 89 86 83 95
[09:30:24.396] <TB2> INFO: PH offset (per ROC): 156 155 145 155 176 161 159 159 157 144 148 157 134 147 160 149
[09:30:24.401] <TB2> INFO: Decoding statistics:
[09:30:24.401] <TB2> INFO: General information:
[09:30:24.401] <TB2> INFO: 16bit words read: 91936
[09:30:24.401] <TB2> INFO: valid events total: 10240
[09:30:24.401] <TB2> INFO: empty events: 7736
[09:30:24.401] <TB2> INFO: valid events with pixels: 2504
[09:30:24.401] <TB2> INFO: valid pixel hits: 2504
[09:30:24.401] <TB2> INFO: Event errors: 0
[09:30:24.401] <TB2> INFO: start marker: 0
[09:30:24.401] <TB2> INFO: stop marker: 0
[09:30:24.401] <TB2> INFO: overflow: 0
[09:30:24.401] <TB2> INFO: invalid 5bit words: 0
[09:30:24.401] <TB2> INFO: invalid XOR eye diagram: 0
[09:30:24.401] <TB2> INFO: frame (failed synchr.): 0
[09:30:24.401] <TB2> INFO: idle data (no TBM trl): 0
[09:30:24.401] <TB2> INFO: no data (only TBM hdr): 0
[09:30:24.401] <TB2> INFO: TBM errors: 0
[09:30:24.401] <TB2> INFO: flawed TBM headers: 0
[09:30:24.401] <TB2> INFO: flawed TBM trailers: 0
[09:30:24.401] <TB2> INFO: event ID mismatches: 0
[09:30:24.401] <TB2> INFO: ROC errors: 0
[09:30:24.401] <TB2> INFO: missing ROC header(s): 0
[09:30:24.401] <TB2> INFO: misplaced readback start: 0
[09:30:24.401] <TB2> INFO: Pixel decoding errors: 0
[09:30:24.401] <TB2> INFO: pixel data incomplete: 0
[09:30:24.401] <TB2> INFO: pixel address: 0
[09:30:24.401] <TB2> INFO: pulse height fill bit: 0
[09:30:24.401] <TB2> INFO: buffer corruption: 0
[09:30:24.584] <TB2> INFO: ######################################################################
[09:30:24.584] <TB2> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[09:30:24.584] <TB2> INFO: ######################################################################
[09:30:24.594] <TB2> INFO: scanning low vcal = 10
[09:30:24.867] <TB2> INFO: Expecting 41600 events.
[09:30:28.407] <TB2> INFO: 41600 events read in total (2985ms).
[09:30:28.408] <TB2> INFO: Test took 3814ms.
[09:30:28.409] <TB2> INFO: scanning low vcal = 20
[09:30:28.712] <TB2> INFO: Expecting 41600 events.
[09:30:32.275] <TB2> INFO: 41600 events read in total (3006ms).
[09:30:32.275] <TB2> INFO: Test took 3866ms.
[09:30:32.277] <TB2> INFO: scanning low vcal = 30
[09:30:32.579] <TB2> INFO: Expecting 41600 events.
[09:30:36.138] <TB2> INFO: 41600 events read in total (3003ms).
[09:30:36.139] <TB2> INFO: Test took 3862ms.
[09:30:36.141] <TB2> INFO: scanning low vcal = 40
[09:30:36.431] <TB2> INFO: Expecting 41600 events.
[09:30:40.569] <TB2> INFO: 41600 events read in total (3582ms).
[09:30:40.569] <TB2> INFO: Test took 4428ms.
[09:30:40.572] <TB2> INFO: scanning low vcal = 50
[09:30:40.843] <TB2> INFO: Expecting 41600 events.
[09:30:45.061] <TB2> INFO: 41600 events read in total (3662ms).
[09:30:45.062] <TB2> INFO: Test took 4490ms.
[09:30:45.064] <TB2> INFO: scanning low vcal = 60
[09:30:45.345] <TB2> INFO: Expecting 41600 events.
[09:30:49.569] <TB2> INFO: 41600 events read in total (3668ms).
[09:30:49.569] <TB2> INFO: Test took 4505ms.
[09:30:49.572] <TB2> INFO: scanning low vcal = 70
[09:30:49.836] <TB2> INFO: Expecting 41600 events.
[09:30:54.046] <TB2> INFO: 41600 events read in total (3654ms).
[09:30:54.046] <TB2> INFO: Test took 4474ms.
[09:30:54.049] <TB2> INFO: scanning low vcal = 80
[09:30:54.337] <TB2> INFO: Expecting 41600 events.
[09:30:58.557] <TB2> INFO: 41600 events read in total (3663ms).
[09:30:58.558] <TB2> INFO: Test took 4509ms.
[09:30:58.560] <TB2> INFO: scanning low vcal = 90
[09:30:58.847] <TB2> INFO: Expecting 41600 events.
[09:31:03.061] <TB2> INFO: 41600 events read in total (3657ms).
[09:31:03.061] <TB2> INFO: Test took 4501ms.
[09:31:03.064] <TB2> INFO: scanning low vcal = 100
[09:31:03.339] <TB2> INFO: Expecting 41600 events.
[09:31:07.557] <TB2> INFO: 41600 events read in total (3662ms).
[09:31:07.558] <TB2> INFO: Test took 4494ms.
[09:31:07.560] <TB2> INFO: scanning low vcal = 110
[09:31:07.829] <TB2> INFO: Expecting 41600 events.
[09:31:12.031] <TB2> INFO: 41600 events read in total (3646ms).
[09:31:12.032] <TB2> INFO: Test took 4472ms.
[09:31:12.034] <TB2> INFO: scanning low vcal = 120
[09:31:12.294] <TB2> INFO: Expecting 41600 events.
[09:31:16.491] <TB2> INFO: 41600 events read in total (3640ms).
[09:31:16.491] <TB2> INFO: Test took 4456ms.
[09:31:16.493] <TB2> INFO: scanning low vcal = 130
[09:31:16.763] <TB2> INFO: Expecting 41600 events.
[09:31:20.971] <TB2> INFO: 41600 events read in total (3652ms).
[09:31:20.972] <TB2> INFO: Test took 4479ms.
[09:31:20.974] <TB2> INFO: scanning low vcal = 140
[09:31:21.239] <TB2> INFO: Expecting 41600 events.
[09:31:25.422] <TB2> INFO: 41600 events read in total (3627ms).
[09:31:25.423] <TB2> INFO: Test took 4449ms.
[09:31:25.426] <TB2> INFO: scanning low vcal = 150
[09:31:25.688] <TB2> INFO: Expecting 41600 events.
[09:31:29.909] <TB2> INFO: 41600 events read in total (3665ms).
[09:31:29.909] <TB2> INFO: Test took 4483ms.
[09:31:29.912] <TB2> INFO: scanning low vcal = 160
[09:31:30.172] <TB2> INFO: Expecting 41600 events.
[09:31:34.370] <TB2> INFO: 41600 events read in total (3641ms).
[09:31:34.370] <TB2> INFO: Test took 4458ms.
[09:31:34.379] <TB2> INFO: scanning low vcal = 170
[09:31:34.643] <TB2> INFO: Expecting 41600 events.
[09:31:38.841] <TB2> INFO: 41600 events read in total (3642ms).
[09:31:38.842] <TB2> INFO: Test took 4462ms.
[09:31:38.845] <TB2> INFO: scanning low vcal = 180
[09:31:39.109] <TB2> INFO: Expecting 41600 events.
[09:31:43.330] <TB2> INFO: 41600 events read in total (3665ms).
[09:31:43.330] <TB2> INFO: Test took 4485ms.
[09:31:43.333] <TB2> INFO: scanning low vcal = 190
[09:31:43.602] <TB2> INFO: Expecting 41600 events.
[09:31:47.791] <TB2> INFO: 41600 events read in total (3633ms).
[09:31:47.792] <TB2> INFO: Test took 4459ms.
[09:31:47.794] <TB2> INFO: scanning low vcal = 200
[09:31:48.054] <TB2> INFO: Expecting 41600 events.
[09:31:52.253] <TB2> INFO: 41600 events read in total (3643ms).
[09:31:52.254] <TB2> INFO: Test took 4460ms.
[09:31:52.256] <TB2> INFO: scanning low vcal = 210
[09:31:52.520] <TB2> INFO: Expecting 41600 events.
[09:31:56.720] <TB2> INFO: 41600 events read in total (3644ms).
[09:31:56.720] <TB2> INFO: Test took 4464ms.
[09:31:56.723] <TB2> INFO: scanning low vcal = 220
[09:31:57.011] <TB2> INFO: Expecting 41600 events.
[09:32:01.201] <TB2> INFO: 41600 events read in total (3633ms).
[09:32:01.202] <TB2> INFO: Test took 4479ms.
[09:32:01.204] <TB2> INFO: scanning low vcal = 230
[09:32:01.492] <TB2> INFO: Expecting 41600 events.
[09:32:05.678] <TB2> INFO: 41600 events read in total (3629ms).
[09:32:05.679] <TB2> INFO: Test took 4475ms.
[09:32:05.681] <TB2> INFO: scanning low vcal = 240
[09:32:05.968] <TB2> INFO: Expecting 41600 events.
[09:32:10.218] <TB2> INFO: 41600 events read in total (3694ms).
[09:32:10.219] <TB2> INFO: Test took 4538ms.
[09:32:10.221] <TB2> INFO: scanning low vcal = 250
[09:32:10.504] <TB2> INFO: Expecting 41600 events.
[09:32:14.721] <TB2> INFO: 41600 events read in total (3661ms).
[09:32:14.721] <TB2> INFO: Test took 4500ms.
[09:32:14.725] <TB2> INFO: scanning high vcal = 30 (= 210 in low range)
[09:32:14.986] <TB2> INFO: Expecting 41600 events.
[09:32:19.191] <TB2> INFO: 41600 events read in total (3649ms).
[09:32:19.192] <TB2> INFO: Test took 4467ms.
[09:32:19.194] <TB2> INFO: scanning high vcal = 50 (= 350 in low range)
[09:32:19.459] <TB2> INFO: Expecting 41600 events.
[09:32:23.674] <TB2> INFO: 41600 events read in total (3659ms).
[09:32:23.674] <TB2> INFO: Test took 4480ms.
[09:32:23.682] <TB2> INFO: scanning high vcal = 70 (= 490 in low range)
[09:32:23.943] <TB2> INFO: Expecting 41600 events.
[09:32:28.155] <TB2> INFO: 41600 events read in total (3656ms).
[09:32:28.155] <TB2> INFO: Test took 4473ms.
[09:32:28.158] <TB2> INFO: scanning high vcal = 90 (= 630 in low range)
[09:32:28.445] <TB2> INFO: Expecting 41600 events.
[09:32:32.696] <TB2> INFO: 41600 events read in total (3695ms).
[09:32:32.697] <TB2> INFO: Test took 4539ms.
[09:32:32.699] <TB2> INFO: scanning high vcal = 200 (= 1400 in low range)
[09:32:33.030] <TB2> INFO: Expecting 41600 events.
[09:32:37.227] <TB2> INFO: 41600 events read in total (3640ms).
[09:32:37.227] <TB2> INFO: Test took 4527ms.
[09:32:37.668] <TB2> INFO: PixTestGainPedestal::measure() done
[09:33:12.643] <TB2> INFO: PixTestGainPedestal::fit() done
[09:33:12.643] <TB2> INFO: non-linearity mean: 0.955 0.952 0.959 0.953 0.962 0.956 0.953 0.958 0.953 0.949 0.958 0.959 0.952 0.950 0.953 0.952
[09:33:12.643] <TB2> INFO: non-linearity RMS: 0.006 0.007 0.005 0.005 0.006 0.006 0.005 0.005 0.006 0.006 0.006 0.005 0.006 0.006 0.006 0.006
[09:33:12.643] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[09:33:12.662] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[09:33:12.682] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[09:33:12.701] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[09:33:12.720] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[09:33:12.738] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[09:33:12.756] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[09:33:12.776] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[09:33:12.794] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[09:33:12.813] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[09:33:12.831] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[09:33:12.851] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[09:33:12.871] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[09:33:12.891] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[09:33:12.912] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[09:33:12.932] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[09:33:12.952] <TB2> INFO: PixTestGainPedestal::fullTest() done, duration: 168 seconds
[09:33:12.952] <TB2> INFO: Decoding statistics:
[09:33:12.952] <TB2> INFO: General information:
[09:33:12.952] <TB2> INFO: 16bit words read: 2662400
[09:33:12.952] <TB2> INFO: valid events total: 166400
[09:33:12.952] <TB2> INFO: empty events: 0
[09:33:12.952] <TB2> INFO: valid events with pixels: 166400
[09:33:12.952] <TB2> INFO: valid pixel hits: 665525
[09:33:12.952] <TB2> INFO: Event errors: 0
[09:33:12.952] <TB2> INFO: start marker: 0
[09:33:12.952] <TB2> INFO: stop marker: 0
[09:33:12.952] <TB2> INFO: overflow: 0
[09:33:12.952] <TB2> INFO: invalid 5bit words: 0
[09:33:12.952] <TB2> INFO: invalid XOR eye diagram: 0
[09:33:12.952] <TB2> INFO: frame (failed synchr.): 0
[09:33:12.952] <TB2> INFO: idle data (no TBM trl): 0
[09:33:12.952] <TB2> INFO: no data (only TBM hdr): 0
[09:33:12.952] <TB2> INFO: TBM errors: 0
[09:33:12.952] <TB2> INFO: flawed TBM headers: 0
[09:33:12.952] <TB2> INFO: flawed TBM trailers: 0
[09:33:12.952] <TB2> INFO: event ID mismatches: 0
[09:33:12.952] <TB2> INFO: ROC errors: 0
[09:33:12.952] <TB2> INFO: missing ROC header(s): 0
[09:33:12.952] <TB2> INFO: misplaced readback start: 0
[09:33:12.952] <TB2> INFO: Pixel decoding errors: 0
[09:33:12.952] <TB2> INFO: pixel data incomplete: 0
[09:33:12.952] <TB2> INFO: pixel address: 0
[09:33:12.952] <TB2> INFO: pulse height fill bit: 0
[09:33:12.952] <TB2> INFO: buffer corruption: 0
[09:33:12.960] <TB2> INFO: readReadbackCal: /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C15.dat
[09:33:12.962] <TB2> INFO: ######################################################################
[09:33:12.962] <TB2> INFO: PixTestReadback::doTest()
[09:33:12.962] <TB2> INFO: ######################################################################
[09:33:12.962] <TB2> INFO: ----------------------------------------------------------------------
[09:33:12.962] <TB2> INFO: PixTestReadback::CalibrateVd()
[09:33:12.962] <TB2> INFO: ----------------------------------------------------------------------
[09:33:22.402] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C0.dat
[09:33:22.402] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C1.dat
[09:33:22.402] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C2.dat
[09:33:22.402] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C3.dat
[09:33:22.402] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C4.dat
[09:33:22.402] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C5.dat
[09:33:22.402] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C6.dat
[09:33:22.402] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C7.dat
[09:33:22.402] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C8.dat
[09:33:22.402] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C9.dat
[09:33:22.403] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C10.dat
[09:33:22.403] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C11.dat
[09:33:22.403] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C12.dat
[09:33:22.403] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C13.dat
[09:33:22.403] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C14.dat
[09:33:22.403] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C15.dat
[09:33:22.434] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[09:33:22.434] <TB2> INFO: ----------------------------------------------------------------------
[09:33:22.434] <TB2> INFO: PixTestReadback::CalibrateVa()
[09:33:22.434] <TB2> INFO: ----------------------------------------------------------------------
[09:33:31.920] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C0.dat
[09:33:31.920] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C1.dat
[09:33:31.920] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C2.dat
[09:33:31.920] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C3.dat
[09:33:31.920] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C4.dat
[09:33:31.920] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C5.dat
[09:33:31.920] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C6.dat
[09:33:31.921] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C7.dat
[09:33:31.921] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C8.dat
[09:33:31.921] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C9.dat
[09:33:31.921] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C10.dat
[09:33:31.921] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C11.dat
[09:33:31.921] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C12.dat
[09:33:31.921] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C13.dat
[09:33:31.921] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C14.dat
[09:33:31.921] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C15.dat
[09:33:31.949] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[09:33:31.949] <TB2> INFO: ----------------------------------------------------------------------
[09:33:31.949] <TB2> INFO: PixTestReadback::readbackVbg()
[09:33:31.949] <TB2> INFO: ----------------------------------------------------------------------
[09:33:39.249] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[09:33:39.249] <TB2> INFO: ----------------------------------------------------------------------
[09:33:39.249] <TB2> INFO: PixTestReadback::getCalibratedVbg()
[09:33:39.249] <TB2> INFO: ----------------------------------------------------------------------
[09:33:39.250] <TB2> INFO: Vbg will be calibrated using Vd calibration
[09:33:39.250] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 154.2calibrated Vbg = 1.19994 :::*/*/*/*/
[09:33:39.250] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 156calibrated Vbg = 1.19966 :::*/*/*/*/
[09:33:39.250] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 143.5calibrated Vbg = 1.20349 :::*/*/*/*/
[09:33:39.250] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 143calibrated Vbg = 1.20511 :::*/*/*/*/
[09:33:39.250] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 158.9calibrated Vbg = 1.20685 :::*/*/*/*/
[09:33:39.250] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 158.8calibrated Vbg = 1.20906 :::*/*/*/*/
[09:33:39.250] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 145calibrated Vbg = 1.22302 :::*/*/*/*/
[09:33:39.250] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 154.9calibrated Vbg = 1.2158 :::*/*/*/*/
[09:33:39.250] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 156.4calibrated Vbg = 1.20876 :::*/*/*/*/
[09:33:39.250] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 158.9calibrated Vbg = 1.21284 :::*/*/*/*/
[09:33:39.250] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 146.5calibrated Vbg = 1.2101 :::*/*/*/*/
[09:33:39.250] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 153.7calibrated Vbg = 1.21374 :::*/*/*/*/
[09:33:39.250] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 143calibrated Vbg = 1.20891 :::*/*/*/*/
[09:33:39.250] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 152.1calibrated Vbg = 1.20271 :::*/*/*/*/
[09:33:39.250] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 145calibrated Vbg = 1.21018 :::*/*/*/*/
[09:33:39.250] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 151.9calibrated Vbg = 1.20582 :::*/*/*/*/
[09:33:39.252] <TB2> INFO: ----------------------------------------------------------------------
[09:33:39.252] <TB2> INFO: PixTestReadback::CalibrateIa()
[09:33:39.252] <TB2> INFO: ----------------------------------------------------------------------
[09:36:14.680] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C0.dat
[09:36:14.680] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C1.dat
[09:36:14.681] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C2.dat
[09:36:14.681] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C3.dat
[09:36:14.681] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C4.dat
[09:36:14.681] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C5.dat
[09:36:14.681] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C6.dat
[09:36:14.681] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C7.dat
[09:36:14.681] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C8.dat
[09:36:14.681] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C9.dat
[09:36:14.681] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C10.dat
[09:36:14.681] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C11.dat
[09:36:14.681] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C12.dat
[09:36:14.681] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C13.dat
[09:36:14.681] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C14.dat
[09:36:14.682] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2317_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C15.dat
[09:36:14.710] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[09:36:14.710] <TB2> INFO: PixTestReadback::doTest() done
[09:36:14.710] <TB2> INFO: Decoding statistics:
[09:36:14.710] <TB2> INFO: General information:
[09:36:14.710] <TB2> INFO: 16bit words read: 1024
[09:36:14.710] <TB2> INFO: valid events total: 128
[09:36:14.710] <TB2> INFO: empty events: 128
[09:36:14.710] <TB2> INFO: valid events with pixels: 0
[09:36:14.710] <TB2> INFO: valid pixel hits: 0
[09:36:14.710] <TB2> INFO: Event errors: 0
[09:36:14.710] <TB2> INFO: start marker: 0
[09:36:14.710] <TB2> INFO: stop marker: 0
[09:36:14.710] <TB2> INFO: overflow: 0
[09:36:14.710] <TB2> INFO: invalid 5bit words: 0
[09:36:14.710] <TB2> INFO: invalid XOR eye diagram: 0
[09:36:14.710] <TB2> INFO: frame (failed synchr.): 0
[09:36:14.710] <TB2> INFO: idle data (no TBM trl): 0
[09:36:14.710] <TB2> INFO: no data (only TBM hdr): 0
[09:36:14.710] <TB2> INFO: TBM errors: 0
[09:36:14.711] <TB2> INFO: flawed TBM headers: 0
[09:36:14.711] <TB2> INFO: flawed TBM trailers: 0
[09:36:14.711] <TB2> INFO: event ID mismatches: 0
[09:36:14.711] <TB2> INFO: ROC errors: 0
[09:36:14.711] <TB2> INFO: missing ROC header(s): 0
[09:36:14.711] <TB2> INFO: misplaced readback start: 0
[09:36:14.711] <TB2> INFO: Pixel decoding errors: 0
[09:36:14.711] <TB2> INFO: pixel data incomplete: 0
[09:36:14.711] <TB2> INFO: pixel address: 0
[09:36:14.711] <TB2> INFO: pulse height fill bit: 0
[09:36:14.711] <TB2> INFO: buffer corruption: 0
[09:36:14.728] <TB2> INFO: Decoding statistics:
[09:36:14.728] <TB2> INFO: General information:
[09:36:14.728] <TB2> INFO: 16bit words read: 2755360
[09:36:14.728] <TB2> INFO: valid events total: 176768
[09:36:14.728] <TB2> INFO: empty events: 7864
[09:36:14.728] <TB2> INFO: valid events with pixels: 168904
[09:36:14.728] <TB2> INFO: valid pixel hits: 668029
[09:36:14.728] <TB2> INFO: Event errors: 0
[09:36:14.728] <TB2> INFO: start marker: 0
[09:36:14.728] <TB2> INFO: stop marker: 0
[09:36:14.728] <TB2> INFO: overflow: 0
[09:36:14.728] <TB2> INFO: invalid 5bit words: 0
[09:36:14.728] <TB2> INFO: invalid XOR eye diagram: 0
[09:36:14.728] <TB2> INFO: frame (failed synchr.): 0
[09:36:14.728] <TB2> INFO: idle data (no TBM trl): 0
[09:36:14.728] <TB2> INFO: no data (only TBM hdr): 0
[09:36:14.728] <TB2> INFO: TBM errors: 0
[09:36:14.728] <TB2> INFO: flawed TBM headers: 0
[09:36:14.728] <TB2> INFO: flawed TBM trailers: 0
[09:36:14.728] <TB2> INFO: event ID mismatches: 0
[09:36:14.728] <TB2> INFO: ROC errors: 0
[09:36:14.728] <TB2> INFO: missing ROC header(s): 0
[09:36:14.728] <TB2> INFO: misplaced readback start: 0
[09:36:14.728] <TB2> INFO: Pixel decoding errors: 0
[09:36:14.728] <TB2> INFO: pixel data incomplete: 0
[09:36:14.728] <TB2> INFO: pixel address: 0
[09:36:14.728] <TB2> INFO: pulse height fill bit: 0
[09:36:14.728] <TB2> INFO: buffer corruption: 0
[09:36:14.728] <TB2> INFO: enter test to run
[09:36:14.728] <TB2> INFO: test: exit no parameter change
[09:36:14.940] <TB2> QUIET: Connection to board 156 closed.
[09:36:15.022] <TB2> INFO: pXar: this is the end, my friend