[15:26:27.727] <TB3> INFO: *** Welcome to pxar ***
[15:26:27.727] <TB3> INFO: *** Today: 2016/09/06
[15:26:27.835] <TB3> INFO: *** Version: fe94
[15:26:27.835] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters_C15.dat
[15:26:27.836] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//tbmParameters_C0b.dat
[15:26:27.836] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//defaultMaskFile.dat
[15:26:27.836] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//trimParameters_C15.dat
[15:26:27.928] <TB3> INFO: clk: 4
[15:26:27.928] <TB3> INFO: ctr: 4
[15:26:27.928] <TB3> INFO: sda: 19
[15:26:27.928] <TB3> INFO: tin: 9
[15:26:27.928] <TB3> INFO: level: 15
[15:26:27.928] <TB3> INFO: triggerdelay: 0
[15:26:27.928] <TB3> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[15:26:27.928] <TB3> INFO: Log level: INFO
[15:26:27.935] <TB3> INFO: Found DTB DTB_WZ4I6J
[15:26:27.944] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[15:26:27.947] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[15:26:27.948] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[15:26:29.484] <TB3> INFO: DUT info:
[15:26:29.484] <TB3> INFO: The DUT currently contains the following objects:
[15:26:29.484] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[15:26:29.484] <TB3> INFO: TBM Core alpha (0): 7 registers set
[15:26:29.484] <TB3> INFO: TBM Core beta (1): 7 registers set
[15:26:29.484] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:26:29.484] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:29.484] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:29.484] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:29.484] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:29.484] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:29.485] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:29.485] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:29.485] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:29.485] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:29.485] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:29.485] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:29.485] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:29.485] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:29.485] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:29.485] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:29.485] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:26:29.886] <TB3> INFO: enter 'restricted' command line mode
[15:26:29.886] <TB3> INFO: enter test to run
[15:26:29.886] <TB3> INFO: test: pretest no parameter change
[15:26:29.886] <TB3> INFO: running: pretest
[15:26:29.893] <TB3> INFO: ----------------------------------------------------------------------
[15:26:29.893] <TB3> INFO: PixTestPretest::programROC()
[15:26:29.893] <TB3> INFO: ----------------------------------------------------------------------
[15:26:47.907] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[15:26:47.907] <TB3> INFO: IA differences per ROC: 19.3 19.3 19.3 19.3 20.1 18.5 18.5 19.3 16.1 19.3 20.1 19.3 18.5 19.3 18.5 18.5
[15:26:48.000] <TB3> INFO: enter test to run
[15:26:48.000] <TB3> INFO: test: pretest no parameter change
[15:26:48.000] <TB3> INFO: running: pretest
[15:26:48.001] <TB3> INFO: ----------------------------------------------------------------------
[15:26:48.001] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[15:26:48.001] <TB3> INFO: ----------------------------------------------------------------------
[15:26:54.797] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 392.3 mA = 24.5187 mA/ROC
[15:26:54.797] <TB3> INFO: i(loss) [mA/ROC]: 19.3 19.3 19.3 19.3 19.3 19.3 19.3 18.5 18.5 19.3 18.5 18.5 18.5 19.3 19.3 18.5
[15:26:54.833] <TB3> INFO: enter test to run
[15:26:54.833] <TB3> INFO: test: pretest no parameter change
[15:26:54.833] <TB3> INFO: running: pretest
[15:26:54.833] <TB3> INFO: ----------------------------------------------------------------------
[15:26:54.833] <TB3> INFO: PixTestPretest::findTiming()
[15:26:54.833] <TB3> INFO: ----------------------------------------------------------------------
[15:26:54.833] <TB3> INFO: PixTestCmd::init()
[15:26:55.697] <TB3> WARNING: Not unmasking DUT, not setting Calibrate bits!
[15:28:24.460] <TB3> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[15:28:24.460] <TB3> INFO: (success/tries = 100/100), width = 3
[15:28:24.461] <TB3> INFO: enter test to run
[15:28:24.461] <TB3> INFO: test: pretest no parameter change
[15:28:24.461] <TB3> INFO: running: pretest
[15:28:24.463] <TB3> INFO: ----------------------------------------------------------------------
[15:28:24.463] <TB3> INFO: PixTestPretest::findWorkingPixel()
[15:28:24.463] <TB3> INFO: ----------------------------------------------------------------------
[15:28:24.557] <TB3> INFO: Expecting 231680 events.
[15:28:30.328] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L497> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[15:28:30.413] <TB3> ERROR: <datapipe.cc/CheckEventID:L459> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[15:28:34.802] <TB3> INFO: 231680 events read in total (9689ms).
[15:28:34.805] <TB3> INFO: Test took 10335ms.
[15:28:35.034] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[15:28:35.114] <TB3> INFO: enter test to run
[15:28:35.115] <TB3> INFO: test: pretest no parameter change
[15:28:35.115] <TB3> INFO: running: pretest
[15:28:35.116] <TB3> INFO: ----------------------------------------------------------------------
[15:28:35.116] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[15:28:35.116] <TB3> INFO: ----------------------------------------------------------------------
[15:28:35.206] <TB3> INFO: Expecting 231680 events.
[15:28:45.514] <TB3> INFO: 231680 events read in total (9751ms).
[15:28:45.519] <TB3> INFO: Test took 10400ms.
[15:28:45.763] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[15:28:45.763] <TB3> INFO: CalDel: 137 148 144 139 148 143 152 136 117 118 161 133 140 131 140 139
[15:28:45.763] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[15:28:45.799] <TB3> INFO: enter test to run
[15:28:45.799] <TB3> INFO: test: pretest no parameter change
[15:28:45.799] <TB3> INFO: running: pretest
[15:28:45.799] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters_C0.dat
[15:28:45.799] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters_C1.dat
[15:28:45.800] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters_C2.dat
[15:28:45.800] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters_C3.dat
[15:28:45.800] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters_C4.dat
[15:28:45.800] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters_C5.dat
[15:28:45.800] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters_C6.dat
[15:28:45.800] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters_C7.dat
[15:28:45.800] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters_C8.dat
[15:28:45.800] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters_C9.dat
[15:28:45.800] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters_C10.dat
[15:28:45.801] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters_C11.dat
[15:28:45.801] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters_C12.dat
[15:28:45.801] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters_C13.dat
[15:28:45.801] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters_C14.dat
[15:28:45.801] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters_C15.dat
[15:28:45.801] <TB3> INFO: enter test to run
[15:28:45.801] <TB3> INFO: test: fulltest no parameter change
[15:28:45.801] <TB3> INFO: running: fulltest
[15:28:45.801] <TB3> INFO: ######################################################################
[15:28:45.801] <TB3> INFO: PixTestFullTest::doTest()
[15:28:45.801] <TB3> INFO: ######################################################################
[15:28:45.802] <TB3> INFO: ######################################################################
[15:28:45.802] <TB3> INFO: PixTestAlive::doTest()
[15:28:45.802] <TB3> INFO: ######################################################################
[15:28:45.803] <TB3> INFO: ----------------------------------------------------------------------
[15:28:45.803] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:28:45.803] <TB3> INFO: ----------------------------------------------------------------------
[15:28:46.064] <TB3> INFO: Expecting 41600 events.
[15:28:49.808] <TB3> INFO: 41600 events read in total (3188ms).
[15:28:49.809] <TB3> INFO: Test took 4004ms.
[15:28:50.033] <TB3> INFO: PixTestAlive::aliveTest() done
[15:28:50.033] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:28:50.034] <TB3> INFO: ----------------------------------------------------------------------
[15:28:50.034] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:28:50.034] <TB3> INFO: ----------------------------------------------------------------------
[15:28:50.337] <TB3> INFO: Expecting 41600 events.
[15:28:53.370] <TB3> INFO: 41600 events read in total (2477ms).
[15:28:53.370] <TB3> INFO: Test took 3334ms.
[15:28:53.370] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:28:53.611] <TB3> INFO: PixTestAlive::maskTest() done
[15:28:53.611] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:28:53.614] <TB3> INFO: ----------------------------------------------------------------------
[15:28:53.614] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:28:53.615] <TB3> INFO: ----------------------------------------------------------------------
[15:28:53.887] <TB3> INFO: Expecting 41600 events.
[15:28:57.696] <TB3> INFO: 41600 events read in total (3253ms).
[15:28:57.697] <TB3> INFO: Test took 4080ms.
[15:28:57.925] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[15:28:57.925] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:28:57.925] <TB3> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[15:28:57.925] <TB3> INFO: Decoding statistics:
[15:28:57.925] <TB3> INFO: General information:
[15:28:57.925] <TB3> INFO: 16bit words read: 0
[15:28:57.925] <TB3> INFO: valid events total: 0
[15:28:57.925] <TB3> INFO: empty events: 0
[15:28:57.925] <TB3> INFO: valid events with pixels: 0
[15:28:57.925] <TB3> INFO: valid pixel hits: 0
[15:28:57.925] <TB3> INFO: Event errors: 0
[15:28:57.925] <TB3> INFO: start marker: 0
[15:28:57.925] <TB3> INFO: stop marker: 0
[15:28:57.925] <TB3> INFO: overflow: 0
[15:28:57.925] <TB3> INFO: invalid 5bit words: 0
[15:28:57.925] <TB3> INFO: invalid XOR eye diagram: 0
[15:28:57.925] <TB3> INFO: frame (failed synchr.): 0
[15:28:57.925] <TB3> INFO: idle data (no TBM trl): 0
[15:28:57.925] <TB3> INFO: no data (only TBM hdr): 0
[15:28:57.925] <TB3> INFO: TBM errors: 0
[15:28:57.925] <TB3> INFO: flawed TBM headers: 0
[15:28:57.925] <TB3> INFO: flawed TBM trailers: 0
[15:28:57.925] <TB3> INFO: event ID mismatches: 0
[15:28:57.925] <TB3> INFO: ROC errors: 0
[15:28:57.925] <TB3> INFO: missing ROC header(s): 0
[15:28:57.925] <TB3> INFO: misplaced readback start: 0
[15:28:57.926] <TB3> INFO: Pixel decoding errors: 0
[15:28:57.926] <TB3> INFO: pixel data incomplete: 0
[15:28:57.926] <TB3> INFO: pixel address: 0
[15:28:57.926] <TB3> INFO: pulse height fill bit: 0
[15:28:57.926] <TB3> INFO: buffer corruption: 0
[15:28:57.933] <TB3> INFO: ######################################################################
[15:28:57.933] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[15:28:57.933] <TB3> INFO: ######################################################################
[15:28:57.935] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[15:28:57.946] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:28:57.946] <TB3> INFO: run 1 of 1
[15:28:58.210] <TB3> INFO: Expecting 3120000 events.
[15:29:39.227] <TB3> INFO: 872645 events read in total (40461ms).
[15:30:19.193] <TB3> INFO: 1727900 events read in total (80427ms).
[15:30:59.941] <TB3> INFO: 2594185 events read in total (121176ms).
[15:31:25.109] <TB3> INFO: 3120000 events read in total (146343ms).
[15:31:25.161] <TB3> INFO: Test took 147215ms.
[15:31:50.721] <TB3> INFO: PixTestBBMap::doTest() done, duration: 172 seconds
[15:31:50.722] <TB3> INFO: number of dead bumps (per ROC): 2 1 0 2 0 2 3 4 1 2 1 1 2 1 0 6
[15:31:50.722] <TB3> INFO: separation cut (per ROC): 98 88 90 88 97 86 93 86 84 89 97 89 94 105 86 86
[15:31:50.722] <TB3> INFO: Decoding statistics:
[15:31:50.722] <TB3> INFO: General information:
[15:31:50.722] <TB3> INFO: 16bit words read: 0
[15:31:50.722] <TB3> INFO: valid events total: 0
[15:31:50.722] <TB3> INFO: empty events: 0
[15:31:50.722] <TB3> INFO: valid events with pixels: 0
[15:31:50.722] <TB3> INFO: valid pixel hits: 0
[15:31:50.722] <TB3> INFO: Event errors: 0
[15:31:50.722] <TB3> INFO: start marker: 0
[15:31:50.722] <TB3> INFO: stop marker: 0
[15:31:50.722] <TB3> INFO: overflow: 0
[15:31:50.722] <TB3> INFO: invalid 5bit words: 0
[15:31:50.722] <TB3> INFO: invalid XOR eye diagram: 0
[15:31:50.722] <TB3> INFO: frame (failed synchr.): 0
[15:31:50.722] <TB3> INFO: idle data (no TBM trl): 0
[15:31:50.722] <TB3> INFO: no data (only TBM hdr): 0
[15:31:50.722] <TB3> INFO: TBM errors: 0
[15:31:50.722] <TB3> INFO: flawed TBM headers: 0
[15:31:50.722] <TB3> INFO: flawed TBM trailers: 0
[15:31:50.722] <TB3> INFO: event ID mismatches: 0
[15:31:50.722] <TB3> INFO: ROC errors: 0
[15:31:50.722] <TB3> INFO: missing ROC header(s): 0
[15:31:50.722] <TB3> INFO: misplaced readback start: 0
[15:31:50.722] <TB3> INFO: Pixel decoding errors: 0
[15:31:50.722] <TB3> INFO: pixel data incomplete: 0
[15:31:50.722] <TB3> INFO: pixel address: 0
[15:31:50.722] <TB3> INFO: pulse height fill bit: 0
[15:31:50.722] <TB3> INFO: buffer corruption: 0
[15:31:50.795] <TB3> INFO: ######################################################################
[15:31:50.795] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:31:50.795] <TB3> INFO: ######################################################################
[15:31:50.795] <TB3> INFO: ----------------------------------------------------------------------
[15:31:50.795] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:31:50.795] <TB3> INFO: ----------------------------------------------------------------------
[15:31:50.795] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:31:50.804] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[15:31:50.804] <TB3> INFO: run 1 of 1
[15:31:51.064] <TB3> INFO: Expecting 26208000 events.
[15:32:24.679] <TB3> INFO: 912750 events read in total (33059ms).
[15:32:57.519] <TB3> INFO: 1811750 events read in total (65899ms).
[15:33:30.319] <TB3> INFO: 2707650 events read in total (98699ms).
[15:34:03.035] <TB3> INFO: 3605500 events read in total (131415ms).
[15:34:35.866] <TB3> INFO: 4497900 events read in total (164246ms).
[15:35:08.530] <TB3> INFO: 5391000 events read in total (196910ms).
[15:35:41.349] <TB3> INFO: 6285850 events read in total (229729ms).
[15:36:14.063] <TB3> INFO: 7177300 events read in total (262443ms).
[15:36:46.850] <TB3> INFO: 8067850 events read in total (295230ms).
[15:37:19.712] <TB3> INFO: 8958450 events read in total (328092ms).
[15:37:52.743] <TB3> INFO: 9849450 events read in total (361123ms).
[15:38:25.640] <TB3> INFO: 10737450 events read in total (394020ms).
[15:38:58.296] <TB3> INFO: 11625550 events read in total (426676ms).
[15:39:31.027] <TB3> INFO: 12511350 events read in total (459407ms).
[15:40:03.366] <TB3> INFO: 13391550 events read in total (491746ms).
[15:40:36.116] <TB3> INFO: 14267300 events read in total (524496ms).
[15:41:08.656] <TB3> INFO: 15138900 events read in total (557036ms).
[15:41:41.376] <TB3> INFO: 16011100 events read in total (589756ms).
[15:42:14.366] <TB3> INFO: 16881750 events read in total (622746ms).
[15:42:47.614] <TB3> INFO: 17752100 events read in total (655994ms).
[15:43:20.767] <TB3> INFO: 18620800 events read in total (689147ms).
[15:43:53.529] <TB3> INFO: 19487350 events read in total (721909ms).
[15:44:25.990] <TB3> INFO: 20354500 events read in total (754370ms).
[15:44:58.493] <TB3> INFO: 21220200 events read in total (786873ms).
[15:45:31.001] <TB3> INFO: 22082800 events read in total (819381ms).
[15:46:03.721] <TB3> INFO: 22948250 events read in total (852101ms).
[15:46:36.396] <TB3> INFO: 23813650 events read in total (884776ms).
[15:47:08.941] <TB3> INFO: 24676350 events read in total (917321ms).
[15:47:41.334] <TB3> INFO: 25539550 events read in total (949714ms).
[15:48:07.329] <TB3> INFO: 26208000 events read in total (975709ms).
[15:48:07.356] <TB3> INFO: Test took 976553ms.
[15:48:07.617] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:48:09.046] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:48:10.422] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:48:11.789] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:48:13.198] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:48:14.716] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:48:16.241] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:48:17.778] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:48:19.298] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:48:20.815] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:48:22.391] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:48:23.919] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:48:25.422] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:48:27.023] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:48:28.546] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:48:30.188] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:48:31.789] <TB3> INFO: PixTestScurves::scurves() done
[15:48:31.789] <TB3> INFO: Vcal mean: 99.75 89.34 91.91 86.26 101.14 93.91 92.52 90.27 91.21 83.50 83.70 88.12 97.20 95.60 89.85 82.07
[15:48:31.789] <TB3> INFO: Vcal RMS: 4.99 5.22 4.48 5.17 5.22 5.11 5.09 4.94 4.82 3.98 5.30 6.30 5.23 4.85 5.09 4.16
[15:48:31.789] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1000 seconds
[15:48:31.789] <TB3> INFO: Decoding statistics:
[15:48:31.789] <TB3> INFO: General information:
[15:48:31.789] <TB3> INFO: 16bit words read: 0
[15:48:31.789] <TB3> INFO: valid events total: 0
[15:48:31.789] <TB3> INFO: empty events: 0
[15:48:31.789] <TB3> INFO: valid events with pixels: 0
[15:48:31.789] <TB3> INFO: valid pixel hits: 0
[15:48:31.789] <TB3> INFO: Event errors: 0
[15:48:31.789] <TB3> INFO: start marker: 0
[15:48:31.789] <TB3> INFO: stop marker: 0
[15:48:31.789] <TB3> INFO: overflow: 0
[15:48:31.789] <TB3> INFO: invalid 5bit words: 0
[15:48:31.789] <TB3> INFO: invalid XOR eye diagram: 0
[15:48:31.789] <TB3> INFO: frame (failed synchr.): 0
[15:48:31.789] <TB3> INFO: idle data (no TBM trl): 0
[15:48:31.789] <TB3> INFO: no data (only TBM hdr): 0
[15:48:31.789] <TB3> INFO: TBM errors: 0
[15:48:31.789] <TB3> INFO: flawed TBM headers: 0
[15:48:31.789] <TB3> INFO: flawed TBM trailers: 0
[15:48:31.789] <TB3> INFO: event ID mismatches: 0
[15:48:31.789] <TB3> INFO: ROC errors: 0
[15:48:31.789] <TB3> INFO: missing ROC header(s): 0
[15:48:31.789] <TB3> INFO: misplaced readback start: 0
[15:48:31.789] <TB3> INFO: Pixel decoding errors: 0
[15:48:31.789] <TB3> INFO: pixel data incomplete: 0
[15:48:31.789] <TB3> INFO: pixel address: 0
[15:48:31.789] <TB3> INFO: pulse height fill bit: 0
[15:48:31.789] <TB3> INFO: buffer corruption: 0
[15:48:31.866] <TB3> INFO: ######################################################################
[15:48:31.866] <TB3> INFO: PixTestTrim::doTest()
[15:48:31.866] <TB3> INFO: ######################################################################
[15:48:31.867] <TB3> INFO: ----------------------------------------------------------------------
[15:48:31.867] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[15:48:31.867] <TB3> INFO: ----------------------------------------------------------------------
[15:48:31.970] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[15:48:31.970] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:48:31.979] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:48:31.979] <TB3> INFO: run 1 of 1
[15:48:32.265] <TB3> INFO: Expecting 6281600 events.
[15:49:11.972] <TB3> INFO: 1077960 events read in total (39147ms).
[15:49:51.392] <TB3> INFO: 2149330 events read in total (78567ms).
[15:50:30.510] <TB3> INFO: 3213090 events read in total (117686ms).
[15:51:10.212] <TB3> INFO: 4286080 events read in total (157387ms).
[15:51:49.662] <TB3> INFO: 5361990 events read in total (196837ms).
[15:52:22.996] <TB3> INFO: 6281600 events read in total (230171ms).
[15:52:23.030] <TB3> INFO: Test took 231051ms.
[15:52:43.272] <TB3> INFO: ROC 0 VthrComp = 102
[15:52:43.273] <TB3> INFO: ROC 1 VthrComp = 89
[15:52:43.273] <TB3> INFO: ROC 2 VthrComp = 96
[15:52:43.274] <TB3> INFO: ROC 3 VthrComp = 89
[15:52:43.274] <TB3> INFO: ROC 4 VthrComp = 100
[15:52:43.274] <TB3> INFO: ROC 5 VthrComp = 90
[15:52:43.274] <TB3> INFO: ROC 6 VthrComp = 96
[15:52:43.274] <TB3> INFO: ROC 7 VthrComp = 91
[15:52:43.274] <TB3> INFO: ROC 8 VthrComp = 95
[15:52:43.274] <TB3> INFO: ROC 9 VthrComp = 93
[15:52:43.274] <TB3> INFO: ROC 10 VthrComp = 86
[15:52:43.275] <TB3> INFO: ROC 11 VthrComp = 92
[15:52:43.275] <TB3> INFO: ROC 12 VthrComp = 99
[15:52:43.275] <TB3> INFO: ROC 13 VthrComp = 102
[15:52:43.276] <TB3> INFO: ROC 14 VthrComp = 93
[15:52:43.276] <TB3> INFO: ROC 15 VthrComp = 87
[15:52:43.276] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[15:52:43.276] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:52:43.286] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:52:43.286] <TB3> INFO: run 1 of 1
[15:52:43.547] <TB3> INFO: Expecting 6281600 events.
[15:53:18.751] <TB3> INFO: 756470 events read in total (34648ms).
[15:53:54.113] <TB3> INFO: 1510980 events read in total (70010ms).
[15:54:29.370] <TB3> INFO: 2265640 events read in total (105267ms).
[15:55:04.499] <TB3> INFO: 3020010 events read in total (140396ms).
[15:55:39.175] <TB3> INFO: 3768190 events read in total (175072ms).
[15:56:14.714] <TB3> INFO: 4512490 events read in total (210611ms).
[15:56:49.550] <TB3> INFO: 5254090 events read in total (245447ms).
[15:57:24.363] <TB3> INFO: 5994390 events read in total (280260ms).
[15:57:38.224] <TB3> INFO: 6281600 events read in total (294121ms).
[15:57:38.265] <TB3> INFO: Test took 294979ms.
[15:58:02.996] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 58.0695 for pixel 13/10 mean/min/max = 45.3416/32.4959/58.1874
[15:58:02.996] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 60.458 for pixel 2/43 mean/min/max = 47.0457/33.6331/60.4584
[15:58:03.004] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 56.0949 for pixel 21/17 mean/min/max = 44.4875/32.8269/56.1481
[15:58:03.004] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 58.0218 for pixel 4/42 mean/min/max = 45.8514/33.5011/58.2017
[15:58:03.004] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 58.5741 for pixel 18/1 mean/min/max = 45.5961/32.3685/58.8237
[15:58:03.004] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 62.3598 for pixel 4/47 mean/min/max = 48.0942/33.7992/62.3892
[15:58:03.005] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 57.7433 for pixel 0/5 mean/min/max = 44.8589/31.9486/57.7692
[15:58:03.005] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 57.2507 for pixel 9/75 mean/min/max = 45.4175/33.5561/57.2789
[15:58:03.005] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 56.1474 for pixel 17/71 mean/min/max = 44.8592/32.901/56.8174
[15:58:03.005] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 53.645 for pixel 30/2 mean/min/max = 43.7349/33.2242/54.2456
[15:58:03.006] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 58.9666 for pixel 16/12 mean/min/max = 45.3858/31.6879/59.0838
[15:58:03.006] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 61.5024 for pixel 0/8 mean/min/max = 46.3966/31.2535/61.5397
[15:58:03.006] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 57.6742 for pixel 17/6 mean/min/max = 44.8066/31.823/57.7902
[15:58:03.006] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 56.778 for pixel 29/0 mean/min/max = 44.226/31.5592/56.8928
[15:58:03.006] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 57.9834 for pixel 18/2 mean/min/max = 45.2115/32.4239/57.9992
[15:58:03.007] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 55.1906 for pixel 18/17 mean/min/max = 43.9736/32.3453/55.6019
[15:58:03.007] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:58:03.095] <TB3> INFO: Expecting 514560 events.
[15:58:16.084] <TB3> INFO: 514560 events read in total (12433ms).
[15:58:16.090] <TB3> INFO: Expecting 514560 events.
[15:58:28.886] <TB3> INFO: 514560 events read in total (12379ms).
[15:58:28.893] <TB3> INFO: Expecting 514560 events.
[15:58:41.755] <TB3> INFO: 514560 events read in total (12442ms).
[15:58:41.765] <TB3> INFO: Expecting 514560 events.
[15:58:54.578] <TB3> INFO: 514560 events read in total (12403ms).
[15:58:54.590] <TB3> INFO: Expecting 514560 events.
[15:59:07.406] <TB3> INFO: 514560 events read in total (12407ms).
[15:59:07.418] <TB3> INFO: Expecting 514560 events.
[15:59:20.179] <TB3> INFO: 514560 events read in total (12352ms).
[15:59:20.193] <TB3> INFO: Expecting 514560 events.
[15:59:32.934] <TB3> INFO: 514560 events read in total (12331ms).
[15:59:32.949] <TB3> INFO: Expecting 514560 events.
[15:59:45.860] <TB3> INFO: 514560 events read in total (12498ms).
[15:59:45.878] <TB3> INFO: Expecting 514560 events.
[15:59:58.676] <TB3> INFO: 514560 events read in total (12392ms).
[15:59:58.695] <TB3> INFO: Expecting 514560 events.
[16:00:11.645] <TB3> INFO: 514560 events read in total (12541ms).
[16:00:11.669] <TB3> INFO: Expecting 514560 events.
[16:00:24.539] <TB3> INFO: 514560 events read in total (12469ms).
[16:00:24.563] <TB3> INFO: Expecting 514560 events.
[16:00:37.428] <TB3> INFO: 514560 events read in total (12464ms).
[16:00:37.454] <TB3> INFO: Expecting 514560 events.
[16:00:50.290] <TB3> INFO: 514560 events read in total (12435ms).
[16:00:50.318] <TB3> INFO: Expecting 514560 events.
[16:01:03.154] <TB3> INFO: 514560 events read in total (12434ms).
[16:01:03.186] <TB3> INFO: Expecting 514560 events.
[16:01:16.241] <TB3> INFO: 514560 events read in total (12654ms).
[16:01:16.285] <TB3> INFO: Expecting 514560 events.
[16:01:29.020] <TB3> INFO: 514560 events read in total (12333ms).
[16:01:29.060] <TB3> INFO: Test took 206053ms.
[16:01:30.169] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[16:01:30.178] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:01:30.178] <TB3> INFO: run 1 of 1
[16:01:30.437] <TB3> INFO: Expecting 6281600 events.
[16:02:05.973] <TB3> INFO: 743960 events read in total (34980ms).
[16:02:40.920] <TB3> INFO: 1486420 events read in total (69927ms).
[16:03:15.806] <TB3> INFO: 2228940 events read in total (104813ms).
[16:03:50.690] <TB3> INFO: 2971400 events read in total (139697ms).
[16:04:25.408] <TB3> INFO: 3707440 events read in total (174415ms).
[16:05:00.309] <TB3> INFO: 4439040 events read in total (209316ms).
[16:05:34.735] <TB3> INFO: 5168270 events read in total (243742ms).
[16:06:09.951] <TB3> INFO: 5896050 events read in total (278958ms).
[16:06:28.732] <TB3> INFO: 6281600 events read in total (297739ms).
[16:06:28.775] <TB3> INFO: Test took 298597ms.
[16:06:53.361] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.140446 .. 49.772611
[16:06:53.447] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 59 (-1/-1) hits flags = 528 (plus default)
[16:06:53.456] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:06:53.456] <TB3> INFO: run 1 of 1
[16:06:53.743] <TB3> INFO: Expecting 2496000 events.
[16:07:32.177] <TB3> INFO: 939730 events read in total (37878ms).
[16:08:09.548] <TB3> INFO: 1873630 events read in total (75249ms).
[16:08:35.157] <TB3> INFO: 2496000 events read in total (100858ms).
[16:08:35.175] <TB3> INFO: Test took 101720ms.
[16:08:49.129] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 17.944262 .. 44.581840
[16:08:49.204] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 7 .. 54 (-1/-1) hits flags = 528 (plus default)
[16:08:49.212] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:08:49.212] <TB3> INFO: run 1 of 1
[16:08:49.478] <TB3> INFO: Expecting 1996800 events.
[16:09:27.519] <TB3> INFO: 935620 events read in total (37485ms).
[16:10:04.946] <TB3> INFO: 1868430 events read in total (74912ms).
[16:10:10.586] <TB3> INFO: 1996800 events read in total (80552ms).
[16:10:10.606] <TB3> INFO: Test took 81395ms.
[16:10:24.222] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 22.451501 .. 41.072319
[16:10:24.322] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 12 .. 51 (-1/-1) hits flags = 528 (plus default)
[16:10:24.331] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:10:24.332] <TB3> INFO: run 1 of 1
[16:10:24.591] <TB3> INFO: Expecting 1664000 events.
[16:11:03.206] <TB3> INFO: 933050 events read in total (38059ms).
[16:11:32.851] <TB3> INFO: 1664000 events read in total (67704ms).
[16:11:32.863] <TB3> INFO: Test took 68531ms.
[16:11:46.607] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 23.920695 .. 40.496065
[16:11:46.685] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 13 .. 50 (-1/-1) hits flags = 528 (plus default)
[16:11:46.694] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:11:46.694] <TB3> INFO: run 1 of 1
[16:11:46.987] <TB3> INFO: Expecting 1580800 events.
[16:12:25.679] <TB3> INFO: 935460 events read in total (38136ms).
[16:12:53.209] <TB3> INFO: 1580800 events read in total (65666ms).
[16:12:53.224] <TB3> INFO: Test took 66530ms.
[16:13:06.956] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[16:13:06.956] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[16:13:06.965] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:13:06.965] <TB3> INFO: run 1 of 1
[16:13:07.226] <TB3> INFO: Expecting 1705600 events.
[16:13:45.442] <TB3> INFO: 879340 events read in total (37660ms).
[16:14:20.065] <TB3> INFO: 1705600 events read in total (72283ms).
[16:14:20.078] <TB3> INFO: Test took 73113ms.
[16:14:35.027] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:14:35.028] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:14:35.028] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:14:35.028] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:14:35.028] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:14:35.028] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:14:35.028] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:14:35.028] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:14:35.028] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:14:35.028] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:14:35.028] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:14:35.029] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:14:35.029] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:14:35.029] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:14:35.029] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:14:35.029] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:14:35.029] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//trimParameters35_C0.dat
[16:14:35.036] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//trimParameters35_C1.dat
[16:14:35.042] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//trimParameters35_C2.dat
[16:14:35.049] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//trimParameters35_C3.dat
[16:14:35.056] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//trimParameters35_C4.dat
[16:14:35.062] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//trimParameters35_C5.dat
[16:14:35.069] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//trimParameters35_C6.dat
[16:14:35.076] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//trimParameters35_C7.dat
[16:14:35.082] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//trimParameters35_C8.dat
[16:14:35.089] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//trimParameters35_C9.dat
[16:14:35.096] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//trimParameters35_C10.dat
[16:14:35.102] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//trimParameters35_C11.dat
[16:14:35.109] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//trimParameters35_C12.dat
[16:14:35.116] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//trimParameters35_C13.dat
[16:14:35.127] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//trimParameters35_C14.dat
[16:14:35.139] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//trimParameters35_C15.dat
[16:14:35.150] <TB3> INFO: PixTestTrim::trimTest() done
[16:14:35.150] <TB3> INFO: vtrim: 98 106 99 98 96 108 96 100 95 99 114 104 103 108 118 94
[16:14:35.150] <TB3> INFO: vthrcomp: 102 89 96 89 100 90 96 91 95 93 86 92 99 102 93 87
[16:14:35.150] <TB3> INFO: vcal mean: 34.92 34.99 34.92 34.96 35.03 34.96 34.95 34.93 34.92 34.99 34.98 34.99 34.98 34.94 34.95 34.90
[16:14:35.150] <TB3> INFO: vcal RMS: 0.83 0.83 0.83 0.83 0.92 0.91 0.82 0.84 0.83 0.78 0.88 0.85 0.88 0.81 0.88 0.81
[16:14:35.150] <TB3> INFO: bits mean: 9.41 8.79 9.72 9.26 9.50 9.18 9.26 9.51 9.42 10.15 9.90 8.83 9.87 9.80 10.12 9.97
[16:14:35.150] <TB3> INFO: bits RMS: 2.73 2.73 2.57 2.60 2.66 2.52 2.92 2.51 2.69 2.34 2.60 3.05 2.61 2.71 2.42 2.57
[16:14:35.157] <TB3> INFO: ----------------------------------------------------------------------
[16:14:35.157] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[16:14:35.157] <TB3> INFO: ----------------------------------------------------------------------
[16:14:35.160] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[16:14:35.172] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[16:14:35.172] <TB3> INFO: run 1 of 1
[16:14:35.461] <TB3> INFO: Expecting 4160000 events.
[16:15:17.461] <TB3> INFO: 937705 events read in total (41444ms).
[16:15:58.753] <TB3> INFO: 1867475 events read in total (82736ms).
[16:16:39.861] <TB3> INFO: 2786580 events read in total (123844ms).
[16:17:20.947] <TB3> INFO: 3699955 events read in total (164930ms).
[16:17:42.490] <TB3> INFO: 4160000 events read in total (186473ms).
[16:17:42.529] <TB3> INFO: Test took 187357ms.
[16:18:13.282] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 164 (-1/-1) hits flags = 528 (plus default)
[16:18:13.291] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[16:18:13.291] <TB3> INFO: run 1 of 1
[16:18:13.552] <TB3> INFO: Expecting 3432000 events.
[16:18:55.928] <TB3> INFO: 984590 events read in total (41819ms).
[16:19:37.684] <TB3> INFO: 1956025 events read in total (83575ms).
[16:20:19.663] <TB3> INFO: 2912250 events read in total (125555ms).
[16:20:42.352] <TB3> INFO: 3432000 events read in total (148243ms).
[16:20:42.382] <TB3> INFO: Test took 149091ms.
[16:21:07.775] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 152 (-1/-1) hits flags = 528 (plus default)
[16:21:07.783] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[16:21:07.783] <TB3> INFO: run 1 of 1
[16:21:08.042] <TB3> INFO: Expecting 3182400 events.
[16:21:52.386] <TB3> INFO: 1022500 events read in total (43787ms).
[16:22:34.744] <TB3> INFO: 2026590 events read in total (86145ms).
[16:23:16.679] <TB3> INFO: 3014915 events read in total (128080ms).
[16:23:24.098] <TB3> INFO: 3182400 events read in total (135499ms).
[16:23:24.125] <TB3> INFO: Test took 136343ms.
[16:23:48.208] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 154 (-1/-1) hits flags = 528 (plus default)
[16:23:48.217] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[16:23:48.217] <TB3> INFO: run 1 of 1
[16:23:48.481] <TB3> INFO: Expecting 3224000 events.
[16:24:31.684] <TB3> INFO: 1014870 events read in total (42647ms).
[16:25:14.364] <TB3> INFO: 2012400 events read in total (85328ms).
[16:25:56.232] <TB3> INFO: 2994795 events read in total (127195ms).
[16:26:06.242] <TB3> INFO: 3224000 events read in total (137205ms).
[16:26:06.282] <TB3> INFO: Test took 138065ms.
[16:26:31.733] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 153 (-1/-1) hits flags = 528 (plus default)
[16:26:31.742] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[16:26:31.742] <TB3> INFO: run 1 of 1
[16:26:32.005] <TB3> INFO: Expecting 3203200 events.
[16:27:15.990] <TB3> INFO: 1018340 events read in total (43429ms).
[16:27:58.738] <TB3> INFO: 2018510 events read in total (86178ms).
[16:28:40.710] <TB3> INFO: 3002920 events read in total (128150ms).
[16:28:49.565] <TB3> INFO: 3203200 events read in total (137004ms).
[16:28:49.597] <TB3> INFO: Test took 137855ms.
[16:29:13.817] <TB3> INFO: PixTestTrim::trimBitTest() done
[16:29:13.818] <TB3> INFO: PixTestTrim::doTest() done, duration: 2441 seconds
[16:29:13.822] <TB3> INFO: Decoding statistics:
[16:29:13.822] <TB3> INFO: General information:
[16:29:13.822] <TB3> INFO: 16bit words read: 0
[16:29:13.822] <TB3> INFO: valid events total: 0
[16:29:13.822] <TB3> INFO: empty events: 0
[16:29:13.822] <TB3> INFO: valid events with pixels: 0
[16:29:13.822] <TB3> INFO: valid pixel hits: 0
[16:29:13.822] <TB3> INFO: Event errors: 0
[16:29:13.822] <TB3> INFO: start marker: 0
[16:29:13.822] <TB3> INFO: stop marker: 0
[16:29:13.822] <TB3> INFO: overflow: 0
[16:29:13.822] <TB3> INFO: invalid 5bit words: 0
[16:29:13.822] <TB3> INFO: invalid XOR eye diagram: 0
[16:29:13.822] <TB3> INFO: frame (failed synchr.): 0
[16:29:13.822] <TB3> INFO: idle data (no TBM trl): 0
[16:29:13.822] <TB3> INFO: no data (only TBM hdr): 0
[16:29:13.822] <TB3> INFO: TBM errors: 0
[16:29:13.822] <TB3> INFO: flawed TBM headers: 0
[16:29:13.822] <TB3> INFO: flawed TBM trailers: 0
[16:29:13.822] <TB3> INFO: event ID mismatches: 0
[16:29:13.822] <TB3> INFO: ROC errors: 0
[16:29:13.822] <TB3> INFO: missing ROC header(s): 0
[16:29:13.822] <TB3> INFO: misplaced readback start: 0
[16:29:13.822] <TB3> INFO: Pixel decoding errors: 0
[16:29:13.822] <TB3> INFO: pixel data incomplete: 0
[16:29:13.822] <TB3> INFO: pixel address: 0
[16:29:13.822] <TB3> INFO: pulse height fill bit: 0
[16:29:13.822] <TB3> INFO: buffer corruption: 0
[16:29:14.595] <TB3> INFO: ######################################################################
[16:29:14.595] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[16:29:14.595] <TB3> INFO: ######################################################################
[16:29:14.857] <TB3> INFO: Expecting 41600 events.
[16:29:18.626] <TB3> INFO: 41600 events read in total (3213ms).
[16:29:18.626] <TB3> INFO: Test took 4030ms.
[16:29:19.121] <TB3> INFO: Expecting 41600 events.
[16:29:22.916] <TB3> INFO: 41600 events read in total (3239ms).
[16:29:22.917] <TB3> INFO: Test took 4087ms.
[16:29:23.224] <TB3> INFO: Expecting 41600 events.
[16:29:27.073] <TB3> INFO: 41600 events read in total (3293ms).
[16:29:27.073] <TB3> INFO: Test took 4130ms.
[16:29:27.328] <TB3> INFO: Expecting 2560 events.
[16:29:28.182] <TB3> INFO: 2560 events read in total (298ms).
[16:29:28.182] <TB3> INFO: Test took 1095ms.
[16:29:28.487] <TB3> INFO: Expecting 2560 events.
[16:29:29.340] <TB3> INFO: 2560 events read in total (297ms).
[16:29:29.341] <TB3> INFO: Test took 1159ms.
[16:29:29.645] <TB3> INFO: Expecting 2560 events.
[16:29:30.499] <TB3> INFO: 2560 events read in total (297ms).
[16:29:30.499] <TB3> INFO: Test took 1158ms.
[16:29:30.804] <TB3> INFO: Expecting 2560 events.
[16:29:31.657] <TB3> INFO: 2560 events read in total (297ms).
[16:29:31.657] <TB3> INFO: Test took 1157ms.
[16:29:31.962] <TB3> INFO: Expecting 2560 events.
[16:29:32.815] <TB3> INFO: 2560 events read in total (297ms).
[16:29:32.815] <TB3> INFO: Test took 1158ms.
[16:29:33.121] <TB3> INFO: Expecting 2560 events.
[16:29:33.973] <TB3> INFO: 2560 events read in total (296ms).
[16:29:33.973] <TB3> INFO: Test took 1158ms.
[16:29:34.278] <TB3> INFO: Expecting 2560 events.
[16:29:35.131] <TB3> INFO: 2560 events read in total (296ms).
[16:29:35.131] <TB3> INFO: Test took 1158ms.
[16:29:35.437] <TB3> INFO: Expecting 2560 events.
[16:29:36.292] <TB3> INFO: 2560 events read in total (299ms).
[16:29:36.292] <TB3> INFO: Test took 1160ms.
[16:29:36.597] <TB3> INFO: Expecting 2560 events.
[16:29:37.452] <TB3> INFO: 2560 events read in total (299ms).
[16:29:37.452] <TB3> INFO: Test took 1159ms.
[16:29:37.757] <TB3> INFO: Expecting 2560 events.
[16:29:38.612] <TB3> INFO: 2560 events read in total (299ms).
[16:29:38.612] <TB3> INFO: Test took 1160ms.
[16:29:38.918] <TB3> INFO: Expecting 2560 events.
[16:29:39.770] <TB3> INFO: 2560 events read in total (296ms).
[16:29:39.770] <TB3> INFO: Test took 1157ms.
[16:29:40.075] <TB3> INFO: Expecting 2560 events.
[16:29:40.929] <TB3> INFO: 2560 events read in total (298ms).
[16:29:40.929] <TB3> INFO: Test took 1158ms.
[16:29:41.234] <TB3> INFO: Expecting 2560 events.
[16:29:42.092] <TB3> INFO: 2560 events read in total (302ms).
[16:29:42.092] <TB3> INFO: Test took 1162ms.
[16:29:42.397] <TB3> INFO: Expecting 2560 events.
[16:29:43.255] <TB3> INFO: 2560 events read in total (302ms).
[16:29:43.256] <TB3> INFO: Test took 1163ms.
[16:29:43.561] <TB3> INFO: Expecting 2560 events.
[16:29:44.418] <TB3> INFO: 2560 events read in total (301ms).
[16:29:44.418] <TB3> INFO: Test took 1162ms.
[16:29:44.723] <TB3> INFO: Expecting 2560 events.
[16:29:45.578] <TB3> INFO: 2560 events read in total (298ms).
[16:29:45.578] <TB3> INFO: Test took 1159ms.
[16:29:45.582] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:29:45.884] <TB3> INFO: Expecting 655360 events.
[16:30:02.650] <TB3> INFO: 655360 events read in total (16210ms).
[16:30:02.659] <TB3> INFO: Expecting 655360 events.
[16:30:19.311] <TB3> INFO: 655360 events read in total (16251ms).
[16:30:19.324] <TB3> INFO: Expecting 655360 events.
[16:30:36.108] <TB3> INFO: 655360 events read in total (16382ms).
[16:30:36.123] <TB3> INFO: Expecting 655360 events.
[16:30:52.765] <TB3> INFO: 655360 events read in total (16241ms).
[16:30:52.783] <TB3> INFO: Expecting 655360 events.
[16:31:09.201] <TB3> INFO: 655360 events read in total (16016ms).
[16:31:09.236] <TB3> INFO: Expecting 655360 events.
[16:31:25.846] <TB3> INFO: 655360 events read in total (16208ms).
[16:31:25.872] <TB3> INFO: Expecting 655360 events.
[16:31:42.583] <TB3> INFO: 655360 events read in total (16310ms).
[16:31:42.616] <TB3> INFO: Expecting 655360 events.
[16:31:59.285] <TB3> INFO: 655360 events read in total (16267ms).
[16:31:59.318] <TB3> INFO: Expecting 655360 events.
[16:32:15.850] <TB3> INFO: 655360 events read in total (16130ms).
[16:32:15.902] <TB3> INFO: Expecting 655360 events.
[16:32:32.438] <TB3> INFO: 655360 events read in total (16134ms).
[16:32:32.478] <TB3> INFO: Expecting 655360 events.
[16:32:49.296] <TB3> INFO: 655360 events read in total (16416ms).
[16:32:49.341] <TB3> INFO: Expecting 655360 events.
[16:33:05.865] <TB3> INFO: 655360 events read in total (16122ms).
[16:33:05.918] <TB3> INFO: Expecting 655360 events.
[16:33:22.585] <TB3> INFO: 655360 events read in total (16266ms).
[16:33:22.635] <TB3> INFO: Expecting 655360 events.
[16:33:39.314] <TB3> INFO: 655360 events read in total (16277ms).
[16:33:39.373] <TB3> INFO: Expecting 655360 events.
[16:33:55.951] <TB3> INFO: 655360 events read in total (16177ms).
[16:33:56.011] <TB3> INFO: Expecting 655360 events.
[16:34:12.642] <TB3> INFO: 655360 events read in total (16229ms).
[16:34:12.706] <TB3> INFO: Test took 267124ms.
[16:34:12.785] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:34:13.016] <TB3> INFO: Expecting 655360 events.
[16:34:29.780] <TB3> INFO: 655360 events read in total (16208ms).
[16:34:29.789] <TB3> INFO: Expecting 655360 events.
[16:34:46.202] <TB3> INFO: 655360 events read in total (16011ms).
[16:34:46.214] <TB3> INFO: Expecting 655360 events.
[16:35:02.949] <TB3> INFO: 655360 events read in total (16333ms).
[16:35:02.966] <TB3> INFO: Expecting 655360 events.
[16:35:19.579] <TB3> INFO: 655360 events read in total (16211ms).
[16:35:19.598] <TB3> INFO: Expecting 655360 events.
[16:35:35.977] <TB3> INFO: 655360 events read in total (15978ms).
[16:35:36.000] <TB3> INFO: Expecting 655360 events.
[16:35:52.832] <TB3> INFO: 655360 events read in total (16430ms).
[16:35:52.859] <TB3> INFO: Expecting 655360 events.
[16:36:09.422] <TB3> INFO: 655360 events read in total (16161ms).
[16:36:09.452] <TB3> INFO: Expecting 655360 events.
[16:36:26.198] <TB3> INFO: 655360 events read in total (16345ms).
[16:36:26.233] <TB3> INFO: Expecting 655360 events.
[16:36:42.979] <TB3> INFO: 655360 events read in total (16344ms).
[16:36:43.016] <TB3> INFO: Expecting 655360 events.
[16:36:59.719] <TB3> INFO: 655360 events read in total (16302ms).
[16:36:59.759] <TB3> INFO: Expecting 655360 events.
[16:37:16.552] <TB3> INFO: 655360 events read in total (16391ms).
[16:37:16.602] <TB3> INFO: Expecting 655360 events.
[16:37:33.195] <TB3> INFO: 655360 events read in total (16191ms).
[16:37:33.244] <TB3> INFO: Expecting 655360 events.
[16:37:49.971] <TB3> INFO: 655360 events read in total (16325ms).
[16:37:50.026] <TB3> INFO: Expecting 655360 events.
[16:38:06.625] <TB3> INFO: 655360 events read in total (16197ms).
[16:38:06.687] <TB3> INFO: Expecting 655360 events.
[16:38:23.210] <TB3> INFO: 655360 events read in total (16122ms).
[16:38:23.272] <TB3> INFO: Expecting 655360 events.
[16:38:39.851] <TB3> INFO: 655360 events read in total (16177ms).
[16:38:39.915] <TB3> INFO: Test took 267130ms.
[16:38:40.143] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:40.154] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:40.161] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:40.168] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:40.175] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:40.182] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[16:38:40.189] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:40.197] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:40.204] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:40.211] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:40.218] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:40.225] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:40.232] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:40.239] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[16:38:40.246] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[16:38:40.253] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[16:38:40.261] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[16:38:40.272] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[16:38:40.283] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[16:38:40.295] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:40.307] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:40.319] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:40.328] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[16:38:40.335] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:40.373] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:38:40.373] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:38:40.373] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:38:40.373] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:38:40.373] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:38:40.373] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:38:40.374] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:38:40.374] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:38:40.374] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:38:40.374] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:38:40.374] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:38:40.374] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:38:40.374] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:38:40.374] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:38:40.374] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:38:40.375] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:38:40.652] <TB3> INFO: Expecting 41600 events.
[16:38:44.166] <TB3> INFO: 41600 events read in total (2958ms).
[16:38:44.167] <TB3> INFO: Test took 3790ms.
[16:38:44.614] <TB3> INFO: Expecting 41600 events.
[16:38:48.175] <TB3> INFO: 41600 events read in total (3005ms).
[16:38:48.176] <TB3> INFO: Test took 3833ms.
[16:38:48.640] <TB3> INFO: Expecting 41600 events.
[16:38:52.139] <TB3> INFO: 41600 events read in total (2943ms).
[16:38:52.140] <TB3> INFO: Test took 3789ms.
[16:38:52.327] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:52.415] <TB3> INFO: Expecting 2560 events.
[16:38:53.274] <TB3> INFO: 2560 events read in total (303ms).
[16:38:53.274] <TB3> INFO: Test took 947ms.
[16:38:53.276] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:53.579] <TB3> INFO: Expecting 2560 events.
[16:38:54.439] <TB3> INFO: 2560 events read in total (303ms).
[16:38:54.439] <TB3> INFO: Test took 1163ms.
[16:38:54.442] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:54.745] <TB3> INFO: Expecting 2560 events.
[16:38:55.600] <TB3> INFO: 2560 events read in total (299ms).
[16:38:55.600] <TB3> INFO: Test took 1158ms.
[16:38:55.602] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:55.906] <TB3> INFO: Expecting 2560 events.
[16:38:56.760] <TB3> INFO: 2560 events read in total (297ms).
[16:38:56.760] <TB3> INFO: Test took 1158ms.
[16:38:56.762] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:57.066] <TB3> INFO: Expecting 2560 events.
[16:38:57.919] <TB3> INFO: 2560 events read in total (297ms).
[16:38:57.919] <TB3> INFO: Test took 1157ms.
[16:38:57.921] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:58.225] <TB3> INFO: Expecting 2560 events.
[16:38:59.084] <TB3> INFO: 2560 events read in total (303ms).
[16:38:59.084] <TB3> INFO: Test took 1163ms.
[16:38:59.087] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:59.390] <TB3> INFO: Expecting 2560 events.
[16:39:00.247] <TB3> INFO: 2560 events read in total (301ms).
[16:39:00.247] <TB3> INFO: Test took 1160ms.
[16:39:00.250] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:00.553] <TB3> INFO: Expecting 2560 events.
[16:39:01.407] <TB3> INFO: 2560 events read in total (298ms).
[16:39:01.407] <TB3> INFO: Test took 1157ms.
[16:39:01.409] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:01.713] <TB3> INFO: Expecting 2560 events.
[16:39:02.568] <TB3> INFO: 2560 events read in total (299ms).
[16:39:02.568] <TB3> INFO: Test took 1159ms.
[16:39:02.570] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:02.874] <TB3> INFO: Expecting 2560 events.
[16:39:03.729] <TB3> INFO: 2560 events read in total (299ms).
[16:39:03.729] <TB3> INFO: Test took 1159ms.
[16:39:03.732] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:04.035] <TB3> INFO: Expecting 2560 events.
[16:39:04.888] <TB3> INFO: 2560 events read in total (296ms).
[16:39:04.888] <TB3> INFO: Test took 1156ms.
[16:39:04.891] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:05.194] <TB3> INFO: Expecting 2560 events.
[16:39:06.047] <TB3> INFO: 2560 events read in total (297ms).
[16:39:06.047] <TB3> INFO: Test took 1156ms.
[16:39:06.049] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:06.354] <TB3> INFO: Expecting 2560 events.
[16:39:07.208] <TB3> INFO: 2560 events read in total (298ms).
[16:39:07.208] <TB3> INFO: Test took 1159ms.
[16:39:07.210] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:07.514] <TB3> INFO: Expecting 2560 events.
[16:39:08.369] <TB3> INFO: 2560 events read in total (298ms).
[16:39:08.369] <TB3> INFO: Test took 1159ms.
[16:39:08.372] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:08.675] <TB3> INFO: Expecting 2560 events.
[16:39:09.529] <TB3> INFO: 2560 events read in total (298ms).
[16:39:09.529] <TB3> INFO: Test took 1157ms.
[16:39:09.531] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:09.835] <TB3> INFO: Expecting 2560 events.
[16:39:10.688] <TB3> INFO: 2560 events read in total (297ms).
[16:39:10.688] <TB3> INFO: Test took 1157ms.
[16:39:10.690] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:10.994] <TB3> INFO: Expecting 2560 events.
[16:39:11.848] <TB3> INFO: 2560 events read in total (298ms).
[16:39:11.849] <TB3> INFO: Test took 1159ms.
[16:39:11.851] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:12.155] <TB3> INFO: Expecting 2560 events.
[16:39:13.010] <TB3> INFO: 2560 events read in total (299ms).
[16:39:13.010] <TB3> INFO: Test took 1160ms.
[16:39:13.013] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:13.316] <TB3> INFO: Expecting 2560 events.
[16:39:14.173] <TB3> INFO: 2560 events read in total (301ms).
[16:39:14.173] <TB3> INFO: Test took 1160ms.
[16:39:14.176] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:14.479] <TB3> INFO: Expecting 2560 events.
[16:39:15.332] <TB3> INFO: 2560 events read in total (297ms).
[16:39:15.332] <TB3> INFO: Test took 1156ms.
[16:39:15.334] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:15.638] <TB3> INFO: Expecting 2560 events.
[16:39:16.496] <TB3> INFO: 2560 events read in total (301ms).
[16:39:16.496] <TB3> INFO: Test took 1162ms.
[16:39:16.498] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:16.802] <TB3> INFO: Expecting 2560 events.
[16:39:17.660] <TB3> INFO: 2560 events read in total (302ms).
[16:39:17.660] <TB3> INFO: Test took 1162ms.
[16:39:17.662] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:17.965] <TB3> INFO: Expecting 2560 events.
[16:39:18.824] <TB3> INFO: 2560 events read in total (303ms).
[16:39:18.824] <TB3> INFO: Test took 1162ms.
[16:39:18.826] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:19.129] <TB3> INFO: Expecting 2560 events.
[16:39:19.987] <TB3> INFO: 2560 events read in total (301ms).
[16:39:19.987] <TB3> INFO: Test took 1161ms.
[16:39:19.990] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:20.292] <TB3> INFO: Expecting 2560 events.
[16:39:21.146] <TB3> INFO: 2560 events read in total (297ms).
[16:39:21.146] <TB3> INFO: Test took 1157ms.
[16:39:21.148] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:21.452] <TB3> INFO: Expecting 2560 events.
[16:39:22.309] <TB3> INFO: 2560 events read in total (301ms).
[16:39:22.309] <TB3> INFO: Test took 1161ms.
[16:39:22.317] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:22.615] <TB3> INFO: Expecting 2560 events.
[16:39:23.468] <TB3> INFO: 2560 events read in total (297ms).
[16:39:23.469] <TB3> INFO: Test took 1152ms.
[16:39:23.470] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:23.775] <TB3> INFO: Expecting 2560 events.
[16:39:24.628] <TB3> INFO: 2560 events read in total (297ms).
[16:39:24.629] <TB3> INFO: Test took 1159ms.
[16:39:24.630] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:24.934] <TB3> INFO: Expecting 2560 events.
[16:39:25.787] <TB3> INFO: 2560 events read in total (297ms).
[16:39:25.787] <TB3> INFO: Test took 1157ms.
[16:39:25.789] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:26.092] <TB3> INFO: Expecting 2560 events.
[16:39:26.945] <TB3> INFO: 2560 events read in total (297ms).
[16:39:26.945] <TB3> INFO: Test took 1156ms.
[16:39:26.948] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:27.251] <TB3> INFO: Expecting 2560 events.
[16:39:28.107] <TB3> INFO: 2560 events read in total (300ms).
[16:39:28.107] <TB3> INFO: Test took 1160ms.
[16:39:28.110] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:28.414] <TB3> INFO: Expecting 2560 events.
[16:39:29.268] <TB3> INFO: 2560 events read in total (297ms).
[16:39:29.268] <TB3> INFO: Test took 1158ms.
[16:39:29.725] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 615 seconds
[16:39:29.725] <TB3> INFO: PH scale (per ROC): 80 77 80 74 80 69 76 80 72 90 74 81 74 78 79 81
[16:39:29.725] <TB3> INFO: PH offset (per ROC): 175 172 165 161 173 167 160 173 180 159 178 176 176 161 189 158
[16:39:29.729] <TB3> INFO: Decoding statistics:
[16:39:29.729] <TB3> INFO: General information:
[16:39:29.729] <TB3> INFO: 16bit words read: 91936
[16:39:29.729] <TB3> INFO: valid events total: 10240
[16:39:29.729] <TB3> INFO: empty events: 7736
[16:39:29.729] <TB3> INFO: valid events with pixels: 2504
[16:39:29.729] <TB3> INFO: valid pixel hits: 2504
[16:39:29.729] <TB3> INFO: Event errors: 0
[16:39:29.729] <TB3> INFO: start marker: 0
[16:39:29.729] <TB3> INFO: stop marker: 0
[16:39:29.729] <TB3> INFO: overflow: 0
[16:39:29.729] <TB3> INFO: invalid 5bit words: 0
[16:39:29.729] <TB3> INFO: invalid XOR eye diagram: 0
[16:39:29.729] <TB3> INFO: frame (failed synchr.): 0
[16:39:29.729] <TB3> INFO: idle data (no TBM trl): 0
[16:39:29.729] <TB3> INFO: no data (only TBM hdr): 0
[16:39:29.729] <TB3> INFO: TBM errors: 0
[16:39:29.729] <TB3> INFO: flawed TBM headers: 0
[16:39:29.729] <TB3> INFO: flawed TBM trailers: 0
[16:39:29.729] <TB3> INFO: event ID mismatches: 0
[16:39:29.729] <TB3> INFO: ROC errors: 0
[16:39:29.729] <TB3> INFO: missing ROC header(s): 0
[16:39:29.729] <TB3> INFO: misplaced readback start: 0
[16:39:29.729] <TB3> INFO: Pixel decoding errors: 0
[16:39:29.729] <TB3> INFO: pixel data incomplete: 0
[16:39:29.729] <TB3> INFO: pixel address: 0
[16:39:29.729] <TB3> INFO: pulse height fill bit: 0
[16:39:29.729] <TB3> INFO: buffer corruption: 0
[16:39:29.903] <TB3> INFO: ######################################################################
[16:39:29.904] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[16:39:29.904] <TB3> INFO: ######################################################################
[16:39:29.914] <TB3> INFO: scanning low vcal = 10
[16:39:30.177] <TB3> INFO: Expecting 41600 events.
[16:39:33.738] <TB3> INFO: 41600 events read in total (3005ms).
[16:39:33.738] <TB3> INFO: Test took 3824ms.
[16:39:33.740] <TB3> INFO: scanning low vcal = 20
[16:39:34.043] <TB3> INFO: Expecting 41600 events.
[16:39:37.606] <TB3> INFO: 41600 events read in total (3007ms).
[16:39:37.606] <TB3> INFO: Test took 3866ms.
[16:39:37.608] <TB3> INFO: scanning low vcal = 30
[16:39:37.910] <TB3> INFO: Expecting 41600 events.
[16:39:41.472] <TB3> INFO: 41600 events read in total (3006ms).
[16:39:41.473] <TB3> INFO: Test took 3865ms.
[16:39:41.474] <TB3> INFO: scanning low vcal = 40
[16:39:41.772] <TB3> INFO: Expecting 41600 events.
[16:39:45.979] <TB3> INFO: 41600 events read in total (3651ms).
[16:39:45.980] <TB3> INFO: Test took 4505ms.
[16:39:45.983] <TB3> INFO: scanning low vcal = 50
[16:39:46.244] <TB3> INFO: Expecting 41600 events.
[16:39:50.447] <TB3> INFO: 41600 events read in total (3647ms).
[16:39:50.448] <TB3> INFO: Test took 4465ms.
[16:39:50.450] <TB3> INFO: scanning low vcal = 60
[16:39:50.713] <TB3> INFO: Expecting 41600 events.
[16:39:54.927] <TB3> INFO: 41600 events read in total (3657ms).
[16:39:54.928] <TB3> INFO: Test took 4478ms.
[16:39:54.930] <TB3> INFO: scanning low vcal = 70
[16:39:55.197] <TB3> INFO: Expecting 41600 events.
[16:39:59.390] <TB3> INFO: 41600 events read in total (3637ms).
[16:39:59.391] <TB3> INFO: Test took 4461ms.
[16:39:59.393] <TB3> INFO: scanning low vcal = 80
[16:39:59.672] <TB3> INFO: Expecting 41600 events.
[16:40:03.854] <TB3> INFO: 41600 events read in total (3626ms).
[16:40:03.854] <TB3> INFO: Test took 4460ms.
[16:40:03.868] <TB3> INFO: scanning low vcal = 90
[16:40:04.132] <TB3> INFO: Expecting 41600 events.
[16:40:08.346] <TB3> INFO: 41600 events read in total (3658ms).
[16:40:08.346] <TB3> INFO: Test took 4478ms.
[16:40:08.349] <TB3> INFO: scanning low vcal = 100
[16:40:08.622] <TB3> INFO: Expecting 41600 events.
[16:40:12.818] <TB3> INFO: 41600 events read in total (3640ms).
[16:40:12.819] <TB3> INFO: Test took 4470ms.
[16:40:12.821] <TB3> INFO: scanning low vcal = 110
[16:40:13.094] <TB3> INFO: Expecting 41600 events.
[16:40:17.281] <TB3> INFO: 41600 events read in total (3630ms).
[16:40:17.281] <TB3> INFO: Test took 4459ms.
[16:40:17.284] <TB3> INFO: scanning low vcal = 120
[16:40:17.546] <TB3> INFO: Expecting 41600 events.
[16:40:21.758] <TB3> INFO: 41600 events read in total (3655ms).
[16:40:21.759] <TB3> INFO: Test took 4475ms.
[16:40:21.762] <TB3> INFO: scanning low vcal = 130
[16:40:22.032] <TB3> INFO: Expecting 41600 events.
[16:40:26.261] <TB3> INFO: 41600 events read in total (3672ms).
[16:40:26.261] <TB3> INFO: Test took 4499ms.
[16:40:26.264] <TB3> INFO: scanning low vcal = 140
[16:40:26.529] <TB3> INFO: Expecting 41600 events.
[16:40:30.735] <TB3> INFO: 41600 events read in total (3650ms).
[16:40:30.736] <TB3> INFO: Test took 4472ms.
[16:40:30.738] <TB3> INFO: scanning low vcal = 150
[16:40:31.009] <TB3> INFO: Expecting 41600 events.
[16:40:35.213] <TB3> INFO: 41600 events read in total (3647ms).
[16:40:35.213] <TB3> INFO: Test took 4475ms.
[16:40:35.217] <TB3> INFO: scanning low vcal = 160
[16:40:35.482] <TB3> INFO: Expecting 41600 events.
[16:40:39.675] <TB3> INFO: 41600 events read in total (3636ms).
[16:40:39.676] <TB3> INFO: Test took 4459ms.
[16:40:39.678] <TB3> INFO: scanning low vcal = 170
[16:40:39.937] <TB3> INFO: Expecting 41600 events.
[16:40:44.131] <TB3> INFO: 41600 events read in total (3637ms).
[16:40:44.132] <TB3> INFO: Test took 4453ms.
[16:40:44.135] <TB3> INFO: scanning low vcal = 180
[16:40:44.418] <TB3> INFO: Expecting 41600 events.
[16:40:48.608] <TB3> INFO: 41600 events read in total (3634ms).
[16:40:48.609] <TB3> INFO: Test took 4474ms.
[16:40:48.611] <TB3> INFO: scanning low vcal = 190
[16:40:48.878] <TB3> INFO: Expecting 41600 events.
[16:40:53.099] <TB3> INFO: 41600 events read in total (3665ms).
[16:40:53.099] <TB3> INFO: Test took 4488ms.
[16:40:53.102] <TB3> INFO: scanning low vcal = 200
[16:40:53.365] <TB3> INFO: Expecting 41600 events.
[16:40:57.582] <TB3> INFO: 41600 events read in total (3661ms).
[16:40:57.583] <TB3> INFO: Test took 4481ms.
[16:40:57.585] <TB3> INFO: scanning low vcal = 210
[16:40:57.845] <TB3> INFO: Expecting 41600 events.
[16:41:02.054] <TB3> INFO: 41600 events read in total (3653ms).
[16:41:02.054] <TB3> INFO: Test took 4469ms.
[16:41:02.058] <TB3> INFO: scanning low vcal = 220
[16:41:02.322] <TB3> INFO: Expecting 41600 events.
[16:41:06.503] <TB3> INFO: 41600 events read in total (3625ms).
[16:41:06.504] <TB3> INFO: Test took 4446ms.
[16:41:06.506] <TB3> INFO: scanning low vcal = 230
[16:41:06.767] <TB3> INFO: Expecting 41600 events.
[16:41:10.998] <TB3> INFO: 41600 events read in total (3674ms).
[16:41:10.999] <TB3> INFO: Test took 4493ms.
[16:41:11.002] <TB3> INFO: scanning low vcal = 240
[16:41:11.265] <TB3> INFO: Expecting 41600 events.
[16:41:15.497] <TB3> INFO: 41600 events read in total (3676ms).
[16:41:15.497] <TB3> INFO: Test took 4495ms.
[16:41:15.500] <TB3> INFO: scanning low vcal = 250
[16:41:15.775] <TB3> INFO: Expecting 41600 events.
[16:41:20.000] <TB3> INFO: 41600 events read in total (3669ms).
[16:41:20.000] <TB3> INFO: Test took 4500ms.
[16:41:20.004] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[16:41:20.287] <TB3> INFO: Expecting 41600 events.
[16:41:24.470] <TB3> INFO: 41600 events read in total (3627ms).
[16:41:24.470] <TB3> INFO: Test took 4465ms.
[16:41:24.473] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[16:41:24.732] <TB3> INFO: Expecting 41600 events.
[16:41:28.964] <TB3> INFO: 41600 events read in total (3676ms).
[16:41:28.964] <TB3> INFO: Test took 4491ms.
[16:41:28.970] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[16:41:29.228] <TB3> INFO: Expecting 41600 events.
[16:41:33.446] <TB3> INFO: 41600 events read in total (3662ms).
[16:41:33.447] <TB3> INFO: Test took 4477ms.
[16:41:33.449] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[16:41:33.722] <TB3> INFO: Expecting 41600 events.
[16:41:37.915] <TB3> INFO: 41600 events read in total (3637ms).
[16:41:37.916] <TB3> INFO: Test took 4467ms.
[16:41:37.918] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[16:41:38.190] <TB3> INFO: Expecting 41600 events.
[16:41:42.398] <TB3> INFO: 41600 events read in total (3652ms).
[16:41:42.398] <TB3> INFO: Test took 4480ms.
[16:41:42.853] <TB3> INFO: PixTestGainPedestal::measure() done
[16:42:17.885] <TB3> INFO: PixTestGainPedestal::fit() done
[16:42:17.885] <TB3> INFO: non-linearity mean: 0.955 0.958 0.959 0.954 0.966 0.960 0.961 0.960 0.950 0.954 0.949 0.965 0.955 0.956 0.955 0.948
[16:42:17.885] <TB3> INFO: non-linearity RMS: 0.006 0.006 0.006 0.007 0.005 0.006 0.005 0.006 0.007 0.007 0.006 0.004 0.007 0.006 0.005 0.006
[16:42:17.885] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[16:42:17.904] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[16:42:17.923] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[16:42:17.942] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[16:42:17.960] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[16:42:17.980] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[16:42:17.999] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[16:42:18.017] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[16:42:18.036] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[16:42:18.055] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[16:42:18.074] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[16:42:18.092] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[16:42:18.111] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[16:42:18.131] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[16:42:18.149] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[16:42:18.169] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[16:42:18.187] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 168 seconds
[16:42:18.187] <TB3> INFO: Decoding statistics:
[16:42:18.187] <TB3> INFO: General information:
[16:42:18.187] <TB3> INFO: 16bit words read: 2662400
[16:42:18.187] <TB3> INFO: valid events total: 166400
[16:42:18.187] <TB3> INFO: empty events: 0
[16:42:18.187] <TB3> INFO: valid events with pixels: 166400
[16:42:18.187] <TB3> INFO: valid pixel hits: 665591
[16:42:18.187] <TB3> INFO: Event errors: 0
[16:42:18.187] <TB3> INFO: start marker: 0
[16:42:18.187] <TB3> INFO: stop marker: 0
[16:42:18.187] <TB3> INFO: overflow: 0
[16:42:18.187] <TB3> INFO: invalid 5bit words: 0
[16:42:18.187] <TB3> INFO: invalid XOR eye diagram: 0
[16:42:18.187] <TB3> INFO: frame (failed synchr.): 0
[16:42:18.187] <TB3> INFO: idle data (no TBM trl): 0
[16:42:18.187] <TB3> INFO: no data (only TBM hdr): 0
[16:42:18.187] <TB3> INFO: TBM errors: 0
[16:42:18.187] <TB3> INFO: flawed TBM headers: 0
[16:42:18.187] <TB3> INFO: flawed TBM trailers: 0
[16:42:18.187] <TB3> INFO: event ID mismatches: 0
[16:42:18.187] <TB3> INFO: ROC errors: 0
[16:42:18.187] <TB3> INFO: missing ROC header(s): 0
[16:42:18.187] <TB3> INFO: misplaced readback start: 0
[16:42:18.187] <TB3> INFO: Pixel decoding errors: 0
[16:42:18.187] <TB3> INFO: pixel data incomplete: 0
[16:42:18.187] <TB3> INFO: pixel address: 0
[16:42:18.187] <TB3> INFO: pulse height fill bit: 0
[16:42:18.187] <TB3> INFO: buffer corruption: 0
[16:42:18.195] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C15.dat
[16:42:18.197] <TB3> INFO: ######################################################################
[16:42:18.197] <TB3> INFO: PixTestReadback::doTest()
[16:42:18.197] <TB3> INFO: ######################################################################
[16:42:18.197] <TB3> INFO: ----------------------------------------------------------------------
[16:42:18.197] <TB3> INFO: PixTestReadback::CalibrateVd()
[16:42:18.197] <TB3> INFO: ----------------------------------------------------------------------
[16:42:27.654] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C0.dat
[16:42:27.654] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C1.dat
[16:42:27.654] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C2.dat
[16:42:27.654] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C3.dat
[16:42:27.654] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C4.dat
[16:42:27.654] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C5.dat
[16:42:27.654] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C6.dat
[16:42:27.654] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C7.dat
[16:42:27.654] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C8.dat
[16:42:27.655] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C9.dat
[16:42:27.655] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C10.dat
[16:42:27.655] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C11.dat
[16:42:27.655] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C12.dat
[16:42:27.655] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C13.dat
[16:42:27.655] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C14.dat
[16:42:27.655] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C15.dat
[16:42:27.681] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:42:27.681] <TB3> INFO: ----------------------------------------------------------------------
[16:42:27.681] <TB3> INFO: PixTestReadback::CalibrateVa()
[16:42:27.681] <TB3> INFO: ----------------------------------------------------------------------
[16:42:37.147] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C0.dat
[16:42:37.147] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C1.dat
[16:42:37.147] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C2.dat
[16:42:37.147] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C3.dat
[16:42:37.147] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C4.dat
[16:42:37.148] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C5.dat
[16:42:37.148] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C6.dat
[16:42:37.148] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C7.dat
[16:42:37.148] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C8.dat
[16:42:37.148] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C9.dat
[16:42:37.148] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C10.dat
[16:42:37.148] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C11.dat
[16:42:37.148] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C12.dat
[16:42:37.148] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C13.dat
[16:42:37.148] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C14.dat
[16:42:37.148] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C15.dat
[16:42:37.172] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:42:37.172] <TB3> INFO: ----------------------------------------------------------------------
[16:42:37.172] <TB3> INFO: PixTestReadback::readbackVbg()
[16:42:37.172] <TB3> INFO: ----------------------------------------------------------------------
[16:42:44.442] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:42:44.442] <TB3> INFO: ----------------------------------------------------------------------
[16:42:44.443] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[16:42:44.443] <TB3> INFO: ----------------------------------------------------------------------
[16:42:44.443] <TB3> INFO: Vbg will be calibrated using Vd calibration
[16:42:44.443] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 152.5calibrated Vbg = 1.23396 :::*/*/*/*/
[16:42:44.443] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 153.6calibrated Vbg = 1.23155 :::*/*/*/*/
[16:42:44.443] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 151calibrated Vbg = 1.22695 :::*/*/*/*/
[16:42:44.443] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 163.3calibrated Vbg = 1.23905 :::*/*/*/*/
[16:42:44.443] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 150.7calibrated Vbg = 1.24767 :::*/*/*/*/
[16:42:44.443] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 149.1calibrated Vbg = 1.24657 :::*/*/*/*/
[16:42:44.443] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 148.6calibrated Vbg = 1.24473 :::*/*/*/*/
[16:42:44.443] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 157.1calibrated Vbg = 1.24617 :::*/*/*/*/
[16:42:44.443] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 159.7calibrated Vbg = 1.24428 :::*/*/*/*/
[16:42:44.443] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 154.9calibrated Vbg = 1.24334 :::*/*/*/*/
[16:42:44.443] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 162.1calibrated Vbg = 1.24606 :::*/*/*/*/
[16:42:44.443] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 147.5calibrated Vbg = 1.23803 :::*/*/*/*/
[16:42:44.443] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 152calibrated Vbg = 1.23222 :::*/*/*/*/
[16:42:44.443] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 145.7calibrated Vbg = 1.23677 :::*/*/*/*/
[16:42:44.443] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 154.9calibrated Vbg = 1.23195 :::*/*/*/*/
[16:42:44.443] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 157.6calibrated Vbg = 1.2322 :::*/*/*/*/
[16:42:44.445] <TB3> INFO: ----------------------------------------------------------------------
[16:42:44.445] <TB3> INFO: PixTestReadback::CalibrateIa()
[16:42:44.445] <TB3> INFO: ----------------------------------------------------------------------
[16:45:19.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C0.dat
[16:45:19.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C1.dat
[16:45:19.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C2.dat
[16:45:19.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C3.dat
[16:45:19.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C4.dat
[16:45:19.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C5.dat
[16:45:19.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C6.dat
[16:45:19.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C7.dat
[16:45:19.588] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C8.dat
[16:45:19.589] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C9.dat
[16:45:19.589] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C10.dat
[16:45:19.589] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C11.dat
[16:45:19.589] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C12.dat
[16:45:19.589] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C13.dat
[16:45:19.589] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C14.dat
[16:45:19.589] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2309_FullQualification_2016-09-06_10h51m_1473151860//004_FulltestPxar_p17//readbackCal_C15.dat
[16:45:19.617] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:45:19.618] <TB3> INFO: PixTestReadback::doTest() done
[16:45:19.618] <TB3> INFO: Decoding statistics:
[16:45:19.618] <TB3> INFO: General information:
[16:45:19.618] <TB3> INFO: 16bit words read: 1024
[16:45:19.618] <TB3> INFO: valid events total: 128
[16:45:19.619] <TB3> INFO: empty events: 128
[16:45:19.619] <TB3> INFO: valid events with pixels: 0
[16:45:19.619] <TB3> INFO: valid pixel hits: 0
[16:45:19.619] <TB3> INFO: Event errors: 0
[16:45:19.619] <TB3> INFO: start marker: 0
[16:45:19.619] <TB3> INFO: stop marker: 0
[16:45:19.619] <TB3> INFO: overflow: 0
[16:45:19.619] <TB3> INFO: invalid 5bit words: 0
[16:45:19.619] <TB3> INFO: invalid XOR eye diagram: 0
[16:45:19.619] <TB3> INFO: frame (failed synchr.): 0
[16:45:19.619] <TB3> INFO: idle data (no TBM trl): 0
[16:45:19.619] <TB3> INFO: no data (only TBM hdr): 0
[16:45:19.619] <TB3> INFO: TBM errors: 0
[16:45:19.619] <TB3> INFO: flawed TBM headers: 0
[16:45:19.619] <TB3> INFO: flawed TBM trailers: 0
[16:45:19.619] <TB3> INFO: event ID mismatches: 0
[16:45:19.619] <TB3> INFO: ROC errors: 0
[16:45:19.619] <TB3> INFO: missing ROC header(s): 0
[16:45:19.619] <TB3> INFO: misplaced readback start: 0
[16:45:19.619] <TB3> INFO: Pixel decoding errors: 0
[16:45:19.619] <TB3> INFO: pixel data incomplete: 0
[16:45:19.619] <TB3> INFO: pixel address: 0
[16:45:19.619] <TB3> INFO: pulse height fill bit: 0
[16:45:19.619] <TB3> INFO: buffer corruption: 0
[16:45:19.640] <TB3> INFO: Decoding statistics:
[16:45:19.640] <TB3> INFO: General information:
[16:45:19.640] <TB3> INFO: 16bit words read: 2755360
[16:45:19.640] <TB3> INFO: valid events total: 176768
[16:45:19.640] <TB3> INFO: empty events: 7864
[16:45:19.640] <TB3> INFO: valid events with pixels: 168904
[16:45:19.640] <TB3> INFO: valid pixel hits: 668095
[16:45:19.640] <TB3> INFO: Event errors: 0
[16:45:19.640] <TB3> INFO: start marker: 0
[16:45:19.640] <TB3> INFO: stop marker: 0
[16:45:19.640] <TB3> INFO: overflow: 0
[16:45:19.640] <TB3> INFO: invalid 5bit words: 0
[16:45:19.640] <TB3> INFO: invalid XOR eye diagram: 0
[16:45:19.640] <TB3> INFO: frame (failed synchr.): 0
[16:45:19.640] <TB3> INFO: idle data (no TBM trl): 0
[16:45:19.640] <TB3> INFO: no data (only TBM hdr): 0
[16:45:19.640] <TB3> INFO: TBM errors: 0
[16:45:19.640] <TB3> INFO: flawed TBM headers: 0
[16:45:19.640] <TB3> INFO: flawed TBM trailers: 0
[16:45:19.640] <TB3> INFO: event ID mismatches: 0
[16:45:19.640] <TB3> INFO: ROC errors: 0
[16:45:19.640] <TB3> INFO: missing ROC header(s): 0
[16:45:19.640] <TB3> INFO: misplaced readback start: 0
[16:45:19.640] <TB3> INFO: Pixel decoding errors: 0
[16:45:19.640] <TB3> INFO: pixel data incomplete: 0
[16:45:19.640] <TB3> INFO: pixel address: 0
[16:45:19.640] <TB3> INFO: pulse height fill bit: 0
[16:45:19.640] <TB3> INFO: buffer corruption: 0
[16:45:19.640] <TB3> INFO: enter test to run
[16:45:19.640] <TB3> INFO: test: exit no parameter change
[16:45:19.819] <TB3> QUIET: Connection to board 170 closed.
[16:45:19.899] <TB3> INFO: pXar: this is the end, my friend